[Letux-kernel] New LetuxOS Kernels - strcmp(NULL)

H. Nikolaus Schaller hns at goldelico.com
Sat Jun 23 12:13:11 CEST 2018


Hi all,


> Am 22.06.2018 um 13:34 schrieb H. Nikolaus Schaller <hns at goldelico.com>:
> 
> 
>> Am 22.06.2018 um 09:16 schrieb Tony Lindgren <tony at atomide.com>:
>> 
>> * H. Nikolaus Schaller <hns at goldelico.com> [180621 17:48]:
>>>> 	selector = pinctrl_generic_group_name_to_selector(pctldev, name);
>>>> 	if (selector >= 0) {
>>>> 		printk("%s: pctldev=%px name=%s found selector=%d\n", __func__, pctldev, name, pctldev->num_groups);
>>> 
>>> well, my printk isn't correct!
>>> I'll check if the result is just an effect of the wrong printk.
>> 
>> Oh OK :)
> 
> Yes, they were wrong. Now here a log with correct printk. Looks sane on first impression:
> 
> root at letux:~# dmesg|grep "dt_free\|generic_add\|deferred"
> [    0.759490] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_hsusb2_pins new selector=0
> [    0.761657] pinctrl_generic_add_group: pctldev=ee35ff80 name=pinmux_hsusb2_2_pins new selector=0
> [    0.762420] pinctrl_generic_add_group: pctldev=ee35fe00 name=pinmux_mcbsp1_devconf0_pins new selector=0
> [    0.763092] pinctrl_generic_add_group: pctldev=ee35fd00 name=pinmux_tv_acbias_devconf1_pins new selector=0
> [    0.780242] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_uart1_pins new selector=1
> [    0.781921] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_uart2_pins new selector=2
> [    0.783050] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_uart3_pins new selector=3
> [    1.812499] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_twl4030_pins new selector=4
> [    1.822235] pinctrl_generic_add_group: pctldev=ee445000 name=pinmux_twl4030_vpins new selector=0
> [    1.989715] pinctrl_generic_add_group: pctldev=ee35ff80 name=spi_gpio_pinmux new selector=1
> [    2.184509] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_mmc1_pins new selector=5
> [    2.221252] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_wlan_irq_pin new selector=6
> [    2.241210] pcs_dt_free_map: pctldev=ee445180
> [    2.394989] platform 6e000000.gpmc: Retrying from deferred list
> [    2.401672] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_gpmc_pins new selector=7
> [    2.789489] platform wlan_en_regulator: Retrying from deferred list
> [    2.796203] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_wlan_pins new selector=8
> [    2.806518] platform bt_en_regulator: Retrying from deferred list
> [    2.813354] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_bt_pins new selector=9
> [    2.822723] platform 480b4000.mmc: Retrying from deferred list
> [    2.829711] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_wlan_irq_pin found selector=6
> [    5.385803] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_dss_dpi_pins new selector=10
> [    6.019317] platform 48070000.i2c:twl at 48:bci: Retrying from deferred list
> [    6.066497] platform 48070000.i2c:twl at 48:bci: Retrying from deferred list
> [    6.080902] platform 48070000.i2c:twl at 48:bci: Retrying from deferred list
> [    6.143432] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_penirq_pins new selector=11
> [    6.218383] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_mcbsp1_pins new selector=12
> [    6.282104] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_mcbsp2_pins new selector=13
> [    6.424468] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins new selector=14
> [    6.473358] pcs_dt_free_map: pctldev=ee445180
> [    6.713745] i2c 1-0030: Retrying from deferred list
> [    6.724761] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    6.742156] pcs_dt_free_map: pctldev=ee445180
> [    6.748229] pinctrl_generic_add_group: pctldev=ee445180 name=hdq_pins new selector=15
> [    6.756622] i2c 1-0030: Retrying from deferred list
> [    6.762542] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    6.782073] pcs_dt_free_map: pctldev=ee445180
> [    6.795288] i2c 1-0030: Retrying from deferred list
> [    6.804748] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    6.822540] pcs_dt_free_map: pctldev=ee445180
> [    6.890319] i2c 1-0030: Retrying from deferred list
> [    6.895629] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    6.935729] pcs_dt_free_map: pctldev=ee445180
> [    6.951324] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_mcbsp3_pins new selector=16
> [    6.960571] i2c 1-0030: Retrying from deferred list
> [    7.028198] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    7.037719] pcs_dt_free_map: pctldev=ee445180
> [    7.057189] i2c 1-0030: Retrying from deferred list
> [    7.062652] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_mcbsp4_pins new selector=17
> [    7.136230] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    7.153869] pcs_dt_free_map: pctldev=ee445180
> [    7.164825] i2c 1-0030: Retrying from deferred list
> [    7.174407] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    7.196655] pcs_dt_free_map: pctldev=ee445180
> [    7.216339] pinctrl_generic_add_group: pctldev=ee445180 name=backlight_pins_pinmux new selector=18
> [    7.405364] pcs_dt_free_map: pctldev=ee445180
> [    7.460327] i2c 1-0030: Retrying from deferred list
> [    7.470367] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    7.491027] pcs_dt_free_map: pctldev=ee445180
> [    7.501129] platform backlight: Retrying from deferred list
> [    7.513153] pinctrl_generic_add_group: pctldev=ee445180 name=backlight_pins_pinmux found selector=18
> [    7.563171] i2c 1-0030: Retrying from deferred list
> [    7.574401] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    7.606201] pcs_dt_free_map: pctldev=ee445180
> [    7.729339] i2c 1-0030: Retrying from deferred list
> [    7.734680] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    7.758361] pcs_dt_free_map: pctldev=ee445180
> [    7.763092] pinctrl_generic_add_group: pctldev=ee445180 name=modem_pins new selector=19
> [    8.287200] i2c 1-0030: Retrying from deferred list
> [    8.301971] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    8.314514] pcs_dt_free_map: pctldev=ee445180
> [    8.392425] i2c 1-0030: Retrying from deferred list
> [    8.397766] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    8.421295] pcs_dt_free_map: pctldev=ee445180
> [    8.425994] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_pps_pins new selector=20
> [    8.518798] i2c 1-0030: Retrying from deferred list
> [    8.524169] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    8.548370] pcs_dt_free_map: pctldev=ee445180
> [    9.366851] platform sound_bluetooth: Retrying from deferred list
> [    9.381958] platform sound_fmradio: Retrying from deferred list
> [    9.396972] i2c 1-0030: Retrying from deferred list
> [    9.407379] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    9.427551] pcs_dt_free_map: pctldev=ee445180
> [    9.583404] platform sound_bluetooth: Retrying from deferred list
> [    9.616180] platform sound_fmradio: Retrying from deferred list
> [    9.637329] i2c 1-0030: Retrying from deferred list
> [    9.644073] pinctrl_generic_add_group: pctldev=ee445180 name=pinmux_camera_pins found selector=14
> [    9.654144] pcs_dt_free_map: pctldev=ee445180
> root at letux:~# 
> 
> And I didn't see the strcmp(NULL) any more. I already was suspecting that it is a Heisenbug: the
> more you study it, the less likely it appears.
> 
> Maybe the printk have some (positive) side-effect (internal locking)?
> 
> To test that I have reverted all patches with printk which produce the log from above.
> Only after removing the printk in pcs_dt_free_map(), the strcmp(NULL) came back.
> 
> Next I'll try to find out if I can add the other patches to see something...

I think I now have a scenario with additional information. I removed the printk
in pcs_dt_free_map() and I get the strcmp(NULL) again.

So far there are several workarounds to get rid of the problem:
a) add printk to pcs_dt_free_map()
b) add printk to pinctrl_generic_add_group()
c) blacklist some drivers which are loaded around the critical point

So I had to keep them all, but still get useful debugging information. Therefore
I added a test for gname == NULL to print a stack dump and the whole list
of selectors in that case:

static int pinctrl_generic_group_name_to_selector(struct pinctrl_dev *pctldev,
						  const char *function)
{
	const struct pinctrl_ops *ops = pctldev->desc->pctlops;
	unsigned ngroups = ops->get_groups_count(pctldev);
	unsigned selector = 0;

	/* See if this pctldev has this group */
	while (selector < ngroups) {
		const char *gname = ops->get_group_name(pctldev, selector);

if (!gname || !function) {
	printk("%s: pctldev = %px function = %s\n", __func__, pctldev, function);
	printk("  strcmp(%s, %s)\n", function, gname);
	printk("  get_group_name() = %pF\n", ops->get_group_name);
	printk("  ngroups = %u\n", ngroups);
	printk("  selector = %u\n", selector);
	dump_stack();
	selector = 0;
	while (selector < ngroups) {
		const char *gname = ops->get_group_name(pctldev, selector);
		printk("  gname[%u] = %s\n", selector, gname);
		selector++;
	}
	return -EINVAL;
}

		if (!strcmp(function, gname))
			return selector;

		selector++;
	}

	return -EINVAL;
}

and

int pinctrl_get_group_selector(struct pinctrl_dev *pctldev,
			       const char *pin_group)
{
	const struct pinctrl_ops *pctlops = pctldev->desc->pctlops;
	unsigned ngroups = pctlops->get_groups_count(pctldev);
	unsigned group_selector = 0;

	while (group_selector < ngroups) {
		const char *gname = pctlops->get_group_name(pctldev,
							    group_selector);
if (!gname || !pin_group) {
	printk("%s: strcmp: %s %s\n", __func__, gname, pin_group);
	printk("  group_selector = %u\n", group_selector);
	printk("  get_group_name = %pF\n", pctlops->get_group_name);
	printk("  pctldev = %pF\n", pctldev);
}
		if (!strcmp(gname, pin_group)) {
			dev_dbg(pctldev->dev,
				"found group selector %u for %s\n",
				group_selector,
				pin_group);
			return group_selector;
		}

		group_selector++;
	}

	dev_err(pctldev->dev, "does not have pin group %s\n",
		pin_group);

	return -EINVAL;
}

This time it is quite reproducible and here is the log:

## Booting kernel from Legacy Image at 82000000 ...
   Image Name:   Linux-4.17.0-letux+
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4760144 Bytes = 4.5 MiB
   Load Address: 80008000
   Entry Point:  80008000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 81c00000
   Booting using the fdt blob at 0x81c00000
   Loading Kernel Image ... OK
   Using Device Tree in place at 81c00000, end 81c14a70

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.17.0-letux+ (hns at iMac.fritz.box) (gcc version 4.9.2 (GCC)) #2494 SMP PREEMPT Sat Jun 23 09:48:15 CEST 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: Goldelico GTA04A5/Letux 2804
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] Memory policy: Data cache writeback
[    0.000000] cma: Reserved 16 MiB at 0xbe800000
[    0.000000] On node 0 totalpages: 261632
[    0.000000]   Normal zone: 1536 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 196608 pages, LIFO batch:31
[    0.000000]   HighMem zone: 65024 pages, LIFO batch:15
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] OMAP3630/DM3730 ES1.2 (l2cache iva sgx neon isp 192mhz_clk)
[    0.000000] percpu: Embedded 17 pages/cpu @(ptrval) s40000 r8192 d21440 u69632
[    0.000000] pcpu-alloc: s40000 r8192 d21440 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 260096
[    0.000000] Kernel command line: console=ttyO2,115200n8 mtdoops.mtddev=omap2.nand ubi.mtd=4 root=/dev/mmcblk0p1 rw rootfstype=ext4,ext3 rootwait console=ttyO2,115200n8 vram=12M omapfb.vram=0:8M,1:4M omapfb.rotate_type=0 omapdss.def_disp=lcd rootwait twl4030_charger.allow_usb=1 log_buf_len=8M ignore_loglevel earlyprintk
[    0.000000] log_buf_len: 8388608 bytes
[    0.000000] early log buf free: 63960(97%)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 1001476K/1046528K available (6547K kernel code, 650K rwdata, 1820K rodata, 1024K init, 220K bss, 28668K reserved, 16384K cma-reserved, 243712K 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)   (7540 kB)
[    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (1024 kB)
[    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 651 kB)
[    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 221 kB)
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=1.
[    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] IRQ: Found an INTC at 0x(ptrval) (revision 4.0) with 96 interrupts
[    0.000000] Clocking rate (Crystal/Core/MPU): 26.0/400/600 MHz
[    0.000000] OMAP clockevent source: timer1 at 32768 Hz
[    0.000000] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65535999984741ns
[    0.000030] OMAP clocksource: 32k_counter at 32768 Hz
[    0.002136] Console: colour dummy device 80x30
[    0.002197] Calibrating delay loop... 597.60 BogoMIPS (lpj=2988032)
[    0.115203] pid_max: default: 32768 minimum: 301
[    0.115386] Security Framework initialized
[    0.115509] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.115539] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.116455] CPU: Testing write buffer coherency: ok
[    0.116943] CPU0: thread -1, cpu 0, socket -1, mpidr 0
[    0.165161] Setting up static identity map for 0x80100000 - 0x80100060
[    0.175079] Hierarchical SRCU implementation.
[    0.195098] smp: Bringing up secondary CPUs ...
[    0.195129] smp: Brought up 1 node, 1 CPU
[    0.195129] SMP: Total of 1 processors activated (597.60 BogoMIPS).
[    0.195159] CPU: All CPU(s) started in SVC mode.
[    0.197204] devtmpfs: initialized
[    0.225524] random: get_random_u32 called from bucket_table_alloc+0x15c/0x1ac with crng_init=0
[    0.235504] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.236236] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.236267] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.237731] pinctrl core: initialized pinctrl subsystem
[    0.239074] NET: Registered protocol family 16
[    0.242218] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.273742] omap_hwmod: mcbsp2_sidetone using broken dt data from mcbsp
[    0.274566] omap_hwmod: mcbsp3_sidetone using broken dt data from mcbsp
[    0.357635] audit: initializing netlink subsys (disabled)
[    0.363189] audit: type=2000 audit(0.360:1): state=initialized audit_enabled=0 res=1
[    0.364807] cpuidle: using governor menu
[    0.366088] Reprogramming SDRC clock to 400000000 Hz
[    0.377929] OMAP GPIO hardware version 2.5
[    0.386535] GPIO line 143 (irda_en) hogged as output/high
[    0.414459] omap-gpmc 6e000000.gpmc: could not find pctldev for node /ocp at 68000000/l4 at 48000000/scm at 2000/pinmux at 30/pinmux_gpmc_pins, deferring probe
[    0.434875] No ATAGs?
[    0.434906] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.436126] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.436157] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.436523] OMAP DMA hardware revision 5.0
[    0.543273] omap-dma-engine 48056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.548492] reg-fixed-voltage wlan_en_regulator: could not find pctldev for node /ocp at 68000000/l4 at 48000000/scm at 2000/pinmux at 30/pinmux_wlan_pins, deferring probe
[    0.548553] reg-fixed-voltage bt_en_regulator: could not find pctldev for node /ocp at 68000000/l4 at 48000000/scm at 2000/pinmux at 30/pinmux_bt_pins, deferring probe
[    0.558776] omap-iommu 480bd400.mmu: 480bd400.mmu registered
[    0.559783] iommu: Adding device 480bc000.isp to group 0
[    0.562622] vgaarb: loaded
[    0.563812] SCSI subsystem initialized
[    0.565612] libata version 3.00 loaded.
[    0.566589] usbcore: registered new interface driver usbfs
[    0.566802] usbcore: registered new interface driver hub
[    0.567016] usbcore: registered new device driver usb
[    0.567718] usb_phy_generic hsusb2_phy: hsusb2_phy supply vcc not found, using dummy regulator
[    0.573394] omap_i2c 48070000.i2c: bus 0 rev4.4 at 2600 kHz
[    0.578063] omap_i2c 48072000.i2c: bus 1 rev4.4 at 400 kHz
[    0.580566] omap_i2c 48060000.i2c: bus 2 rev4.4 at 100 kHz
[    0.582733] Advanced Linux Sound Architecture Driver Initialized.
[    0.587829] clocksource: Switched to clocksource 32k_counter
[    0.684509] VFS: Disk quotas dquot_6.6.0
[    0.684631] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.719573] NET: Registered protocol family 2
[    0.720947] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[    0.721008] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.721130] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.721282] TCP: Hash tables configured (established 8192 bind 8192)
[    0.721435] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.721496] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.722381] NET: Registered protocol family 1
[    0.722442] PCI: CLS 0 bytes, default 64
[    0.726928] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[    0.732513] Initialise system trusted keyrings
[    0.734283] workingset: timestamp_bits=30 max_order=18 bucket_order=0
[    0.734924] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.739746] Key type asymmetric registered
[    0.739776] Asymmetric key parser 'x509' registered
[    0.739898] bounce: pool size: 64 pages
[    0.740020] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.740051] io scheduler noop registered
[    0.740051] io scheduler deadline registered
[    0.740142] io scheduler cfq registered (default)
[    0.740173] io scheduler mq-deadline registered
[    0.740173] io scheduler kyber registered
[    0.749450] pinctrl-single 48002030.pinmux: 284 pins, size 568
[    0.750427] pinctrl-single 48002a00.pinmux: 46 pins, size 92
[    0.751434] pinctrl-single 480025a0.pinmux: 46 pins, size 92
[    0.752319] pinctrl-single 48002274.pinmux_mcbsp1: initialized with no interrupts
[    0.752319] pinctrl-single 48002274.pinmux_mcbsp1: 10 pins, size 4
[    0.753082] pinctrl-single 480022d8.pinmux_tv_out: initialized with no interrupts
[    0.753082] pinctrl-single 480022d8.pinmux_tv_out: 4 pins, size 4
[    0.770263] omap_uart 4806a000.serial: no wakeirq for uart0
[    0.770782] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 88, base_baud = 3000000) is a OMAP UART0
[    0.771514] serial serial0: tty port ttyO0 registered
[    0.772064] omap_uart 4806c000.serial: no wakeirq for uart1
[    0.772277] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 89, base_baud = 3000000) is a OMAP UART1
[    0.772796] serial serial1: tty port ttyO1 registered
[    0.773315] omap_uart 49020000.serial: no wakeirq for uart2
[    0.773742] 49020000.serial: ttyO2 at MMIO 0x49020000 (irq = 90, base_baud = 3000000) is a OMAP UART2
[    1.669250] console [ttyO2] enabled
[    1.674468] omap_uart 49042000.serial: no wakeirq for uart3
[    1.681060] 49042000.serial: ttyO3 at MMIO 0x49042000 (irq = 96, base_baud = 3000000) is a OMAP UART3
[    1.715942] brd: module loaded
[    1.732696] loop: module loaded
[    1.771636] twl 0-0048: PIH (irq 23) chaining IRQs 144..152
[    1.777618] twl 0-0048: power (irq 149) chaining IRQs 152..159
[    1.789855] random: fast init done
[    1.796966] VAUX3: Bringing 2800000uV into 2500000-2500000uV
[    1.822326] VMMC2: Bringing 2600000uV into 1800000-1800000uV
[    1.829650] VMMC2: failed to apply 1800000-1800000uV constraint(-22)
[    1.836334] twl4030_reg 48070000.i2c:twl at 48:regulator-vmmc2: can't register VMMC2, -22
[    1.844879] twl4030_reg: probe of 48070000.i2c:twl at 48:regulator-vmmc2 failed with error -22
[    1.863830] VSIM: Bringing 1800000uV into 2800000-2800000uV
[    1.909942] libphy: Fixed MDIO Bus: probed
[    1.924407] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.932098] ehci-pci: EHCI PCI platform driver
[    1.937194] usbcore: registered new interface driver usb-storage
[    1.944854] mousedev: PS/2 mouse device common for all mice
[    1.953552] twl_rtc 48070000.i2c:twl at 48:rtc: Power up reset detected.
[    1.961181] twl_rtc 48070000.i2c:twl at 48:rtc: Enabling TWL-RTC
[    1.969757] twl_rtc 48070000.i2c:twl at 48:rtc: rtc core: registered 48070000.i2c:twl at 48:rtc as rtc0
[    1.980316] i2c /dev entries driver
[    1.985534] IR NEC protocol handler initialized
[    1.990753] IR RC5(x/sz) protocol handler initialized
[    1.996063] IR RC6 protocol handler initialized
[    2.000915] IR JVC protocol handler initialized
[    2.005645] IR Sony protocol handler initialized
[    2.010681] IR SANYO protocol handler initialized
[    2.015624] IR Sharp protocol handler initialized
[    2.020629] IR MCE Keyboard/mouse protocol handler initialized
[    2.026733] IR XMP protocol handler initialized
[    2.031524] Driver for 1-wire Dallas network protocol.
[    2.039276] ti-soc-thermal 48002524.bandgap: This OMAP thermal sensor is unreliable. You've been warned
[    2.049774] ti-soc-thermal 48002524.bandgap: Non-trimmed BGAP, Temp not accurate
[    2.060546] omap_wdt: OMAP Watchdog Timer Rev 0x31: initial timeout 60 sec
[    2.079956] sdhci: Secure Digital Host Controller Interface driver
[    2.086395] sdhci: Copyright(c) Pierre Ossman
[    2.119934] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.127655] ledtrig-cpu: registered to indicate activity on CPUs
[    2.142974] usbcore: registered new interface driver usbhid
[    2.150115] usbhid: USB HID core driver
[    2.154602] ashmem: initialized
[    2.164764] oprofile: using arm/armv7
[    2.168762] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.178344] Initializing XFRM netlink socket
[    2.182983] mmc0: new high speed SDHC card at address b368
[    2.188934] NET: Registered protocol family 17
[    2.193634] NET: Registered protocol family 15
[    2.199462] mmcblk0: mmc0:b368 USD   7.47 GiB 
[    2.205291] Key type dns_resolver registered
[    2.211181] omap2_set_init_voltage: unable to find boot up OPP for vdd_core
[    2.218627] omap2_set_init_voltage: unable to set vdd_core
[    2.224731] omap3_pm_off_mode_enable(1)
[    2.229064]  mmcblk0: p1
[    2.232788] ThumbEE CPU extension supported.
[    2.237274] Registering SWP/SWPB emulation handler
[    2.243316] SmartReflex Class3 initialized
[    2.248870] Loading compiled-in X.509 certificates
[    2.272949] platform 6e000000.gpmc: Retrying from deferred list
[    2.279907] omap-gpmc 6e000000.gpmc: GPMC revision 5.0
[    2.285308] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[    2.293548] omap2-onenand 4000000.onenand: initializing on CS0 (0x04000000), va (ptrval), DMA mode
[    2.303405] OneNAND Manufacturer: Samsung (0xec)
[    2.308349] Muxed OneNAND 512MB 1.8V 16-bit (0x50)
[    2.313323] OneNAND version = 0x0232
[    2.317077] Chip support all block unlock
[    2.321319] Chip has 4KiB pagesize
[    2.324859] Chip has cache program feature
[    2.330688] Scanning device for bad blocks
[    2.438171] onenand_bbt_wait: ecc 0xaaaa ctrl 0x0400 intr 0x8080 addr1 0x34b addr8 0x0
[    2.446441] OneNAND eraseblock 843 is an initial bad block
[    2.599395] omap2-onenand 4000000.onenand: optimized timings for 83 MHz
[    2.606353] 5 fixed-partitions partitions found on MTD device 4000000.onenand
[    2.613861] Creating 5 MTD partitions on "4000000.onenand":
[    2.619720] 0x000000000000-0x000000080000 : "X-Loader"
[    2.626708] 0x000000080000-0x000000240000 : "U-Boot"
[    2.633850] 0x000000240000-0x000000280000 : "U-Boot Env"
[    2.641326] 0x000000280000-0x000000880000 : "Kernel"
[    2.648498] 0x000000880000-0x000020000000 : "File System"
[    2.658264] platform wlan_en_regulator: Retrying from deferred list
[    2.666290] platform bt_en_regulator: Retrying from deferred list
[    2.673919] platform 480b4000.mmc: Retrying from deferred list
[    2.795898] ubi0: default fastmap pool size: 100
[    2.802978] ubi0: default fastmap WL pool size: 50
[    2.808135] ubi0: attaching mtd4
[    2.815002] omap_hsmmc 480b4000.mmc: card claims to support voltages below defined range
[    2.834747] onenand_wait: correctable ECC error = 0x5555
[    2.841308] ubi0: fixable bit-flip detected at PEB 187
[    2.858123] mmc1: new high speed SDIO card at address 0001
[    2.876342] onenand_wait: correctable ECC error = 0x5555
[    2.882049] ubi0: fixable bit-flip detected at PEB 465
[    2.948211] onenand_wait: correctable ECC error = 0x5555
[    2.953765] ubi0: fixable bit-flip detected at PEB 1326
[    2.975341] onenand_wait: correctable ECC error = 0x5555
[    2.980987] ubi0: fixable bit-flip detected at PEB 1554
[    3.015136] onenand_wait: correctable ECC error = 0x5555
[    3.020751] ubi0: fixable bit-flip detected at PEB 1960
[    3.029998] ubi0: scanning is finished
[    3.040924] ubi0: attached mtd4 (name "File System", size 503 MiB)
[    3.047393] ubi0: PEB size: 262144 bytes (256 KiB), LEB size: 258048 bytes
[    3.054962] ubi0: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
[    3.062164] ubi0: VID header offset: 1024 (aligned 1024), data offset: 4096
[    3.069519] ubi0: good PEBs: 2013, bad PEBs: 1, corrupted PEBs: 0
[    3.075897] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[    3.083587] ubi0: max/mean erase counter: 59/35, WL threshold: 4096, image sequence number: 163025748
[    3.093353] ubi0: available PEBs: 0, total reserved PEBs: 2013, PEBs reserved for bad PEB handling: 39
[    3.103149] ubi0: background thread "ubi_bgt0d" started, PID 1039
[    3.110595] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    3.119354] input: gpio-keys-wwan-wakeup as /devices/platform/gpio-keys-wwan-wakeup/input/input1
[    3.129791] omap_gpio 49056000.gpio: Could not set line 16 debounce to 10000 microseconds (-22)
[    3.139648] input: antenna-detect as /devices/platform/antenna-detect/input/input2
[    3.149291] twl_rtc 48070000.i2c:twl at 48:rtc: setting system clock to 2000-01-01 00:00:01 UTC (946684801)
[    3.159606] sr_init: No PMIC hook to init smartreflex
[    3.165618] smartreflex 480cb000.smartreflex: omap_sr_probe: SmartReflex driver initialized
[    3.175109] smartreflex 480c9000.smartreflex: omap_sr_probe: SmartReflex driver initialized
[    3.186187] VAUX3: disabling
[    3.190185] VDAC: disabling
[    3.193664] VUSB1V5: disabling
[    3.197296] VUSB1V8: disabling
[    3.201049] VUSB3V1: disabling
[    3.204681] wlan-en-regulator: disabling
[    3.208923] bt-en-regulator: disabling
[    3.212860] ALSA device list:
[    3.215942]   No soundcards found.
[    3.250671] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    3.259613] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    3.280364] devtmpfs: mounted
[    3.285339] Freeing unused kernel memory: 1024K
Mount failed for selinuxfs on /sys/fs/selinux:  No such file or directory
INIT: version 2.88 booting
[info] Using makefile-style concurrent boot in runlevel S.
[....] Starting the hotplug events dispatcher: udevd[    4.705017] systemd-udevd[1172]: starting version 215
[    4.800628] random: udevd: uninitialized urandom read (16 bytes read)
[    4.821441] random: udevd: uninitialized urandom read (16 bytes read)
.
[ ok ] Synthesizing the initial hotplug events...^[[c[    5.236297] omapdss: unknown parameter 'def_disp' ignored
done.
[....] Waiting for /dev to be fully populated...[    5.303161] omapdss_dss 48050000.dss: 48050000.dss supply vdda_video not found, using dummy regulator
^[[c[    5.342681] DSS: OMAP DSS rev 2.0
[    5.365570] omapdss_dss 48050000.dss: bound 48050400.dispc (ops dispc_component_ops [omapdss])
[    5.425079] omapdss_dss 48050000.dss: bound 48050c00.encoder (ops venc_component_ops [omapdss])
[    5.469055] gnss: GNSS driver registered with major 243
[    5.517791] ehci-omap: OMAP-EHCI Host Controller driver
[    5.550659] input: twl4030_pwrbutton as /devices/platform/68000000.ocp/48070000.i2c/i2c-0/0-0048/48070000.i2c:twl at 48:pwrbutton/input/input3
[    5.583160] input: twl4030:vibrator as /devices/platform/68000000.ocp/48070000.i2c/i2c-0/0-0048/48070000.i2c:twl at 48:audio/twl4030-vibra/input/input4
[    5.658874] twl4030_madc 48070000.i2c:twl at 48:madc: 48070000.i2c:twl at 48:madc supply vusb3v1 not found, using dummy regulator
[    5.670684] ehci-omap 48064800.ehci: EHCI Host Controller
[    5.699920] ehci-omap 48064800.ehci: new USB bus registered, assigned bus number 1
[    5.779174] ehci-omap 48064800.ehci: irq 93, io mem 0x48064800
[    5.791900] twl4030_gpio twl4030-gpio: can't dispatch IRQs from modules
[    5.836730] ehci-omap 48064800.ehci: USB 2.0 started, EHCI 1.00
[    5.882385] platform 48070000.i2c:twl at 48:bci: Retrying from deferred list
[    5.893646] media: Linux media interface: v0.10
[    5.932739] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.17
[    5.948638] platform 48070000.i2c:twl at 48:bci: Retrying from deferred list
[    6.018402] input: accelerometer-iio-input-bridge as /devices/virtual/input/input5
[    6.028686] twl4030_usb 48070000.i2c:twl at 48:twl4030-usb: Initialized TWL4030 USB module
[    6.044372] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    6.062072] Linux video capture interface: v2.00
[    6.072601] input: TSC2007 Touchscreen as /devices/platform/68000000.ocp/48072000.i2c/i2c-1/1-0048/input/input6
[    6.108154] usb usb1: Product: EHCI Host Controller
[    6.113250] usb usb1: Manufacturer: Linux 4.17.0-letux+ ehci_hcd
[    6.125518] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[    6.172119] usb usb1: SerialNumber: 48064800.ehci
[    6.180450] bmp280 1-0076: 1-0076 supply vddd not found, using dummy regulator
[    6.207427] hub 1-0:1.0: USB hub found
[    6.212493] bmp280 1-0076: 1-0076 supply vdda not found, using dummy regulator
[    6.220794] hub 1-0:1.0: 3 ports detected
[    6.245635] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 2
[    6.298309] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.17
[    6.363555] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    6.394958] usb usb2: Product: MUSB HDRC host driver
[    6.442871] usb usb2: Manufacturer: Linux 4.17.0-letux+ musb-hcd
[    6.505920] usb usb2: SerialNumber: musb-hdrc.0.auto
[    6.535705] hub 2-0:1.0: USB hub found
[    6.550048] i2c 1-0030: Retrying from deferred list
[    6.576538] hub 2-0:1.0: 1 port detected
[    6.612670] i2c 1-0030: Retrying from deferred list
[    6.626068] i2c 1-0030: Retrying from deferred list
[    6.648223] omap_hdq 480b2000.1w: OMAP HDQ Hardware Rev 0.5. Driver in Interrupt mode
[    6.728515] i2c 1-0030: Retrying from deferred list
[    6.738250] w1_master_driver w1_bus_master1: Attaching one wire slave 01.000000000000 crc 3d
[    6.747406] i2c 1-0030: Retrying from deferred list
[    6.790191] bq27xxx_battery_setup
[    6.793701] bq27xxx_battery_setup: dm_regs=  (null)
[    6.798980] i2c 1-0030: Retrying from deferred list
[    6.812499] (NULL device *): hwmon: 'bq27000-battery' is not a valid name attribute, please fix
[    6.835296] bq27xxx_battery_settings
[    6.840545] bq27xxx_battery_settings: power_supply_get_battery_info failed ret=-1088380908
[    6.882781] pwm-backlight backlight: backlight supply power not found, using dummy regulator
[    6.966156] (NULL device *): hwmon: 'gta04-battery' is not a valid name attribute, please fix
[    6.994628] wwan_on_off_init: wwan_on_off_init
[    7.014556] pps_core: LinuxPPS API ver. 1 registered
[    7.037109] i2c 1-0030: Retrying from deferred list
[    7.042602] iio_charge:-747
[    7.048522] platform backlight: Retrying from deferred list
[    7.054901] pinctrl_generic_get_group_name: group>name is NULL
[    7.077026] pinctrl_generic_group_name_to_selector: pctldev = ee33b180 function = backlight_pins_pinmux
[    7.096343]   strcmp(backlight_pins_pinmux, (null))
[    7.108062]   get_group_name() = pinctrl_generic_get_group_name+0x0/0x5c
[    7.115081]   ngroups = 19
[    7.119476] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti at linux.it>
[    7.129150]   selector = 18
[    7.132080] CPU: 0 PID: 952 Comm: kworker/0:2 Not tainted 4.17.0-letux+ #2494
[    7.139526] Hardware name: Generic OMAP36xx (Flattened Device Tree)
[    7.146087] Workqueue: events deferred_probe_work_func
[    7.151489] [<c01106ec>] (unwind_backtrace) from [<c010c058>] (show_stack+0x10/0x14)
[    7.159606] [<c010c058>] (show_stack) from [<c074bd08>] (dump_stack+0x7c/0x9c)
[    7.167144] [<c074bd08>] (dump_stack) from [<c042ce78>] (pinctrl_generic_add_group+0xa4/0x174)
[    7.176147] [<c042ce78>] (pinctrl_generic_add_group) from [<c0432128>] (pcs_dt_node_to_map+0x4b0/0x81c)
[    7.185943] [<c0432128>] (pcs_dt_node_to_map) from [<c04300c0>] (pinctrl_dt_to_map+0x1ec/0x2b8)
[    7.195037] [<c04300c0>] (pinctrl_dt_to_map) from [<c042d10c>] (create_pinctrl+0x58/0x2f8)
[    7.203674] [<c042d10c>] (create_pinctrl) from [<c042d46c>] (devm_pinctrl_get+0x2c/0x6c)
[    7.212127] [<c042d46c>] (devm_pinctrl_get) from [<c04f2f88>] (pinctrl_bind_pins+0x3c/0x138)
[    7.220947] [<c04f2f88>] (pinctrl_bind_pins) from [<c04d3fa4>] (driver_probe_device+0xe8/0x318)
[    7.230041] [<c04d3fa4>] (driver_probe_device) from [<c04d27f4>] (bus_for_each_drv+0x84/0x94)
[    7.238952] [<c04d27f4>] (bus_for_each_drv) from [<c04d3e24>] (__device_attach+0x88/0xfc)
[    7.247497] [<c04d3e24>] (__device_attach) from [<c04d33d0>] (bus_probe_device+0x28/0x80)
[    7.256011] [<c04d33d0>] (bus_probe_device) from [<c04d38ec>] (deferred_probe_work_func+0xf8/0x130)
[    7.265472] [<c04d38ec>] (deferred_probe_work_func) from [<c014874c>] (process_one_work+0x244/0x464)
[    7.275054] [<c014874c>] (process_one_work) from [<c0148f20>] (worker_thread+0x2c0/0x3ec)
[    7.283599] [<c0148f20>] (worker_thread) from [<c014cef4>] (kthread+0x134/0x150)
[    7.291320] [<c014cef4>] (kthread) from [<c01010e8>] (ret_from_fork+0x14/0x2c)
[    7.298858] Exception stack(0xee69dfb0 to 0xee69dff8)
[    7.304138] dfa0:                                     00000000 00000000 00000000 00000000
[    7.312683] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[    7.321197] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[    7.528137]   gname[0] = pinmux_hsusb2_pins
[    7.532531]   gname[1] = pinmux_uart1_pins
[    7.536804]   gname[2] = pinmux_uart2_pins
[    7.558166]   gname[3] = pinmux_uart3_pins
[    7.562469]   gname[4] = pinmux_twl4030_pins
[    7.566925]   gname[5] = pinmux_mmc1_pins
[    7.588165]   gname[6] = pinmux_wlan_irq_pin
[    7.592651]   gname[7] = pinmux_gpmc_pins
[    7.596832]   gname[8] = pinmux_wlan_pins
[    7.608184]   gname[9] = pinmux_bt_pins
[    7.612213]   gname[10] = pinmux_dss_dpi_pins
[    7.616760]   gname[11] = pinmux_mcbsp1_pins
[    7.636383]   gname[12] = pinmux_mcbsp2_pins
[    7.648162]   gname[13] = pinmux_mcbsp3_pins
[    7.652648]   gname[14] = pinmux_mcbsp4_pins
[    7.657104]   gname[15] = pinmux_penirq_pins
[    7.671142]   gname[16] = pinmux_camera_pins
[    7.675598]   gname[17] = hdq_pins
[    7.688140] pinctrl_generic_get_group_name: group>name is NULL
[    7.694244]   gname[18] = (null)
[    7.697692] pinctrl_generic_get_group_name: group>name is NULL
[    7.708221] pinctrl_generic_get_group_name: group>name is NULL
[    7.714324] pinctrl_get_group_selector: strcmp: (null) backlight_pins_pinmux
[    7.733917]   group_selector = 18
[    7.737426]   get_group_name = pinctrl_generic_get_group_name+0x0/0x5c
[    7.758148]   pctldev = 0xee33b180
[    7.761749] Unable to handle kernel NULL pointer dereference at virtual address 00000000
[    7.786560] pgd = (ptrval)
[    7.789642] [00000000] *pgd=00000000
[    7.793395] Internal error: Oops: 17 [#1] PREEMPT SMP ARM
[    7.799041] Modules linked in: pps_gpio(+) panel_tpo_td028ttec1 snd_soc_simple_card(+) snd_soc_simple_card_utils snd_soc_omap_twl4030(+) encoder_opa362 pps_core wwan_on_off(+) snd_soc_gtm601 connector_analog_tv pwm_omap_dmtimer generic_adc_battery pwm_bl bq27xxx_battery_hdq bq27xxx_battery bmp280_spi wlcore_sdio ov9655 v4l2_fwnode v4l2_common omap_hdq omap2430 bmp280_i2c bmp280 videodev at24 bmc150_accel_i2c bmc150_magn_i2c tsc2007 bmc150_magn leds_tca6507 bmc150_accel_core industrialio_triggered_buffer phy_twl4030_usb kfifo_buf media snd_soc_omap_mcbsp snd_soc_omap snd_pcm_dmaengine musb_hdrc gpio_twl4030 snd_soc_twl4030 twl4030_charger twl4030_vibra gnss_w2sg0004 twl4030_madc twl4030_pwrbutton industrialio ehci_omap gnss w2cbw003_bluetooth omapdss omapdss_base cec
[    7.870178] CPU: 0 PID: 952 Comm: kworker/0:2 Not tainted 4.17.0-letux+ #2494
[    7.877655] Hardware name: Generic OMAP36xx (Flattened Device Tree)
[    7.884216] Workqueue: events deferred_probe_work_func
[    7.889617] PC is at strcmp+0x0/0x34
[    7.893341] LR is at pinctrl_get_group_selector+0x90/0xd8
[    7.898986] pc : [<c0758fc0>]    lr : [<c042dac8>]    psr: 60000113
[    7.905548] sp : ee69de20  ip : 00000000  fp : 00000000
[    7.911010] r10: ed22f6c0  r9 : 00000014  r8 : 00000000
[    7.916442] r7 : c0799fd4  r6 : ee33b180  r5 : ef7c4958  r4 : 00000012
[    7.923248] r3 : 00000000  r2 : 00000000  r1 : ef7c4958  r0 : 00000000
[    7.930053] Flags: nZCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment none
[    7.937499] Control: 10c5387d  Table: ad3a8019  DAC: 00000051
[    7.943511] Process kworker/0:2 (pid: 952, stack limit = 0x(ptrval))
[    7.950134] Stack: (0xee69de20 to 0xee69e000)
[    7.954681] de20: ee69de44 00000000 ee33b180 ed12c210 ef7c4958 ed22f6c0 00000000 c042eaac
[    7.963226] de40: ed20b910 00000001 ed2267c0 ed22f700 ed22f740 ed12c210 00000000 c042d2a4
[    7.971771] de60: 00000014 ee28f780 c0b59e30 c08e9fec ee230010 00000000 ed226890 ee230010
[    7.980316] de80: c0bd1df0 fffffdfb bf212014 0000002e c0b98f70 c042d46c 00000000 ee230010
[    7.988830] dea0: ed2265d0 c04f2f88 ee230010 00000000 c0bd1df4 c04d3fa4 00000000 ee69def0
[    7.997375] dec0: c04d42b0 ee230044 c0b664c4 c0b02d00 00000000 c04d27f4 ee01be6c ed204b38
[    8.005889] dee0: ee230010 c0b666c8 00000001 c04d3e24 ee230010 00000001 00000000 ee230010
[    8.014434] df00: c0b666c8 ee230010 00000000 c04d33d0 ee230010 c0b664a8 ef7b7c80 c04d38ec
[    8.022979] df20: ee4eef00 c0b664dc ef7b7c80 00000000 ef7baf00 c014874c ee4eef00 c0b664dc
[    8.031524] df40: ffff8d8f ee4eef00 ef7b7c80 ef7b7c80 ee69c000 ef7b7c98 c0b02d00 ee4eef18
[    8.040039] df60: 00000008 c0148f20 00000000 ee680b00 ee4ebdc0 00000000 ee4eef00 c0148c60
[    8.048583] df80: ee0b5ef0 ee680b1c 00000000 c014cef4 ee4ebdc0 c014cdc0 00000000 00000000
[    8.057128] dfa0: 00000000 00000000 00000000 c01010e8 00000000 00000000 00000000 00000000
[    8.065673] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[    8.074218] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000 00000000 00000000
[    8.082763] [<c0758fc0>] (strcmp) from [<c042dac8>] (pinctrl_get_group_selector+0x90/0xd8)
[    8.091400] [<c042dac8>] (pinctrl_get_group_selector) from [<c042eaac>] (pinmux_map_to_setting+0xf0/0x134)
[    8.101501] [<c042eaac>] (pinmux_map_to_setting) from [<c042d2a4>] (create_pinctrl+0x1f0/0x2f8)
[    8.110565] [<c042d2a4>] (create_pinctrl) from [<c042d46c>] (devm_pinctrl_get+0x2c/0x6c)
[    8.119018] [<c042d46c>] (devm_pinctrl_get) from [<c04f2f88>] (pinctrl_bind_pins+0x3c/0x138)
[    8.127838] [<c04f2f88>] (pinctrl_bind_pins) from [<c04d3fa4>] (driver_probe_device+0xe8/0x318)
[    8.136962] [<c04d3fa4>] (driver_probe_device) from [<c04d27f4>] (bus_for_each_drv+0x84/0x94)
[    8.145843] [<c04d27f4>] (bus_for_each_drv) from [<c04d3e24>] (__device_attach+0x88/0xfc)
[    8.154418] [<c04d3e24>] (__device_attach) from [<c04d33d0>] (bus_probe_device+0x28/0x80)
[    8.162963] [<c04d33d0>] (bus_probe_device) from [<c04d38ec>] (deferred_probe_work_func+0xf8/0x130)
[    8.172424] [<c04d38ec>] (deferred_probe_work_func) from [<c014874c>] (process_one_work+0x244/0x464)
[    8.181976] [<c014874c>] (process_one_work) from [<c0148f20>] (worker_thread+0x2c0/0x3ec)
[    8.190551] [<c0148f20>] (worker_thread) from [<c014cef4>] (kthread+0x134/0x150)
[    8.198272] [<c014cef4>] (kthread) from [<c01010e8>] (ret_from_fork+0x14/0x2c)
[    8.205841] Exception stack(0xee69dfb0 to 0xee69dff8)
[    8.211120] dfa0:                                     00000000 00000000 00000000 00000000
[    8.219665] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[    8.228210] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[    8.235137] Code: e3520000 e5e32001 1afffffb e12fff1e (e4d03001) 
[    8.248138] pinctrl_generic_group_name_to_selector: pctldev = ee33b180 function = modem_pins
[    8.256988]   strcmp(modem_pins, (null))
[    8.345520]   get_group_name() = pinctrl_generic_get_group_name+0x0/0x5c
[    8.376617]   ngroups = 20
[    8.379638]   selector = 18
[    8.382568] CPU: 0 PID: 1207 Comm: udevd Tainted: G      D           4.17.0-letux+ #2494
[    8.391021] Hardware name: Generic OMAP36xx (Flattened Device Tree)
[    8.397613] [<c01106ec>] (unwind_backtrace) from [<c010c058>] (show_stack+0x10/0x14)
[    8.405700] [<c010c058>] (show_stack) from [<c074bd08>] (dump_stack+0x7c/0x9c)
[    8.413269] [<c074bd08>] (dump_stack) from [<c042ce78>] (pinctrl_generic_add_group+0xa4/0x174)
[    8.422271] [<c042ce78>] (pinctrl_generic_add_group) from [<c0432128>] (pcs_dt_node_to_map+0x4b0/0x81c)
[    8.432128] [<c0432128>] (pcs_dt_node_to_map) from [<c04300c0>] (pinctrl_dt_to_map+0x1ec/0x2b8)
[    8.441223] [<c04300c0>] (pinctrl_dt_to_map) from [<c042d10c>] (create_pinctrl+0x58/0x2f8)
[    8.449890] [<c042d10c>] (create_pinctrl) from [<c042d46c>] (devm_pinctrl_get+0x2c/0x6c)
[    8.458343] [<c042d46c>] (devm_pinctrl_get) from [<c04f2f88>] (pinctrl_bind_pins+0x3c/0x138)
[    8.467193] [<c04f2f88>] (pinctrl_bind_pins) from [<c04d3fa4>] (driver_probe_device+0xe8/0x318)
[    8.476287] [<c04d3fa4>] (driver_probe_device) from [<c04d4254>] (__driver_attach+0x80/0xa4)
[    8.485107] [<c04d4254>] (__driver_attach) from [<c04d2738>] (bus_for_each_dev+0x58/0x7c)
[    8.493682] [<c04d2738>] (bus_for_each_dev) from [<c04d35d0>] (bus_add_driver+0xcc/0x1e0)
[    8.502227] [<c04d35d0>] (bus_add_driver) from [<c04d4df8>] (driver_register+0x9c/0xe0)
[    8.510620] [<c04d4df8>] (driver_register) from [<c0102d74>] (do_one_initcall+0xb4/0x248)
[    8.519165] [<c0102d74>] (do_one_initcall) from [<c01a7174>] (do_init_module+0x58/0x1d0)
[    8.527648] [<c01a7174>] (do_init_module) from [<c01a5fa4>] (load_module+0xe04/0xfb0)
[    8.535858] [<c01a5fa4>] (load_module) from [<c01a6328>] (sys_finit_module+0x88/0x90)
[    8.544036] [<c01a6328>] (sys_finit_module) from [<c0101000>] (ret_fast_syscall+0x0/0x54)
[    8.552612] Exception stack(0xed09bfa8 to 0xed09bff0)
[    8.557891] bfa0:                   b6d6a7d4 00051f70 00000006 b6d699f8 00000000 b6d6a31c
[    8.566436] bfc0: b6d6a7d4 00051f70 d5a16800 0000017b 00020000 00037f78 00050048 000593e8
[    8.575012] bfe0: bede5ee0 bede5ed0 b6d63c4b b6e6ea42
[    8.694702] ---[ end trace e23961e39ad1e53b ]---
[    9.145721]   gname[0] = pinmux_hsusb2_pins
[    9.152832]   gname[1] = pinmux_uart1_pins
[    9.157623]   gname[2] = pinmux_uart2_pins
[    9.162536]   gname[3] = pinmux_uart3_pins
[    9.167541]   gname[4] = pinmux_twl4030_pins
[    9.188140]   gname[5] = pinmux_mmc1_pins
[    9.192352]   gname[6] = pinmux_wlan_irq_pin
[    9.196807]   gname[7] = pinmux_gpmc_pins
[    9.238220]   gname[8] = pinmux_wlan_pins
[    9.255950]   gname[9] = pinmux_bt_pins
[    9.268737]   gname[10] = pinmux_dss_dpi_pins
[    9.300689]   gname[11] = pinmux_mcbsp1_pins
[    9.324005]   gname[12] = pinmux_mcbsp2_pins
[    9.343505]   gname[13] = pinmux_mcbsp3_pins
[    9.365600]   gname[14] = pinmux_mcbsp4_pins
[    9.389404]   gname[15] = pinmux_penirq_pins
[    9.393859]   gname[16] = pinmux_camera_pins
[    9.438110]   gname[17] = hdq_pins
[    9.455657] pinctrl_generic_get_group_name: group>name is NULL
[    9.492248]   gname[18] = (null)
[    9.495666]   gname[19] = backlight_pins_pinmux
[    9.571685] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    9.626831] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    9.665374] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[    9.682403] cfg80211: failed to load regulatory.db
[   24.218994] random: crng init done

So the issue is that "backlight_pins_pinmux" are searched for a NULL record before they
are properly stored. Or someone punches a NULL into the radix_tree.

Hope this sheds some new light on the problem.

BR and thanks,
Nikolaus




More information about the Letux-kernel mailing list