* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 [not found] ` <197431489595078@web8g.yandex.ru> @ 2017-03-16 1:06 ` Greg Kroah-Hartman 2017-03-17 14:08 ` Tejun Heo 0 siblings, 1 reply; 12+ messages in thread From: Greg Kroah-Hartman @ 2017-03-16 1:06 UTC (permalink / raw) To: Icenowy Zheng, Tejun Heo; +Cc: Adam Borowski, Andre Przywara, linux-kernel On Thu, Mar 16, 2017 at 12:24:38AM +0800, Icenowy Zheng wrote: > > > 16.03.2017, 00:14, "Adam Borowski" <kilobyte@angband.pl>: > > Hi! > > On Pine64, since mid-February's -next, I get the following non-fatal warning: > > I don't think this is from any bug in sun50i-a64-pinctrl driver, as the PC even > didn't enter sun50i-a64-pinctrl driver... > > Maybe you should send this email also to the maintainer of the basical driver > framework. > > He's "Greg Kroah-Hartman <gregkh@linuxfoundation.org> (supporter:DRIVER > CORE, KOBJECTS, DEBUGFS, KERNFS AND SYSFS)" > > I added him to the "To" list of this reply. Well, Tejun added that line, way back in 2.6.21, it's not exactly "new code". It says something is wrong with the devres list for the device, that the bus and driver control, probe of your driver hasn't happened yet though, so I don't really know what this is trying to check for. Tejun? rest of the email left below for context for Tejun.. thanks, greg k-h > > [ 2.895375] platform 1c20800.pinctrl: Retrying from deferred list > > [ 2.901945] bus: 'platform': driver_probe_device: matched device 1c20800.pinctrl with driver sun50i-a64-pinctrl > > [ 2.912660] bus: 'platform': really_probe: probing driver sun50i-a64-pinctrl with device 1c20800.pinctrl > > [ 2.922826] ------------[ cut here ]------------ > > [ 2.927751] WARNING: CPU: 2 PID: 65 at drivers/base/dd.c:350 driver_probe_device.part.1+0x37c/0x390 > > [ 2.937339] Modules linked in: > > [ 2.940578] > > [ 2.942165] CPU: 2 PID: 65 Comm: kworker/2:1 Not tainted 4.11.0-rc1-00037-g4ca6f786aed9 #2 > > [ 2.950913] Hardware name: Pine64+ (DT) > > [ 2.954989] Workqueue: events deferred_probe_work_func > > [ 2.960449] task: ffff80007c690c80 task.stack: ffff80007c698000 > > [ 2.966741] PC is at driver_probe_device.part.1+0x37c/0x390 > > [ 2.972658] LR is at driver_probe_device.part.1+0x140/0x390 > > ... > > [ 3.173581] [<ffff000008555794>] driver_probe_device.part.1+0x37c/0x390 > > [ 3.180602] [<ffff00000855595c>] __device_attach_driver+0xac/0x150 > > [ 3.187162] [<ffff000008553610>] bus_for_each_drv+0x58/0x98 > > [ 3.193081] [<ffff000008555374>] __device_attach+0xc4/0x150 > > [ 3.198991] [<ffff000008555b40>] device_initial_probe+0x10/0x18 > > [ 3.205265] [<ffff0000085547f4>] bus_probe_device+0x94/0xa0 > > > > (this log has drivers/base/dd.c:350 not :349 because of added "#define > > DEBUG" at the start of that file) > > > > Full console output until that point attached. > > > > -- > > ⢀⣴⠾⠻⢶⣦⠀ Meow! > > ⣾⠁⢠⠒⠀⣿⡁ > > ⢿⡄⠘⠷⠚⠋⠀ Collisions shmolisions, let's see them find a collision or second > > ⠈⠳⣄⠀⠀⠀⠀ preimage for double rot13! ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-03-16 1:06 ` sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 Greg Kroah-Hartman @ 2017-03-17 14:08 ` Tejun Heo [not found] ` <785901489760914@web50g.yandex.ru> 0 siblings, 1 reply; 12+ messages in thread From: Tejun Heo @ 2017-03-17 14:08 UTC (permalink / raw) To: Greg Kroah-Hartman Cc: Icenowy Zheng, Adam Borowski, Andre Przywara, linux-kernel Hello, On Thu, Mar 16, 2017 at 10:06:15AM +0900, Greg Kroah-Hartman wrote: > > > [ 2.895375] platform 1c20800.pinctrl: Retrying from deferred list > > > [ 2.901945] bus: 'platform': driver_probe_device: matched device 1c20800.pinctrl with driver sun50i-a64-pinctrl > > > [ 2.912660] bus: 'platform': really_probe: probing driver sun50i-a64-pinctrl with device 1c20800.pinctrl > > > [ 2.922826] ------------[ cut here ]------------ > > > [ 2.927751] WARNING: CPU: 2 PID: 65 at drivers/base/dd.c:350 driver_probe_device.part.1+0x37c/0x390 > > > [ 2.937339] Modules linked in: > > > [ 2.940578] > > > [ 2.942165] CPU: 2 PID: 65 Comm: kworker/2:1 Not tainted 4.11.0-rc1-00037-g4ca6f786aed9 #2 > > > [ 2.950913] Hardware name: Pine64+ (DT) > > > [ 2.954989] Workqueue: events deferred_probe_work_func > > > [ 2.960449] task: ffff80007c690c80 task.stack: ffff80007c698000 > > > [ 2.966741] PC is at driver_probe_device.part.1+0x37c/0x390 > > > [ 2.972658] LR is at driver_probe_device.part.1+0x140/0x390 > > > ... > > > [ 3.173581] [<ffff000008555794>] driver_probe_device.part.1+0x37c/0x390 > > > [ 3.180602] [<ffff00000855595c>] __device_attach_driver+0xac/0x150 > > > [ 3.187162] [<ffff000008553610>] bus_for_each_drv+0x58/0x98 > > > [ 3.193081] [<ffff000008555374>] __device_attach+0xc4/0x150 > > > [ 3.198991] [<ffff000008555b40>] device_initial_probe+0x10/0x18 > > > [ 3.205265] [<ffff0000085547f4>] bus_probe_device+0x94/0xa0 It's warning that the device has resources associated with it on probe. There gotta be something fishy going on with the probing sequence. How reproducible is the problem? Thanks. -- tejun ^ permalink raw reply [flat|nested] 12+ messages in thread
[parent not found: <785901489760914@web50g.yandex.ru>]
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 [not found] ` <785901489760914@web50g.yandex.ru> @ 2017-03-17 14:44 ` Tejun Heo 2017-03-17 16:32 ` Adam Borowski 2017-04-02 23:48 ` André Przywara 0 siblings, 2 replies; 12+ messages in thread From: Tejun Heo @ 2017-03-17 14:44 UTC (permalink / raw) To: Icenowy Zheng Cc: Greg Kroah-Hartman, Adam Borowski, Andre Przywara, linux-kernel On Fri, Mar 17, 2017 at 10:28:34PM +0800, Icenowy Zheng wrote: > > It's warning that the device has resources associated with it on > > probe. There gotta be something fishy going on with the probing > > sequence. How reproducible is the problem? > > Do you mean in the first probing trial the driver didn't clean up well? Possibly but devres should have released all resources after the previous probe failure or driver disassociation, so I have no idea how there can be resources left on that list. > With the same driver I didn't see this problem in 4.11-rc{1,2}. devres hasn't changed, so I have no idea what changed that. Which kernels are affected? Can you bisect if the problem is easily reproducible? Thanks. -- tejun ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-03-17 14:44 ` Tejun Heo @ 2017-03-17 16:32 ` Adam Borowski 2017-04-02 23:48 ` André Przywara 1 sibling, 0 replies; 12+ messages in thread From: Adam Borowski @ 2017-03-17 16:32 UTC (permalink / raw) To: Tejun Heo; +Cc: Icenowy Zheng, Greg Kroah-Hartman, Andre Przywara, linux-kernel On Fri, Mar 17, 2017 at 10:44:22AM -0400, Tejun Heo wrote: > On Fri, Mar 17, 2017 at 10:28:34PM +0800, Icenowy Zheng wrote: > > > It's warning that the device has resources associated with it on > > > probe. There gotta be something fishy going on with the probing > > > sequence. How reproducible is the problem? > > > > Do you mean in the first probing trial the driver didn't clean up well? > > Possibly but devres should have released all resources after the > previous probe failure or driver disassociation, so I have no idea how > there can be resources left on that list. > > > With the same driver I didn't see this problem in 4.11-rc{1,2}. > > devres hasn't changed, so I have no idea what changed that. Which > kernels are affected? It's a not-yet-mainlined part (that's why I sent the report to Icenowy and Andre Przywara -- they work on the DT bindings and the driver itself). The exact kernel is based on git@github.com:Icenowy/linux.git (icenowy/sunxi64-4.11-rc1); I've removed all additional commits that could possibly be related. I need at the very least a f2fs fix to boot this machine (without reformatting). I did not bother trimming things that are obviously irrelevant (n_tty, vt, btrfs, sd); lemme retry with 4.11-rc2 that has just the f2fs fix and "#define DEBUG" in drivers/base/dd.c to be sure. I've first noticed the warning on pre-4.11 -next; because of very intense work in these areas Icenowy, Andre and the rest are doing the pine64 parts are hard to naively rebase -- and thus I haven't run intermediate versions. > Can you bisect if the problem is easily reproducible? While it reproduces 100%, the churn in required commits atop mainline would make bisecting pretty tricky. Do you have some other ideas over debugging the hard way? (I don't know these parts of the kernel myself thus I reported first.) In case my exact .config and tree are relevant: https://angband.pl/tmp/config-kb-ice-4.11-rc1.xz git@github.com:kilobyte/linux.git kb-ice-4.11-rc1 -- ⢀⣴⠾⠻⢶⣦⠀ Meow! ⣾⠁⢠⠒⠀⣿⡁ ⢿⡄⠘⠷⠚⠋⠀ Collisions shmolisions, let's see them find a collision or second ⠈⠳⣄⠀⠀⠀⠀ preimage for double rot13! ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-03-17 14:44 ` Tejun Heo 2017-03-17 16:32 ` Adam Borowski @ 2017-04-02 23:48 ` André Przywara 2017-04-18 7:25 ` Tejun Heo 1 sibling, 1 reply; 12+ messages in thread From: André Przywara @ 2017-04-02 23:48 UTC (permalink / raw) To: Tejun Heo, Icenowy Zheng; +Cc: Greg Kroah-Hartman, Adam Borowski, linux-kernel [-- Attachment #1: Type: text/plain, Size: 2079 bytes --] Hi, On 17/03/17 14:44, Tejun Heo wrote: > On Fri, Mar 17, 2017 at 10:28:34PM +0800, Icenowy Zheng wrote: >>> It's warning that the device has resources associated with it on >>> probe. There gotta be something fishy going on with the probing >>> sequence. How reproducible is the problem? >> >> Do you mean in the first probing trial the driver didn't clean up well? > > Possibly but devres should have released all resources after the > previous probe failure or driver disassociation, so I have no idea how > there can be resources left on that list. > >> With the same driver I didn't see this problem in 4.11-rc{1,2}. > > devres hasn't changed, so I have no idea what changed that. Which > kernels are affected? Can you bisect if the problem is easily > reproducible? So I see this problem easily now - on every boot - with an unpatched 4.11-rc3 kernel and the (arm64) defconfig on a Pine64 or BananaPi M64. I enabled devres.log and see that pinctrl probes early, but apparently gets deferred, pretty late actually (after 43 ADDs). Now what sticks out from the sequence (see the attached log) is that there are two un-matches ADDs with a devm_kmalloc of size 0: sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84200 devm_kzalloc_release (0 bytes) sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84100 devm_kzalloc_release (0 bytes) While all the other ADDs have a matching REL, those two have not. I guess it's due to the size being 0. Does that ring a bell? Or is due to the fact that these two ADDs are after the RELs have already started, so at a point where the driver is already cleaned up? The last two successful actions are the devm_pinctrl_register() and the following devm_kzalloc() for struct gpiochip (312 bytes) (in drivers/pinctrl/sunxi/pinctrl-sunxi.c). The it calls gpiochip_add_data(), which apparently returns -EPROBE_DEFER. I am pretty clueless when it comes to the internals of devm and devres, and TBH couldn't easily see where the it could fail in drivers/base/devres.c. So does any of you have a clue? Cheers, Andre. [-- Attachment #2: dmesg_pinctrl.txt --] [-- Type: text/plain, Size: 50228 bytes --] [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.11.0-rc1 (aprzywara@slackpad) (gcc version 6.2.0 (GCC) ) #298 SMP PREEMPT Sun Apr 2 23:31:00 BST 2017 [ 0.000000] Boot CPU: AArch64 Processor [410fd034] [ 0.000000] earlycon: uart0 at MMIO32 0x0000000001c28000 (options '') [ 0.000000] bootconsole [uart0] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000000bfffffff] [ 0.000000] NUMA: Adding memblock [0x40000000 - 0xbfffffff] on node 0 [ 0.000000] NUMA: Initmem setup node 0 [mem 0x40000000-0xbfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbefe7480-0xbefe8f7f] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] On node 0 totalpages: 524288 [ 0.000000] DMA zone: 8192 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 524288 pages, LIFO batch:31 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv0.2 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] percpu: Embedded 23 pages/cpu @ffff80007ef88000 s56728 r8192 d29288 u94208 [ 0.000000] pcpu-alloc: s56728 r8192 d29288 u94208 alloc=23*4096 [ 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] Built 1 zonelists in Node order, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA [ 0.000000] Kernel command line: console=ttyS0,115200n8 ro root=/dev/mmcblk0p2 rootwait earlycon=uart,mmio32,0x1c28000 devres.log=1 [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Memory: 2032148K/2097152K available (8828K kernel code, 962K rwdata, 3956K rodata, 1088K init, 394K bss, 48620K reserved, 16384K cma-reserved) [ 0.000000] Virtual kernel memory layout: [ 0.000000] modules : 0xffff000000000000 - 0xffff000008000000 ( 128 MB) [ 0.000000] vmalloc : 0xffff000008000000 - 0xffff7dffbfff0000 (129022 GB) [ 0.000000] .text : 0xffff000008080000 - 0xffff000008920000 ( 8832 KB) [ 0.000000] .rodata : 0xffff000008920000 - 0xffff000008d10000 ( 4032 KB) [ 0.000000] .init : 0xffff000008d10000 - 0xffff000008e20000 ( 1088 KB) [ 0.000000] .data : 0xffff000008e20000 - 0xffff000008f10a00 ( 963 KB) [ 0.000000] .bss : 0xffff000008f10a00 - 0xffff000008f733ac ( 395 KB) [ 0.000000] fixed : 0xffff7dfffe7fd000 - 0xffff7dfffec00000 ( 4108 KB) [ 0.000000] PCI I/O : 0xffff7dfffee00000 - 0xffff7dffffe00000 ( 16 MB) [ 0.000000] vmemmap : 0xffff7e0000000000 - 0xffff800000000000 ( 2048 GB maximum) [ 0.000000] 0xffff7e0000000000 - 0xffff7e0002000000 ( 32 MB actual) [ 0.000000] memory : 0xffff800000000000 - 0xffff800080000000 ( 2048 MB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 [ 0.000000] NR_IRQS:64 nr_irqs:64 0 [ 0.000000] GIC: Using split EOI/Deactivate mode [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000005] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.008247] Console: colour dummy device 80x25 [ 0.012742] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) [ 0.022999] pid_max: default: 32768 minimum: 301 [ 0.027691] Security Framework initialized [ 0.032363] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.041718] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.049754] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.056461] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.075645] ASID allocator initialised with 65536 entries [ 0.101451] EFI services will not be available. [ 0.114011] smp: Bringing up secondary CPUs ... [ 0.151416] Detected VIPT I-cache on CPU1 [ 0.151469] CPU1: Booted secondary processor [410fd034] [ 0.183098] Detected VIPT I-cache on CPU2 [ 0.183127] CPU2: Booted secondary processor [410fd034] [ 0.215130] Detected VIPT I-cache on CPU3 [ 0.215158] CPU3: Booted secondary processor [410fd034] [ 0.215212] smp: Brought up 1 node, 4 CPUs [ 0.246961] SMP: Total of 4 processors activated. [ 0.251663] CPU features: detected feature: 32-bit EL0 Support [ 0.257534] CPU: All CPU(s) started at EL2 [ 0.261646] alternatives: patching kernel code [ 0.267283] devtmpfs: initialized [ 0.272657] DMI not present or invalid. [ 0.276684] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.286472] futex hash table entries: 1024 (order: 5, 131072 bytes) [ 0.293410] pinctrl core: initialized pinctrl subsystem [ 0.299196] bus: 'platform': really_probe: probing driver reg-dummy with device reg-dummy [ 0.307395] reg-dummy reg-dummy: DEVRES ADD ffff80007c11ac80 devm_kzalloc_release (40 bytes) [ 0.315837] reg-dummy reg-dummy: DEVRES ADD ffff80007c11ad80 devm_pinctrl_release (8 bytes) [ 0.324186] reg-dummy reg-dummy: DEVRES REM ffff80007c11ad80 devm_pinctrl_release (8 bytes) [ 0.332535] reg-dummy reg-dummy: DEVRES REM ffff80007c11ac80 devm_kzalloc_release (40 bytes) [ 0.341710] NET: Registered protocol family 16 [ 0.347739] cpuidle: using governor menu [ 0.352013] vdso: 2 pages (1 code @ ffff000008927000, 1 data @ ffff000008e25000) [ 0.359433] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.367428] DMA: preallocated 256 KiB pool for atomic allocations [ 0.373966] Serial: AMBA PL011 UART driver [ 0.378897] bus: 'platform': really_probe: probing driver sram with device 10000.sram [ 0.386744] sram 10000.sram: DEVRES ADD ffff80007c0f6e80 devm_kzalloc_release (40 bytes) [ 0.394847] sram 10000.sram: DEVRES REM ffff80007c0f6e80 devm_kzalloc_release (40 bytes) [ 0.402951] sram 10000.sram: DEVRES ADD ffff80007c0f6f80 devm_kzalloc_release (48 bytes) [ 0.411048] sram 10000.sram: DEVRES ADD ffff80007c0f6f00 devm_region_release (24 bytes) [ 0.419061] sram 10000.sram: DEVRES ADD ffff80007c0f6a80 devm_ioremap_release (8 bytes) [ 0.427069] sram 10000.sram: DEVRES ADD ffff80007c0f6600 devm_gen_pool_release (8 bytes) [ 0.446895] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.454212] ACPI: Interpreter disabled. [ 0.458415] bus: 'platform': really_probe: probing driver reg-fixed-voltage with device vcc3v3 [ 0.467044] reg-fixed-voltage vcc3v3: DEVRES ADD ffff80007c22cf80 devm_kzalloc_release (40 bytes) [ 0.476783] reg-fixed-voltage vcc3v3: DEVRES REM ffff80007c22cf80 devm_kzalloc_release (40 bytes) [ 0.485693] reg-fixed-voltage vcc3v3: DEVRES ADD ffff80007c2e1800 devm_kzalloc_release (232 bytes) [ 0.494661] reg-fixed-voltage vcc3v3: DEVRES ADD ffff80007c22ce80 devm_kzalloc_release (40 bytes) [ 0.503533] reg-fixed-voltage vcc3v3: DEVRES ADD ffff80007c035500 devm_kzalloc_release (160 bytes) [ 0.512558] reg-fixed-voltage vcc3v3: DEVRES ADD ffff80007c22cd00 devm_kzalloc_release (7 bytes) [ 0.521525] reg-fixed-voltage vcc3v3: DEVRES ADD ffff80007c22cc80 devm_rdev_release (8 bytes) [ 0.530496] vgaarb: loaded [ 0.533478] SCSI subsystem initialized [ 0.537410] libata version 3.00 loaded. [ 0.537712] usbcore: registered new interface driver usbfs [ 0.543259] usbcore: registered new interface driver hub [ 0.548647] usbcore: registered new device driver usb [ 0.554289] pps_core: LinuxPPS API ver. 1 registered [ 0.559261] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.568450] PTP clock support registered [ 0.572736] dmi: Firmware registration failed. [ 0.577383] Advanced Linux Sound Architecture Driver Initialized. [ 0.584417] clocksource: Switched to clocksource arch_sys_counter [ 0.590667] VFS: Disk quotas dquot_6.6.0 [ 0.594651] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.601815] pnp: PnP ACPI: disabled [ 0.614803] NET: Registered protocol family 2 [ 0.619718] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 0.627162] TCP bind hash table entries: 16384 (order: 6, 262144 bytes) [ 0.634110] TCP: Hash tables configured (established 16384 bind 16384) [ 0.640781] UDP hash table entries: 1024 (order: 3, 32768 bytes) [ 0.646861] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) [ 0.653493] NET: Registered protocol family 1 [ 0.658210] RPC: Registered named UNIX socket transport module. [ 0.664133] RPC: Registered udp transport module. [ 0.668842] RPC: Registered tcp transport module. [ 0.673545] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.679990] PCI: CLS 0 bytes, default 128 [ 0.680770] kvm [1]: 8-bit VMID [ 0.683907] kvm [1]: IDMAP page: 4090b000 [ 0.687924] kvm [1]: HYP VA range: 800000000000:ffffffffffff [ 0.694326] kvm [1]: Hyp mode initialized successfully [ 0.699508] kvm [1]: vgic-v2@1c84000 [ 0.703170] kvm [1]: vgic interrupt IRQ1 [ 0.707106] kvm [1]: virtual timer IRQ4 [ 0.713093] bus: 'platform': really_probe: probing driver alarmtimer with device alarmtimer [ 0.721462] alarmtimer alarmtimer: DEVRES ADD ffff80007bc68380 devm_kzalloc_release (40 bytes) [ 0.730082] alarmtimer alarmtimer: DEVRES ADD ffff80007bc68480 devm_pinctrl_release (8 bytes) [ 0.738605] alarmtimer alarmtimer: DEVRES REM ffff80007bc68480 devm_pinctrl_release (8 bytes) [ 0.747133] alarmtimer alarmtimer: DEVRES REM ffff80007bc68380 devm_kzalloc_release (40 bytes) [ 0.756345] audit: initializing netlink subsys (disabled) [ 0.761846] audit: type=2000 audit(0.644:1): state=initialized audit_enabled=0 res=1 [ 0.762337] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 0.773052] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.773914] NFS: Registering the id_resolver key type [ 0.773940] Key type id_resolver registered [ 0.773942] Key type id_legacy registered [ 0.773952] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.774159] 9p: Installing v9fs 9p2000 file system support [ 0.776847] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 0.776853] io scheduler noop registered [ 0.777071] io scheduler cfq registered (default) [ 0.777075] io scheduler mq-deadline registered [ 0.783445] bus: 'platform': really_probe: probing driver sun50i-a64-pinctrl with device 1c20800.pinctrl [ 0.783460] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c27e400 devm_kzalloc_release (40 bytes) [ 0.783478] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REM ffff80007c27e400 devm_kzalloc_release (40 bytes) [ 0.783507] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c285d00 devm_kzalloc_release (112 bytes) [ 0.783519] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c27e780 devm_region_release (24 bytes) [ 0.783538] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c27ef80 devm_ioremap_release (8 bytes) [ 0.783545] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c287200 devm_kzalloc_release (384 bytes) [ 0.783553] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c339800 devm_kzalloc_release (1648 bytes) [ 0.783567] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c1d6000 devm_kzalloc_release (2472 bytes) [ 0.783727] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c23ac00 devm_kzalloc_release (824 bytes) [ 0.783733] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c23b000 devm_kzalloc_release (824 bytes) [ 0.783740] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c22c500 devm_kzalloc_release (32 bytes) [ 0.783747] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c22c280 devm_kzalloc_release (64 bytes) [ 0.783753] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c287400 devm_kzalloc_release (288 bytes) [ 0.783761] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c105980 devm_kzalloc_release (56 bytes) [ 0.783771] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c2cb980 devm_kzalloc_release (40 bytes) [ 0.783779] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c279c80 devm_kzalloc_release (32 bytes) [ 0.783792] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c279e00 devm_kzalloc_release (32 bytes) [ 0.783800] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c285e00 devm_kzalloc_release (136 bytes) [ 0.783807] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c279a80 devm_kzalloc_release (32 bytes) [ 0.783815] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c285f00 devm_kzalloc_release (96 bytes) [ 0.783846] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd74000 devm_kzalloc_release (176 bytes) [ 0.783853] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c279d00 devm_kzalloc_release (48 bytes) [ 0.783860] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c279700 devm_kzalloc_release (32 bytes) [ 0.783867] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd74100 devm_kzalloc_release (96 bytes) [ 0.783882] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75000 devm_kzalloc_release (32 bytes) [ 0.783903] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd74200 devm_kzalloc_release (128 bytes) [ 0.783917] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75080 devm_kzalloc_release (80 bytes) [ 0.783949] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75100 devm_kzalloc_release (8 bytes) [ 0.783960] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd74300 devm_kzalloc_release (112 bytes) [ 0.783967] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd74400 devm_kzalloc_release (96 bytes) [ 0.784001] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75180 devm_kzalloc_release (8 bytes) [ 0.784008] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75200 devm_kzalloc_release (16 bytes) [ 0.784019] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75280 devm_kzalloc_release (48 bytes) [ 0.784038] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75300 devm_kzalloc_release (48 bytes) [ 0.784056] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75380 devm_kzalloc_release (32 bytes) [ 0.784070] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75400 devm_kzalloc_release (32 bytes) [ 0.784077] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75480 devm_kzalloc_release (32 bytes) [ 0.784095] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75500 devm_kzalloc_release (16 bytes) [ 0.784105] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75580 devm_kzalloc_release (16 bytes) [ 0.784123] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75600 devm_kzalloc_release (8 bytes) [ 0.784132] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75680 devm_kzalloc_release (16 bytes) [ 0.784143] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c1d2000 devm_kzalloc_release (2472 bytes) [ 0.784152] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75700 devm_kzalloc_release (80 bytes) [ 0.784309] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd75780 devm_pinctrl_dev_release (8 bytes) [ 0.784316] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c287600 devm_kzalloc_release (312 bytes) [ 0.784822] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c287600 devm_kzalloc_release (312 bytes) [ 0.784829] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75780 devm_pinctrl_dev_release (8 bytes) [ 0.784895] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84200 devm_kzalloc_release (0 bytes) [ 0.784902] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84100 devm_kzalloc_release (0 bytes) [ 0.784977] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75700 devm_kzalloc_release (80 bytes) [ 0.784983] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c1d2000 devm_kzalloc_release (2472 bytes) [ 0.784989] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75680 devm_kzalloc_release (16 bytes) [ 0.784995] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75600 devm_kzalloc_release (8 bytes) [ 0.785001] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75580 devm_kzalloc_release (16 bytes) [ 0.785007] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75500 devm_kzalloc_release (16 bytes) [ 0.785013] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75480 devm_kzalloc_release (32 bytes) [ 0.785020] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75400 devm_kzalloc_release (32 bytes) [ 0.785026] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75380 devm_kzalloc_release (32 bytes) [ 0.785032] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75300 devm_kzalloc_release (48 bytes) [ 0.785038] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75280 devm_kzalloc_release (48 bytes) [ 0.785044] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75200 devm_kzalloc_release (16 bytes) [ 0.785050] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75180 devm_kzalloc_release (8 bytes) [ 0.785056] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd74400 devm_kzalloc_release (96 bytes) [ 0.785062] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd74300 devm_kzalloc_release (112 bytes) [ 0.785068] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75100 devm_kzalloc_release (8 bytes) [ 0.785074] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75080 devm_kzalloc_release (80 bytes) [ 0.785080] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd74200 devm_kzalloc_release (128 bytes) [ 0.785086] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd75000 devm_kzalloc_release (32 bytes) [ 0.785093] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd74100 devm_kzalloc_release (96 bytes) [ 0.785099] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c279700 devm_kzalloc_release (32 bytes) [ 0.785105] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c279d00 devm_kzalloc_release (48 bytes) [ 0.785111] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007bd74000 devm_kzalloc_release (176 bytes) [ 0.785118] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c285f00 devm_kzalloc_release (96 bytes) [ 0.785124] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c279a80 devm_kzalloc_release (32 bytes) [ 0.785130] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c285e00 devm_kzalloc_release (136 bytes) [ 0.785136] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c279e00 devm_kzalloc_release (32 bytes) [ 0.785142] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c279c80 devm_kzalloc_release (32 bytes) [ 0.785148] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c2cb980 devm_kzalloc_release (40 bytes) [ 0.785155] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c105980 devm_kzalloc_release (56 bytes) [ 0.785162] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c287400 devm_kzalloc_release (288 bytes) [ 0.785168] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c22c280 devm_kzalloc_release (64 bytes) [ 0.785174] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c22c500 devm_kzalloc_release (32 bytes) [ 0.785180] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c23b000 devm_kzalloc_release (824 bytes) [ 0.785186] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c23ac00 devm_kzalloc_release (824 bytes) [ 0.785193] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c1d6000 devm_kzalloc_release (2472 bytes) [ 0.785199] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c339800 devm_kzalloc_release (1648 bytes) [ 0.785205] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c287200 devm_kzalloc_release (384 bytes) [ 0.785212] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c27ef80 devm_ioremap_release (8 bytes) [ 0.785225] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c27e780 devm_region_release (24 bytes) [ 0.785233] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REL ffff80007c285d00 devm_kzalloc_release (112 bytes) [ 0.788968] bus: 'platform': really_probe: probing driver sun50i-a64-ccu with device 1c20000.clock [ 0.788984] sun50i-a64-ccu 1c20000.clock: DEVRES ADD ffff80007bcf2680 devm_kzalloc_release (40 bytes) [ 0.789003] sun50i-a64-ccu 1c20000.clock: DEVRES REM ffff80007bcf2680 devm_kzalloc_release (40 bytes) [ 0.789050] sun50i-a64-ccu 1c20000.clock: DEVRES ADD ffff80007bcf2e00 devm_region_release (24 bytes) [ 0.789068] sun50i-a64-ccu 1c20000.clock: DEVRES ADD ffff80007bcf2d00 devm_ioremap_release (8 bytes) [ 0.791647] xenfs: not registering filesystem on non-xen platform [ 0.796647] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.797832] bus: 'platform': really_probe: probing driver serial8250 with device serial8250 [ 0.797847] serial8250 serial8250: DEVRES ADD ffff80007bde3680 devm_kzalloc_release (40 bytes) [ 0.797858] serial8250 serial8250: DEVRES ADD ffff80007bde3700 devm_pinctrl_release (8 bytes) [ 0.797868] serial8250 serial8250: DEVRES REM ffff80007bde3700 devm_pinctrl_release (8 bytes) [ 0.797876] serial8250 serial8250: DEVRES REM ffff80007bde3680 devm_kzalloc_release (40 bytes) [ 0.798283] bus: 'platform': really_probe: probing driver dw-apb-uart with device 1c28000.serial [ 0.798292] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007bde3700 devm_kzalloc_release (40 bytes) [ 0.798319] dw-apb-uart 1c28000.serial: could not find pctldev for node /soc/pinctrl@1c20800/uart0@0, deferring probe [ 0.798326] dw-apb-uart 1c28000.serial: DEVRES REM ffff80007bde3700 devm_kzalloc_release (40 bytes) [ 0.798341] bus: 'platform': really_probe: probing driver dw-apb-uart with device 1c28400.serial [ 0.798348] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007bde3700 devm_kzalloc_release (40 bytes) [ 0.798361] dw-apb-uart 1c28400.serial: could not find pctldev for node /soc/pinctrl@1c20800/uart1_pins, deferring probe [ 0.798368] dw-apb-uart 1c28400.serial: DEVRES REM ffff80007bde3700 devm_kzalloc_release (40 bytes) [ 0.798868] SuperH (H)SCI(F) driver initialized [ 0.799224] msm_serial: driver initialized [ 0.809328] loop: module loaded [ 0.810293] hisi_sas: driver version v1.6 [ 0.813195] libphy: Fixed MDIO Bus: probed [ 0.814016] tun: Universal TUN/TAP device driver, 1.6 [ 0.814019] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com> [ 0.815129] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 0.815131] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 0.815224] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 0.815226] igb: Copyright (c) 2007-2014 Intel Corporation. [ 0.815302] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k [ 0.815305] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 0.815388] sky2: driver version 1.30 [ 0.815997] VFIO - User Level meta-driver version: 0.3 [ 0.821156] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.821162] ehci-pci: EHCI PCI platform driver [ 0.821204] ehci-platform: EHCI generic platform driver [ 0.821279] ehci-exynos: EHCI EXYNOS driver [ 0.821341] ehci-msm: Qualcomm On-Chip EHCI Host Controller [ 0.821397] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.821420] ohci-pci: OHCI PCI platform driver [ 0.821469] ohci-platform: OHCI generic platform driver [ 0.821531] ohci-exynos: OHCI EXYNOS driver [ 0.821843] usbcore: registered new interface driver usb-storage [ 0.822969] bus: 'platform': really_probe: probing driver sun6i-rtc with device 1f00000.rtc [ 0.822985] sun6i-rtc 1f00000.rtc: DEVRES ADD ffff80007b834400 devm_kzalloc_release (40 bytes) [ 0.823002] sun6i-rtc 1f00000.rtc: DEVRES REM ffff80007b834400 devm_kzalloc_release (40 bytes) [ 0.823100] sun6i-rtc 1f00000.rtc: DEVRES ADD ffff80007b834b80 devm_irq_release (16 bytes) [ 0.823663] sun6i-rtc 1f00000.rtc: rtc core: registered rtc-sun6i as rtc0 [ 0.823670] sun6i-rtc 1f00000.rtc: DEVRES ADD ffff80007b834c80 devm_rtc_device_release (8 bytes) [ 0.823674] sun6i-rtc 1f00000.rtc: RTC enabled [ 0.823873] i2c /dev entries driver [ 0.824191] bus: 'platform': really_probe: probing driver mv64xxx_i2c with device 1c2b000.i2c [ 0.824201] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007b834980 devm_kzalloc_release (40 bytes) [ 0.824225] mv64xxx_i2c 1c2b000.i2c: could not find pctldev for node /soc/pinctrl@1c20800/i2c1_pins, deferring probe [ 0.824233] mv64xxx_i2c 1c2b000.i2c: DEVRES REM ffff80007b834980 devm_kzalloc_release (40 bytes) [ 0.825845] sdhci: Secure Digital Host Controller Interface driver [ 0.825847] sdhci: Copyright(c) Pierre Ossman [ 0.826046] Synopsys Designware Multimedia Card Interface Driver [ 0.826437] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c0f000.mmc [ 0.826449] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b844080 devm_kzalloc_release (40 bytes) [ 0.826474] sunxi-mmc 1c0f000.mmc: could not find pctldev for node /soc/pinctrl@1c20800/mmc0-pins, deferring probe [ 0.826482] sunxi-mmc 1c0f000.mmc: DEVRES REM ffff80007b844080 devm_kzalloc_release (40 bytes) [ 0.826499] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c10000.mmc [ 0.826506] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b844080 devm_kzalloc_release (40 bytes) [ 0.826518] sunxi-mmc 1c10000.mmc: could not find pctldev for node /soc/pinctrl@1c20800/mmc1-pins, deferring probe [ 0.826525] sunxi-mmc 1c10000.mmc: DEVRES REM ffff80007b844080 devm_kzalloc_release (40 bytes) [ 0.826538] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c11000.mmc [ 0.826544] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b844080 devm_kzalloc_release (40 bytes) [ 0.826557] sunxi-mmc 1c11000.mmc: could not find pctldev for node /soc/pinctrl@1c20800/mmc2-pins, deferring probe [ 0.826563] sunxi-mmc 1c11000.mmc: DEVRES REM ffff80007b844080 devm_kzalloc_release (40 bytes) [ 0.826614] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.828777] ledtrig-cpu: registered to indicate activity on CPUs [ 0.828828] bus: 'platform': really_probe: probing driver scpi_protocol with device scpi [ 0.828838] scpi_protocol scpi: DEVRES ADD ffff80007c248400 devm_kzalloc_release (40 bytes) [ 0.828854] scpi_protocol scpi: DEVRES REM ffff80007c248400 devm_kzalloc_release (40 bytes) [ 0.828881] scpi_protocol scpi: DEVRES ADD ffff80007c385b00 devm_kzalloc_release (120 bytes) [ 0.828895] scpi_protocol scpi: DEVRES ADD ffff80007c385c00 devm_kzalloc_release (168 bytes) [ 0.828929] scpi_protocol scpi: DEVRES ADD ffff80007c248400 devm_ioremap_release (8 bytes) [ 0.828937] scpi_protocol scpi: DEVRES ADD ffff80007bc9dc00 devm_kzalloc_release (880 bytes) [ 0.828949] scpi_protocol scpi: DEVRES REL ffff80007bc9dc00 devm_kzalloc_release (880 bytes) [ 0.828956] scpi_protocol scpi: DEVRES REL ffff80007c248400 devm_ioremap_release (8 bytes) [ 0.828968] scpi_protocol scpi: DEVRES REL ffff80007c385c00 devm_kzalloc_release (168 bytes) [ 0.828974] scpi_protocol scpi: DEVRES REL ffff80007c385b00 devm_kzalloc_release (120 bytes) [ 0.829776] usbcore: registered new interface driver usbhid [ 0.829778] usbhid: USB HID core driver [ 0.830870] bus: 'platform': really_probe: probing driver snd-soc-dummy with device snd-soc-dummy [ 0.830884] snd-soc-dummy snd-soc-dummy: DEVRES ADD ffff80007c2f1880 devm_kzalloc_release (40 bytes) [ 0.830896] snd-soc-dummy snd-soc-dummy: DEVRES ADD ffff80007c2f1380 devm_pinctrl_release (8 bytes) [ 0.830904] snd-soc-dummy snd-soc-dummy: DEVRES REM ffff80007c2f1380 devm_pinctrl_release (8 bytes) [ 0.830913] snd-soc-dummy snd-soc-dummy: DEVRES REM ffff80007c2f1880 devm_kzalloc_release (40 bytes) [ 0.831382] NET: Registered protocol family 17 [ 0.831474] 9pnet: Installing 9P2000 support [ 0.831532] Key type dns_resolver registered [ 0.832147] registered taskstats version 1 [ 0.836738] bus: 'platform': really_probe: probing driver sun50i-a64-pinctrl with device 1c20800.pinctrl [ 0.836812] ------------[ cut here ]------------ [ 0.836837] WARNING: CPU: 1 PID: 89 at /data/src/linux/drivers/base/dd.c:349 driver_probe_device+0x2ac/0x2e8 [ 0.836839] Modules linked in: [ 0.836851] CPU: 1 PID: 89 Comm: kworker/1:1 Not tainted 4.11.0-rc1 #298 [ 0.836854] Hardware name: BananaPi-M64 (DT) [ 0.836863] Workqueue: events deferred_probe_work_func [ 0.836867] task: ffff80007c164e00 task.stack: ffff80007c180000 [ 0.836873] PC is at driver_probe_device+0x2ac/0x2e8 [ 0.836879] LR is at driver_probe_device+0x108/0x2e8 [ 0.836883] pc : [<ffff000008553714>] lr : [<ffff000008553570>] pstate: 20000045 [ 0.836885] sp : ffff80007c183c50 [ 0.836887] x29: ffff80007c183c50 x28: 0000000000000000 [ 0.836892] x27: ffff80007cbd7d20 x26: ffff80007c0c01b8 [ 0.836898] x25: ffff000008a0f000 x24: ffff000008ed9e08 [ 0.836903] x23: 0000000000000001 x22: ffff000008f62000 [ 0.836909] x21: ffff000008e71ea0 x20: 0000000000000000 [ 0.836914] x19: ffff80007c042010 x18: ffff80007cb53cf0 [ 0.836920] x17: 00000000b8b143dc x16: 00000000553657ab [ 0.836925] x15: 000000009add3b90 x14: 2068746977206c72 [ 0.836930] x13: 74636e69702d3436 x12: 612d6930356e7573 [ 0.836936] x11: 2072657669726420 x10: 676e69626f727020 [ 0.836941] x9 : 3a65626f72705f79 x8 : ffff000008f26330 [ 0.836946] x7 : 0000000000000000 x6 : 0000000055e669a8 [ 0.836951] x5 : 0000000000000000 x4 : 0000000000000000 [ 0.836957] x3 : ffffffffffffffff x2 : 00008000761c3000 [ 0.836962] x1 : ffff80007c0422a0 x0 : ffff80007bd84200 [ 0.836969] ---[ end trace 1c2e8db5a1ad158a ]--- [ 0.836973] Call trace: [ 0.836978] Exception stack(0xffff80007c183a80 to 0xffff80007c183bb0) [ 0.836984] 3a80: ffff80007c042010 0001000000000000 ffff80007c183c50 ffff000008553714 [ 0.836990] 3aa0: ffff80007cbd7d20 0000000000000000 ffff80007c183c40 0000000000000000 [ 0.836995] 3ac0: ffff80007c183c50 ffff80007c183c50 ffff80007c183c10 00000000ffffffc8 [ 0.837000] 3ae0: ffff80007c183b10 ffff00000810fcc8 ffff80007c183c50 ffff80007c183c50 [ 0.837006] 3b00: ffff80007c183c10 00000000ffffffc8 ffff80007c183bc0 ffff000008176958 [ 0.837011] 3b20: ffff80007bd84200 ffff80007c0422a0 00008000761c3000 ffffffffffffffff [ 0.837016] 3b40: 0000000000000000 0000000000000000 0000000055e669a8 0000000000000000 [ 0.837022] 3b60: ffff000008f26330 3a65626f72705f79 676e69626f727020 2072657669726420 [ 0.837027] 3b80: 612d6930356e7573 74636e69702d3436 2068746977206c72 000000009add3b90 [ 0.837031] 3ba0: 00000000553657ab 00000000b8b143dc [ 0.837038] [<ffff000008553714>] driver_probe_device+0x2ac/0x2e8 [ 0.837044] [<ffff00000855389c>] __device_attach_driver+0x9c/0xf8 [ 0.837050] [<ffff0000085518c0>] bus_for_each_drv+0x58/0x98 [ 0.837056] [<ffff000008553344>] __device_attach+0xc4/0x138 [ 0.837062] [<ffff000008553948>] device_initial_probe+0x10/0x18 [ 0.837068] [<ffff0000085528b4>] bus_probe_device+0x94/0xa0 [ 0.837075] [<ffff000008552d34>] deferred_probe_work_func+0x74/0xa8 [ 0.837085] [<ffff0000080d62a0>] process_one_work+0x1d0/0x378 [ 0.837091] [<ffff0000080d668c>] worker_thread+0x244/0x488 [ 0.837098] [<ffff0000080dc750>] kthread+0xf0/0x120 [ 0.837105] [<ffff000008082ec0>] ret_from_fork+0x10/0x50 [ 0.837123] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bddf700 devm_kzalloc_release (40 bytes) [ 0.837155] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES REM ffff80007bddf700 devm_kzalloc_release (40 bytes) [ 0.837201] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bfddb00 devm_kzalloc_release (112 bytes) [ 0.837213] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c080 devm_region_release (24 bytes) [ 0.837231] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c180 devm_ioremap_release (8 bytes) [ 0.837238] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b85c400 devm_kzalloc_release (384 bytes) [ 0.837246] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b8a9000 devm_kzalloc_release (1648 bytes) [ 0.837262] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bf9e000 devm_kzalloc_release (2472 bytes) [ 0.837420] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bc9f800 devm_kzalloc_release (824 bytes) [ 0.837426] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bc9fc00 devm_kzalloc_release (824 bytes) [ 0.837433] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c300 devm_kzalloc_release (32 bytes) [ 0.837439] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c380 devm_kzalloc_release (64 bytes) [ 0.837446] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b85c600 devm_kzalloc_release (288 bytes) [ 0.837453] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c400 devm_kzalloc_release (56 bytes) [ 0.837462] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c480 devm_kzalloc_release (40 bytes) [ 0.837470] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c500 devm_kzalloc_release (32 bytes) [ 0.837482] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c580 devm_kzalloc_release (32 bytes) [ 0.837491] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bfddc00 devm_kzalloc_release (136 bytes) [ 0.837497] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c600 devm_kzalloc_release (32 bytes) [ 0.837505] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bfddd00 devm_kzalloc_release (96 bytes) [ 0.837532] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bfdde00 devm_kzalloc_release (176 bytes) [ 0.837539] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c680 devm_kzalloc_release (48 bytes) [ 0.837546] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c700 devm_kzalloc_release (32 bytes) [ 0.837552] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bfddf00 devm_kzalloc_release (96 bytes) [ 0.837563] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c780 devm_kzalloc_release (32 bytes) [ 0.837584] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c24e500 devm_kzalloc_release (128 bytes) [ 0.837599] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c800 devm_kzalloc_release (80 bytes) [ 0.837630] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c880 devm_kzalloc_release (8 bytes) [ 0.837641] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c24e400 devm_kzalloc_release (112 bytes) [ 0.837648] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007c24e300 devm_kzalloc_release (96 bytes) [ 0.837682] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c900 devm_kzalloc_release (8 bytes) [ 0.837690] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87c980 devm_kzalloc_release (16 bytes) [ 0.837700] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87ca00 devm_kzalloc_release (48 bytes) [ 0.837718] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87ca80 devm_kzalloc_release (48 bytes) [ 0.837736] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cb00 devm_kzalloc_release (32 bytes) [ 0.837751] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cb80 devm_kzalloc_release (32 bytes) [ 0.837758] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cc00 devm_kzalloc_release (32 bytes) [ 0.837776] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cc80 devm_kzalloc_release (16 bytes) [ 0.837786] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cd00 devm_kzalloc_release (16 bytes) [ 0.837804] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cd80 devm_kzalloc_release (8 bytes) [ 0.837813] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87ce00 devm_kzalloc_release (16 bytes) [ 0.837824] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bf99000 devm_kzalloc_release (2472 bytes) [ 0.837833] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87ce80 devm_kzalloc_release (80 bytes) [ 0.837974] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b87cf00 devm_pinctrl_dev_release (8 bytes) [ 0.837981] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b85c800 devm_kzalloc_release (312 bytes) [ 0.838311] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b883980 devm_clk_release (8 bytes) [ 0.838326] sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007b883a80 devm_kzalloc_release (12 bytes) [ 0.840655] sun50i-a64-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver [ 0.841116] bus: 'platform': really_probe: probing driver dw-apb-uart with device 1c28000.serial [ 0.841125] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007b8c1b00 devm_kzalloc_release (40 bytes) [ 0.841174] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007b8c1b80 devm_pinctrl_release (8 bytes) [ 0.841240] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007b8c1f80 devm_ioremap_release (8 bytes) [ 0.841247] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007b854c00 devm_kzalloc_release (288 bytes) [ 0.841273] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007b8c4100 devm_clk_release (8 bytes) [ 0.841292] dw-apb-uart 1c28000.serial: DEVRES ADD ffff80007b8c4200 devm_reset_control_release (8 bytes) [ 0.841624] console [ttyS0] disabled [ 3.269066] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 12, base_baud = 1500000) is a U6_16550A [ 3.278056] console [ttyS0] enabled [ 3.285028] bootconsole [uart0] disabled [ 3.293568] bus: 'platform': really_probe: probing driver dw-apb-uart with device 1c28400.serial [ 3.302364] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007b8c4800 devm_kzalloc_release (40 bytes) [ 3.311526] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007b8c4900 devm_pinctrl_release (8 bytes) [ 3.320565] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007b8c4f00 devm_ioremap_release (8 bytes) [ 3.329528] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007b854e00 devm_kzalloc_release (288 bytes) [ 3.338681] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007b8c4380 devm_clk_release (8 bytes) [ 3.347308] dw-apb-uart 1c28400.serial: DEVRES ADD ffff80007bddf780 devm_reset_control_release (8 bytes) [ 3.377122] 1c28400.serial: ttyS1 at MMIO 0x1c28400 (irq = 13, base_baud = 1500000) is a U6_16550A [ 3.386869] bus: 'platform': really_probe: probing driver mv64xxx_i2c with device 1c2b000.i2c [ 3.395401] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007bddfa80 devm_kzalloc_release (40 bytes) [ 3.404280] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007bddfe80 devm_pinctrl_release (8 bytes) [ 3.413017] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007b8a9800 devm_kzalloc_release (1184 bytes) [ 3.421983] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007b8c5500 devm_region_release (24 bytes) [ 3.430687] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007b8c5600 devm_ioremap_release (8 bytes) [ 3.439399] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007b8c5780 devm_clk_release (8 bytes) [ 3.447780] mv64xxx_i2c 1c2b000.i2c: DEVRES ADD ffff80007b8c5880 devm_reset_control_release (8 bytes) [ 3.458020] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c0f000.mmc [ 3.466381] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c5f80 devm_kzalloc_release (40 bytes) [ 3.475140] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c5f00 devm_pinctrl_release (8 bytes) [ 3.483728] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c7980 devm_kzalloc_release (70 bytes) [ 3.492444] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c7a00 devm_regulator_release (8 bytes) [ 3.501160] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c7c00 devm_region_release (24 bytes) [ 3.509692] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c7d00 devm_ioremap_release (8 bytes) [ 3.518230] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c7e80 devm_clk_release (8 bytes) [ 3.526424] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c7f80 devm_clk_release (8 bytes) [ 3.534613] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c8080 devm_reset_control_release (8 bytes) [ 3.543814] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c8180 devm_irq_release (16 bytes) [ 3.552254] sunxi-mmc 1c0f000.mmc: DEVRES ADD ffff80007b8c8280 devm_gpiod_release (8 bytes) [ 3.560607] sunxi-mmc 1c0f000.mmc: Got CD GPIO [ 3.604440] sunxi-mmc 1c0f000.mmc: base:0xffff000009309000 irq:6 [ 3.611197] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c10000.mmc [ 3.619556] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c8700 devm_kzalloc_release (40 bytes) [ 3.628324] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c8780 devm_pinctrl_release (8 bytes) [ 3.636922] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9100 devm_kzalloc_release (70 bytes) [ 3.645589] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9180 devm_regulator_release (8 bytes) [ 3.654300] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9380 devm_region_release (24 bytes) [ 3.662830] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9480 devm_ioremap_release (8 bytes) [ 3.671364] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9600 devm_clk_release (8 bytes) [ 3.679547] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9700 devm_clk_release (8 bytes) [ 3.687729] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9800 devm_reset_control_release (8 bytes) [ 3.696906] sunxi-mmc 1c10000.mmc: DEVRES ADD ffff80007b8c9900 devm_irq_release (16 bytes) [ 3.744444] sunxi-mmc 1c10000.mmc: base:0xffff00000930d000 irq:7 [ 3.751220] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c11000.mmc [ 3.759596] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8c9d80 devm_kzalloc_release (40 bytes) [ 3.768360] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8c9e00 devm_pinctrl_release (8 bytes) [ 3.776970] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cbd80 devm_kzalloc_release (70 bytes) [ 3.785648] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cbe00 devm_regulator_release (8 bytes) [ 3.794380] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cc080 devm_region_release (24 bytes) [ 3.802923] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cc180 devm_ioremap_release (8 bytes) [ 3.811468] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cc300 devm_clk_release (8 bytes) [ 3.819666] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cc400 devm_clk_release (8 bytes) [ 3.827864] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cc500 devm_reset_control_release (8 bytes) [ 3.837045] mmc0: new high speed SDHC card at address 0007 [ 3.842663] bus: 'mmc': really_probe: probing driver mmcblk with device mmc0:0007 [ 3.850184] mmcblk mmc0:0007: DEVRES ADD ffff80007b8cc900 devm_kzalloc_release (40 bytes) [ 3.858422] sunxi-mmc 1c11000.mmc: DEVRES ADD ffff80007b8cc700 devm_irq_release (16 bytes) [ 3.866917] mmcblk mmc0:0007: DEVRES ADD ffff80007b8ccc80 devm_pinctrl_release (8 bytes) [ 3.875017] mmcblk mmc0:0007: DEVRES REM ffff80007b8ccc80 devm_pinctrl_release (8 bytes) [ 3.883111] mmcblk mmc0:0007: DEVRES REM ffff80007b8cc900 devm_kzalloc_release (40 bytes) [ 3.891637] mmcblk0: mmc0:0007 SD16G 14.5 GiB [ 3.897519] mmcblk0: p1 p2 [ 3.905764] sunxi-mmc 1c10000.mmc: smc 1 err, cmd 8, RTO !! [ 3.912437] sunxi-mmc 1c11000.mmc: base:0xffff000009311000 irq:8 [ 3.919264] bus: 'platform': really_probe: probing driver scpi_protocol with device scpi [ 3.927383] scpi_protocol scpi: DEVRES ADD ffff80007b8ce300 devm_kzalloc_release (40 bytes) [ 3.935756] scpi_protocol scpi: DEVRES REM ffff80007b8ce300 devm_kzalloc_release (40 bytes) [ 3.944144] scpi_protocol scpi: DEVRES ADD ffff80007bd36300 devm_kzalloc_release (120 bytes) [ 3.952604] scpi_protocol scpi: DEVRES ADD ffff80007bd36400 devm_kzalloc_release (168 bytes) [ 3.954194] sunxi-mmc 1c10000.mmc: smc 1 err, cmd 55, RTO !! [ 3.966734] scpi_protocol scpi: DEVRES ADD ffff80007b8ce300 devm_ioremap_release (8 bytes) [ 3.968304] sunxi-mmc 1c10000.mmc: smc 1 err, cmd 55, RTO !! [ 3.980655] scpi_protocol scpi: DEVRES ADD ffff80007b87bc00 devm_kzalloc_release (880 bytes) [ 3.982253] sunxi-mmc 1c10000.mmc: smc 1 err, cmd 55, RTO !! [ 3.994759] scpi_protocol scpi: DEVRES REL ffff80007b87bc00 devm_kzalloc_release (880 bytes) [ 3.996353] sunxi-mmc 1c10000.mmc: smc 1 err, cmd 55, RTO !! [ 4.004862] sunxi-mmc 1c10000.mmc: smc 1 err, cmd 1, RTO !! [ 4.015527] scpi_protocol scpi: DEVRES REL ffff80007b8ce300 devm_ioremap_release (8 bytes) [ 4.023794] scpi_protocol scpi: DEVRES REL ffff80007bd36400 devm_kzalloc_release (168 bytes) [ 4.032229] scpi_protocol scpi: DEVRES REL ffff80007bd36300 devm_kzalloc_release (120 bytes) [ 4.040940] sun6i-rtc 1f00000.rtc: setting system clock to 1970-01-01 00:44:58 UTC (2698) [ 4.049320] ALSA device list: [ 4.049710] sunxi-mmc 1c11000.mmc: smc 2 err, cmd 8, RTO !! [ 4.057774] sunxi-mmc 1c11000.mmc: smc 2 err, cmd 55, RTO !! [ 4.059394] sunxi-mmc 1c11000.mmc: smc 2 err, cmd 55, RTO !! [ 4.061014] sunxi-mmc 1c11000.mmc: smc 2 err, cmd 55, RTO !! [ 4.062633] sunxi-mmc 1c11000.mmc: smc 2 err, cmd 55, RTO !! [ 4.080477] No soundcards found. [ 4.085094] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities [ 4.110882] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 4.112452] mmc2: new high speed MMC card at address 0001 [ 4.112642] bus: 'mmc': really_probe: probing driver mmcblk with device mmc2:0001 [ 4.112656] mmcblk mmc2:0001: DEVRES ADD ffff80007b8d6080 devm_kzalloc_release (40 bytes) [ 4.112676] mmcblk mmc2:0001: DEVRES ADD ffff80007b8d6180 devm_pinctrl_release (8 bytes) [ 4.112685] mmcblk mmc2:0001: DEVRES REM ffff80007b8d6180 devm_pinctrl_release (8 bytes) [ 4.112694] mmcblk mmc2:0001: DEVRES REM ffff80007b8d6080 devm_kzalloc_release (40 bytes) [ 4.112940] mmcblk2: mmc2:0001 8WPD3R 7.28 GiB [ 4.117125] mmcblk2boot0: mmc2:0001 8WPD3R partition 1 4.00 MiB [ 4.117312] mmcblk2boot1: mmc2:0001 8WPD3R partition 2 4.00 MiB [ 4.119934] mmcblk2: p1 p2 p3 < p5 p6 p7 > [ 4.126279] bus: 'platform': really_probe: probing driver scpi_protocol with device scpi [ 4.126294] scpi_protocol scpi: DEVRES ADD ffff80007b876980 devm_kzalloc_release (40 bytes) [ 4.126312] scpi_protocol scpi: DEVRES REM ffff80007b876980 devm_kzalloc_release (40 bytes) [ 4.126343] scpi_protocol scpi: DEVRES ADD ffff80007bd4d400 devm_kzalloc_release (120 bytes) [ 4.126358] scpi_protocol scpi: DEVRES ADD ffff80007bd4d300 devm_kzalloc_release (168 bytes) [ 4.126395] scpi_protocol scpi: DEVRES ADD ffff80007b876a80 devm_ioremap_release (8 bytes) [ 4.126402] scpi_protocol scpi: DEVRES ADD ffff80007b91e000 devm_kzalloc_release (880 bytes) [ 4.126415] scpi_protocol scpi: DEVRES REL ffff80007b91e000 devm_kzalloc_release (880 bytes) [ 4.126422] scpi_protocol scpi: DEVRES REL ffff80007b876a80 devm_ioremap_release (8 bytes) [ 4.126432] scpi_protocol scpi: DEVRES REL ffff80007bd4d300 devm_kzalloc_release (168 bytes) [ 4.126438] scpi_protocol scpi: DEVRES REL ffff80007bd4d400 devm_kzalloc_release (120 bytes) [ 4.276860] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 4.285083] devtmpfs: mounted [ 4.288791] Freeing unused kernel memory: 1088K [ 4.557432] random: fast init done [ 4.783266] systemd[1]: System time before build time, advancing clock. [ 4.870648] systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN) [ 4.889067] systemd[1]: Detected architecture arm64. [ 4.909627] systemd[1]: Set hostname to <localhost.localdomain>. [ 5.414653] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.432558] systemd[1]: Reached target Encrypted Volumes. [ 5.448498] systemd[1]: Reached target Remote File Systems (Pre). [ 5.466221] systemd[1]: Created slice System Slice. [ 5.485691] systemd[1]: Created slice system-getty.slice. [ 5.505645] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 5.524732] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 6.329806] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) [ 6.451616] systemd-journald[1148]: Received request to flush runtime journal from PID 1 [ 7.078472] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 16.562101] random: crng init done ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-02 23:48 ` André Przywara @ 2017-04-18 7:25 ` Tejun Heo 2017-04-18 9:12 ` Andre Przywara 2017-04-18 10:51 ` Icenowy Zheng 0 siblings, 2 replies; 12+ messages in thread From: Tejun Heo @ 2017-04-18 7:25 UTC (permalink / raw) To: André Przywara Cc: Icenowy Zheng, Greg Kroah-Hartman, Adam Borowski, linux-kernel Hello, On Mon, Apr 03, 2017 at 12:48:16AM +0100, André Przywara wrote: > So I see this problem easily now - on every boot - with an unpatched > 4.11-rc3 kernel and the (arm64) defconfig on a Pine64 or BananaPi M64. > I enabled devres.log and see that pinctrl probes early, but apparently > gets deferred, pretty late actually (after 43 ADDs). > > Now what sticks out from the sequence (see the attached log) is that > there are two un-matches ADDs with a devm_kmalloc of size 0: > sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84200 > devm_kzalloc_release (0 bytes) > sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84100 > devm_kzalloc_release (0 bytes) > > While all the other ADDs have a matching REL, those two have not. I > guess it's due to the size being 0. Does that ring a bell? AFAICS, 0 size allocs should be fine. > Or is due to the fact that these two ADDs are after the RELs have > already started, so at a point where the driver is already cleaned up? But this sounds problematic to me. So, these zero length allocations are happening after release of the device is initiated? Where are they coming from? Thanks. -- tejun ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-18 7:25 ` Tejun Heo @ 2017-04-18 9:12 ` Andre Przywara 2017-04-28 22:03 ` Tejun Heo 2017-04-18 10:51 ` Icenowy Zheng 1 sibling, 1 reply; 12+ messages in thread From: Andre Przywara @ 2017-04-18 9:12 UTC (permalink / raw) To: Tejun Heo; +Cc: Icenowy Zheng, Greg Kroah-Hartman, Adam Borowski, linux-kernel Hi, On 18/04/17 08:25, Tejun Heo wrote: > Hello, > > On Mon, Apr 03, 2017 at 12:48:16AM +0100, André Przywara wrote: >> So I see this problem easily now - on every boot - with an unpatched >> 4.11-rc3 kernel and the (arm64) defconfig on a Pine64 or BananaPi M64. >> I enabled devres.log and see that pinctrl probes early, but apparently >> gets deferred, pretty late actually (after 43 ADDs). >> >> Now what sticks out from the sequence (see the attached log) is that >> there are two un-matches ADDs with a devm_kmalloc of size 0: >> sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84200 >> devm_kzalloc_release (0 bytes) >> sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84100 >> devm_kzalloc_release (0 bytes) >> >> While all the other ADDs have a matching REL, those two have not. I >> guess it's due to the size being 0. Does that ring a bell? > > AFAICS, 0 size allocs should be fine. > >> Or is due to the fact that these two ADDs are after the RELs have >> already started, so at a point where the driver is already cleaned up? > > But this sounds problematic to me. So, these zero length allocations > are happening after release of the device is initiated? Where are > they coming from? Yeah, so I stack-dumped on the zero allocations and indeed they are called from cleanup functions: drivers/pinctrl/pinmux.c:pinmux_generic_free_functions(): devm_kzalloc(sizeof(*indices) * pctldev->num_functions, ...) (and another one I don't know from the top of the my head, logs at home) So my hunch was that once EPROBE_DEFER triggers the devres cleanup, it uses some reverse list traversal to release all allocated resources (backwards!), so missing those which get (appended) during the process. But I don't think that would not work with the locking. So I have to dig deeper tonight in my logs. Just curious why this does (or did) not happen for other users, because the affected code paths seem rather generic to me. Is that due to probing order being unfortunate in our case and the pinctrl driver is never released due to DEFER anywhere else? Cheers, Andre. ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-18 9:12 ` Andre Przywara @ 2017-04-28 22:03 ` Tejun Heo 2017-04-29 21:28 ` Adam Borowski 0 siblings, 1 reply; 12+ messages in thread From: Tejun Heo @ 2017-04-28 22:03 UTC (permalink / raw) To: Andre Przywara Cc: Icenowy Zheng, Greg Kroah-Hartman, Adam Borowski, linux-kernel Hello, On Tue, Apr 18, 2017 at 10:12:16AM +0100, Andre Przywara wrote: > Yeah, so I stack-dumped on the zero allocations and indeed they are > called from cleanup functions: > drivers/pinctrl/pinmux.c:pinmux_generic_free_functions(): > devm_kzalloc(sizeof(*indices) * pctldev->num_functions, ...) > (and another one I don't know from the top of the my head, logs at home) > > So my hunch was that once EPROBE_DEFER triggers the devres cleanup, it > uses some reverse list traversal to release all allocated resources > (backwards!), so missing those which get (appended) during the process. > But I don't think that would not work with the locking. > So I have to dig deeper tonight in my logs. If this is a valid use case, we can change devm to repeat till empty but it's a weird thing to do to allocate from a release function. So, something like this. Only compile tested. Thanks. diff --git a/drivers/base/devres.c b/drivers/base/devres.c index 71d5770..d2a9f34 100644 --- a/drivers/base/devres.c +++ b/drivers/base/devres.c @@ -509,13 +509,21 @@ static int release_nodes(struct device *dev, struct list_head *first, int devres_release_all(struct device *dev) { unsigned long flags; + int cnt = 0, ret; /* Looks like an uninitialized device structure */ if (WARN_ON(dev->devres_head.next == NULL)) return -ENODEV; - spin_lock_irqsave(&dev->devres_lock, flags); - return release_nodes(dev, dev->devres_head.next, &dev->devres_head, - flags); + + /* Release callbacks may create new nodes, repeat till empty */ + do { + spin_lock_irqsave(&dev->devres_lock, flags); + ret = release_nodes(dev, dev->devres_head.next, + &dev->devres_head, flags); + cnt += ret; + } while (ret); + + return cnt; } /** ^ permalink raw reply related [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-28 22:03 ` Tejun Heo @ 2017-04-29 21:28 ` Adam Borowski 2017-04-29 23:34 ` André Przywara 0 siblings, 1 reply; 12+ messages in thread From: Adam Borowski @ 2017-04-29 21:28 UTC (permalink / raw) To: Tejun Heo; +Cc: Andre Przywara, Icenowy Zheng, Greg Kroah-Hartman, linux-kernel [-- Attachment #1: Type: text/plain, Size: 5028 bytes --] On Fri, Apr 28, 2017 at 06:03:14PM -0400, Tejun Heo wrote: > On Tue, Apr 18, 2017 at 10:12:16AM +0100, Andre Przywara wrote: > > Yeah, so I stack-dumped on the zero allocations and indeed they are > > called from cleanup functions: > > drivers/pinctrl/pinmux.c:pinmux_generic_free_functions(): > > devm_kzalloc(sizeof(*indices) * pctldev->num_functions, ...) > > (and another one I don't know from the top of the my head, logs at home) > > > > So my hunch was that once EPROBE_DEFER triggers the devres cleanup, it > > uses some reverse list traversal to release all allocated resources > > (backwards!), so missing those which get (appended) during the process. > > But I don't think that would not work with the locking. > > So I have to dig deeper tonight in my logs. > > If this is a valid use case, we can change devm to repeat till empty > but it's a weird thing to do to allocate from a release function. > > So, something like this. Only compile tested. I've tested this, and the results are mixed. It _does_ make the WARN go away, but on the other hand, much later during the boot, another driver (sun8i-emac) gets deferred and never undefers. Without the patch, there's: [ ok ] Synthesizing the initial hotplug events...done. [ 6.666984] bus: 'platform': driver_probe_device: matched device 1c30000.ethernet with driver sun8i-emac [ 6.676520] bus: 'platform': really_probe: probing driver sun8i-emac with device 1c30000.ethernet [ 6.686479] driver: 'sun8i-emac': driver_bound: bound to device '1c30000.ethernet' [....] [ 6.694132] bus: 'platform': really_probe: bound device 1c30000.ethernet to driver sun8i-emac [ ok ng for /dev to be fully populated...done. <snip> [....] Configuring network interfaces...[ 11.414884] libphy: 1c30000.ethernet: probed [ 11.449742] bus: 'mdio_bus': driver_probe_device: matched device 1c30000.ethernet-0:00 with driver RTL8211E Gigabit Ethernet [ 11.461013] bus: 'mdio_bus': really_probe: probing driver RTL8211E Gigabit Ethernet with device 1c30000.ethernet-0:00 [ 11.471666] driver: 'RTL8211E Gigabit Ethernet': driver_bound: bound to device '1c30000.ethernet-0:00' [ 11.481007] bus: 'mdio_bus': really_probe: bound device 1c30000.ethernet-0:00 to driver RTL8211E Gigabit Ethernet [ 11.491527] RTL8211E Gigabit Ethernet 1c30000.ethernet-0:00: attached PHY driver [RTL8211E Gigabit Ethernet] (mii_bus:phy_addr=1c30000.ethernet-0:00, irq=-1) [ 11.505608] sun8i-emac 1c30000.ethernet: device MAC address slot 0 02:ba:8f:9e:44:8f With the patch: [ ok ] Synthesizing the initial hotplug events...done. [....] Waiting for /dev to be fully populated...[ 6.325314] bus: 'platform': driver_probe_device: matched device 1c30000.ethernet with driver sun8i-emac [ 6.334842] bus: 'platform': really_probe: probing driver sun8i-emac with device 1c30000.ethernet [ 6.344253] platform 1c30000.ethernet: Driver sun8i-emac requests probe deferral [ 6.351662] platform 1c30000.ethernet: Added to deferred list done. <snip> [....] Configuring network interfaces...Cannot find device "eth0" ifup: failed to bring up eth0 failed. There's a concern, though, that this second driver is not in mainline (and won't ever be -- it's being replaced by dwmac-sun8i, but the latter is not yet up to scratch), thus I'm not sure how relevant this is. The second concern is that I'm using the same tree (icenowy/sunxi64-4.11-rc7) and config (plus filesystems) as Icenowy, on the same hardware, yet I did see this warning in versions much earlier than her, and it doesn't go away in -next for me either. All that differs are toolchain versions (I'm on Debian unstable) and perhaps some u-boot bits. Because of the amount of churn in sunxi land and the driver's mainlining status, I think it'd be good to wait after the merge window that starts tomorrow, and re-test then. Full console output with the patch applied attached. Meow! > diff --git a/drivers/base/devres.c b/drivers/base/devres.c > index 71d5770..d2a9f34 100644 > --- a/drivers/base/devres.c > +++ b/drivers/base/devres.c > @@ -509,13 +509,21 @@ static int release_nodes(struct device *dev, struct list_head *first, > int devres_release_all(struct device *dev) > { > unsigned long flags; > + int cnt = 0, ret; > > /* Looks like an uninitialized device structure */ > if (WARN_ON(dev->devres_head.next == NULL)) > return -ENODEV; > - spin_lock_irqsave(&dev->devres_lock, flags); > - return release_nodes(dev, dev->devres_head.next, &dev->devres_head, > - flags); > + > + /* Release callbacks may create new nodes, repeat till empty */ > + do { > + spin_lock_irqsave(&dev->devres_lock, flags); > + ret = release_nodes(dev, dev->devres_head.next, > + &dev->devres_head, flags); > + cnt += ret; > + } while (ret); > + > + return cnt; > } > > /** > -- Don't be racist. White, amber or black, all beers should be judged based solely on their merits. Heck, even if occasionally a cider applies for a beer's job, why not? On the other hand, corpo lager is not a race. [-- Attachment #2: with-patch --] [-- Type: text/plain, Size: 35698 bytes --] INFO: PSCI Affinity Map: INFO: AffInst: Level 0, MPID 0x0, State ON INFO: AffInst: Level 0, MPID 0x1, State ON INFO: AffInst: Level 0, MPID 0x2, State ON INFO: AffInst: Level 0, MPID 0x3, State ON HELLO! BOOT0 is starting! boot0 commit : 045061a8bb2580cb3fa02e301f52a015040c158f boot0 version : 4.0.0 set pll start set pll end rtc[0] value = 0x00000000 rtc[1] value = 0x00000000 rtc[2] value = 0x00000000 rtc[3] value = 0x00000000 rtc[4] value = 0x00000000 rtc[5] value = 0x00000000 DRAM driver version: V1.1 rsb_send_initseq: rsb clk 400Khz -> 3Mhz PMU: AXP81X ddr voltage = 1500 mv DRAM Type = 3 (2:DDR2,3:DDR3,6:LPDDR2,7:LPDDR3) DRAM clk = 672 MHz DRAM zq value: 003b3bbb DRAM single rank full DQ OK DRAM size = 2048 MB DRAM init ok dram size =2048 card boot number = 0, boot0 copy = 0 card no is 0 sdcard 0 line count 4 [mmc]: mmc driver ver 2015-05-08 20:06 [mmc]: sdc0 spd mode error, 2 [mmc]: Wrong media type 0x00000000 [mmc]: ***Try SD card 0*** [mmc]: HSSDR52/SDR25 4 bit [mmc]: 50000000 Hz [mmc]: 14804 MB [mmc]: ***SD/MMC 0 init OK!!!*** sdcard 0 init ok The size of uboot is 0006c000. sum=19037d2d src_sum=19037d2d Succeed in loading uboot from sdmmc flash. boot0: start load other image boot0: Loading BL3-1 Loading file 0 at address 0x40000000,size 0x00000200 success boot0: Loading scp Loading file 2 at address 0x00040000,size 0x0000b200 success set arisc reset to de-assert state Ready to disable icache. Jump to secend Boot. NOTICE: BL3-1: Running on A64/H64 (1689) in SRAM A2 (@0x44000) NOTICE: Configuring SPC Controller NOTICE: BL3-1: v1.0(debug):aa75c8da NOTICE: BL3-1: Built : 01:57:26, Mar 21 2017 NOTICE: Configuring AXP PMIC NOTICE: PMIC: already configured for RSB NOTICE: PMIC: setup successful INFO: BL3-1: Initializing runtime services INFO: BL3-1: Preparing for EL3 exit to normal world INFO: BL3-1: Next image address: 0x4a000000, SPSR: 0x3c9 U-Boot 2017.03-00203-gd5abcf94c7 (Mar 22 2017 - 00:45:58 +0100) Allwinner Technology CPU: Allwinner A64 (SUN50I) Model: Pine64+ DRAM: 2 GiB MMC: SUNXI SD/MMC: 0 *** Warning - bad CRC, using default environment In: serial Out: serial Err: serial Net: phy interface7 eth0: ethernet@01c30000 starting USB... USB0: USB EHCI 1.00 USB1: USB OHCI 1.0 scanning bus 0 for devices... 1 USB Device(s) found scanning usb for storage devices... 0 Storage Device(s) found Hit any key to stop autoboot: 0 switch to partitions #0, OK mmc0 is current device Scanning mmc 0:1... Found U-Boot script /boot.scr.uimg 295 bytes read in 40 ms (6.8 KiB/s) ## Executing script at 4fc00000 13224448 bytes read in 57346 ms (224.6 KiB/s) 11552 bytes read in 277 ms (40 KiB/s) ## Flattened Device Tree blob at 4fa00000 Booting using the fdt blob at 0x4fa00000 Loading Device Tree to 0000000049ffa000, end 0000000049fffd1f ... OK Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.11.0-rc7-iceconfig-00064-gf4aec3d9ff13 (kilobyte@sirius) (gcc version 6.3.0 20170415 (Debian 6.3.0-14) ) #1 SMP PREEMPT Sat Apr 29 01:29:37 CEST 2017 [ 0.000000] Boot CPU: AArch64 Processor [410fd034] [ 0.000000] earlycon: uart0 at MMIO32 0x0000000001c28000 (options '') [ 0.000000] bootconsole [uart0] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] On node 0 totalpages: 524288 [ 0.000000] DMA zone: 8192 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 524288 pages, LIFO batch:31 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv0.2 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] percpu: Embedded 23 pages/cpu @ffff80007ef86000 s56472 r8192 d29544 u94208 [ 0.000000] pcpu-alloc: s56472 r8192 d29544 u94208 alloc=23*4096 [ 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] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 516096 [ 0.000000] Kernel command line: console=ttyS0,115200n8 ro root=/dev/mmcblk0p5 rootwait earlycon=uart,mmio32,0x01c28000 loglevel=8 [ 0.000000] log_buf_len individual max cpu contribution: 4096 bytes [ 0.000000] log_buf_len total cpu_extra contributions: 12288 bytes [ 0.000000] log_buf_len min size: 16384 bytes [ 0.000000] log_buf_len: 32768 bytes [ 0.000000] early log buf free: 14636(89%) [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.000000] Memory: 2031132K/2097152K available (8764K kernel code, 562K rwdata, 2968K rodata, 576K init, 298K bss, 49636K reserved, 16384K cma-reserved) [ 0.000000] Virtual kernel memory layout: [ 0.000000] modules : 0xffff000000000000 - 0xffff000008000000 ( 128 MB) [ 0.000000] vmalloc : 0xffff000008000000 - 0xffff7dffbfff0000 (129022 GB) [ 0.000000] .text : 0xffff000008080000 - 0xffff000008910000 ( 8768 KB) [ 0.000000] .rodata : 0xffff000008910000 - 0xffff000008c00000 ( 3008 KB) [ 0.000000] .init : 0xffff000008c00000 - 0xffff000008c90000 ( 576 KB) [ 0.000000] .data : 0xffff000008c90000 - 0xffff000008d1ca00 ( 563 KB) [ 0.000000] .bss : 0xffff000008d1ca00 - 0xffff000008d672cc ( 299 KB) [ 0.000000] fixed : 0xffff7dfffe7fd000 - 0xffff7dfffec00000 ( 4108 KB) [ 0.000000] PCI I/O : 0xffff7dfffee00000 - 0xffff7dffffe00000 ( 16 MB) [ 0.000000] vmemmap : 0xffff7e0000000000 - 0xffff800000000000 ( 2048 GB maximum) [ 0.000000] 0xffff7e0000000000 - 0xffff7e0002000000 ( 32 MB actual) [ 0.000000] memory : 0xffff800000000000 - 0xffff800080000000 ( 2048 MB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 [ 0.000000] NR_IRQS:64 nr_irqs:64 0 [ 0.000000] GIC: Using split EOI/Deactivate mode [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000004] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.008247] Console: colour dummy device 80x25 [ 0.012708] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) [ 0.022963] pid_max: default: 32768 minimum: 301 [ 0.027649] Security Framework initialized [ 0.031784] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.038482] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.061690] ASID allocator initialised with 65536 entries [ 0.075226] EFI services will not be available. [ 0.095802] smp: Bringing up secondary CPUs ... [ 0.133579] Detected VIPT I-cache on CPU1 [ 0.133631] CPU1: Booted secondary processor [410fd034] [ 0.165002] Detected VIPT I-cache on CPU2 [ 0.165034] CPU2: Booted secondary processor [410fd034] [ 0.197048] Detected VIPT I-cache on CPU3 [ 0.197077] CPU3: Booted secondary processor [410fd034] [ 0.197131] smp: Brought up 1 node, 4 CPUs [ 0.228879] SMP: Total of 4 processors activated. [ 0.233583] CPU features: detected feature: 32-bit EL0 Support [ 0.239456] CPU: All CPU(s) started at EL2 [ 0.243567] alternatives: patching kernel code [ 0.249116] devtmpfs: initialized [ 0.254982] DMI not present or invalid. [ 0.259008] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.268752] futex hash table entries: 1024 (order: 5, 131072 bytes) [ 0.275673] xor: measuring software checksum speed [ 0.320517] 8regs : 1566.000 MB/sec [ 0.364744] 8regs_prefetch: 1396.000 MB/sec [ 0.409318] 32regs : 1855.000 MB/sec [ 0.453543] 32regs_prefetch: 1618.000 MB/sec [ 0.458158] xor: using function: 32regs (1855.000 MB/sec) [ 0.463558] pinctrl core: initialized pinctrl subsystem [ 0.469171] bus: 'platform': driver_probe_device: matched device reg-dummy with driver reg-dummy [ 0.477963] bus: 'platform': really_probe: probing driver reg-dummy with device reg-dummy [ 0.486267] driver: 'reg-dummy': driver_bound: bound to device 'reg-dummy' [ 0.493143] bus: 'platform': really_probe: bound device reg-dummy to driver reg-dummy [ 0.501480] NET: Registered protocol family 16 [ 0.506973] cpuidle: using governor menu [ 0.511217] vdso: 2 pages (1 code @ ffff000008917000, 1 data @ ffff000008c95000) [ 0.518621] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.526423] DMA: preallocated 256 KiB pool for atomic allocations [ 0.532658] Serial: AMBA PL011 UART driver [ 0.548501] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.622364] raid6: int64x1 gen() 284 MB/s [ 0.694296] raid6: int64x1 xor() 298 MB/s [ 0.766349] raid6: int64x2 gen() 448 MB/s [ 0.838417] raid6: int64x2 xor() 401 MB/s [ 0.910473] raid6: int64x4 gen() 667 MB/s [ 0.982521] raid6: int64x4 xor() 512 MB/s [ 1.054656] raid6: int64x8 gen() 749 MB/s [ 1.126675] raid6: int64x8 xor() 495 MB/s [ 1.198756] raid6: neonx1 gen() 490 MB/s [ 1.270847] raid6: neonx1 xor() 515 MB/s [ 1.342928] raid6: neonx2 gen() 793 MB/s [ 1.414965] raid6: neonx2 xor() 700 MB/s [ 1.487048] raid6: neonx4 gen() 1023 MB/s [ 1.559109] raid6: neonx4 xor() 802 MB/s [ 1.631189] raid6: neonx8 gen() 1078 MB/s [ 1.703247] raid6: neonx8 xor() 832 MB/s [ 1.707516] raid6: using algorithm neonx8 gen() 1078 MB/s [ 1.712908] raid6: .... xor() 832 MB/s, rmw enabled [ 1.717781] raid6: using intx1 recovery algorithm [ 1.723149] bus: 'platform': driver_probe_device: matched device vcc3v3 with driver reg-fixed-voltage [ 1.732377] bus: 'platform': really_probe: probing driver reg-fixed-voltage with device vcc3v3 [ 1.741186] driver: 'reg-fixed-voltage': driver_bound: bound to device 'vcc3v3' [ 1.748496] bus: 'platform': really_probe: bound device vcc3v3 to driver reg-fixed-voltage [ 1.757221] SCSI subsystem initialized [ 1.761137] libata version 3.00 loaded. [ 1.765275] usbcore: registered new interface driver usbfs [ 1.770810] usbcore: registered new interface driver hub [ 1.776204] usbcore: registered new device driver usb [ 1.781515] pps_core: LinuxPPS API ver. 1 registered [ 1.786485] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 1.795628] PTP clock support registered [ 1.799707] dmi: Firmware registration failed. [ 1.804290] Advanced Linux Sound Architecture Driver Initialized. [ 1.811143] clocksource: Switched to clocksource arch_sys_counter [ 1.817384] VFS: Disk quotas dquot_6.6.0 [ 1.821371] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.835376] NET: Registered protocol family 2 [ 1.840222] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 1.847603] TCP bind hash table entries: 16384 (order: 6, 262144 bytes) [ 1.854544] TCP: Hash tables configured (established 16384 bind 16384) [ 1.861202] UDP hash table entries: 1024 (order: 3, 32768 bytes) [ 1.867263] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) [ 1.873892] NET: Registered protocol family 1 [ 1.878583] RPC: Registered named UNIX socket transport module. [ 1.884512] RPC: Registered udp transport module. [ 1.889228] RPC: Registered tcp transport module. [ 1.893933] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.901079] kvm [1]: 8-bit VMID [ 1.904233] kvm [1]: IDMAP page: 408f7000 [ 1.908246] kvm [1]: HYP VA range: 800000000000:ffffffffffff [ 1.914468] kvm [1]: Hyp mode initialized successfully [ 1.919646] kvm [1]: vgic-v2@1c84000 [ 1.923310] kvm [1]: vgic interrupt IRQ1 [ 1.927250] kvm [1]: virtual timer IRQ5 [ 1.932870] bus: 'platform': driver_probe_device: matched device alarmtimer with driver alarmtimer [ 1.941854] bus: 'platform': really_probe: probing driver alarmtimer with device alarmtimer [ 1.950223] driver: 'alarmtimer': driver_bound: bound to device 'alarmtimer' [ 1.957275] bus: 'platform': really_probe: bound device alarmtimer to driver alarmtimer [ 1.965663] audit: initializing netlink subsys (disabled) [ 1.971197] audit: type=2000 audit(1.896:1): state=initialized audit_enabled=0 res=1 [ 1.971440] workingset: timestamp_bits=46 max_order=19 bucket_order=0 [ 1.978729] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.979461] NFS: Registering the id_resolver key type [ 1.979486] Key type id_resolver registered [ 1.979489] Key type id_legacy registered [ 1.979498] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.979669] SGI XFS with ACLs, security attributes, no debug enabled [ 1.980851] 9p: Installing v9fs 9p2000 file system support [ 1.983779] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.983787] io scheduler noop registered [ 1.983910] io scheduler cfq registered (default) [ 1.983913] io scheduler mq-deadline registered [ 1.985655] bus: 'platform': driver_probe_device: matched device 1c19400.phy with driver sun4i-usb-phy [ 1.985667] bus: 'platform': really_probe: probing driver sun4i-usb-phy with device 1c19400.phy [ 1.985936] sun4i-usb-phy 1c19400.phy: failed to get clock usb0_phy [ 1.986044] platform 1c19400.phy: Driver sun4i-usb-phy requests probe deferral [ 1.986049] platform 1c19400.phy: Added to deferred list [ 1.986350] bus: 'platform': driver_probe_device: matched device 1c20800.pinctrl with driver sun50i-a64-pinctrl [ 1.986358] bus: 'platform': really_probe: probing driver sun50i-a64-pinctrl with device 1c20800.pinctrl [ 1.987654] platform 1c20800.pinctrl: Driver sun50i-a64-pinctrl requests probe deferral [ 1.987659] platform 1c20800.pinctrl: Added to deferred list [ 1.987750] bus: 'platform': driver_probe_device: matched device 1f02c00.pinctrl with driver sun50i-a64-r-pinctrl [ 1.987758] bus: 'platform': really_probe: probing driver sun50i-a64-r-pinctrl with device 1f02c00.pinctrl [ 1.988820] sun50i-a64-r-pinctrl 1f02c00.pinctrl: initialized sunXi PIO driver [ 1.988825] driver: 'sun50i-a64-r-pinctrl': driver_bound: bound to device '1f02c00.pinctrl' [ 1.988831] bus: 'platform': really_probe: bound device 1f02c00.pinctrl to driver sun50i-a64-r-pinctrl [ 1.989831] bus: 'platform': driver_probe_device: matched device 1c20000.clock with driver sun50i-a64-ccu [ 1.989840] bus: 'platform': really_probe: probing driver sun50i-a64-ccu with device 1c20000.clock [ 1.990872] driver: 'sun50i-a64-ccu': driver_bound: bound to device '1c20000.clock' [ 1.990879] bus: 'platform': really_probe: bound device 1c20000.clock to driver sun50i-a64-ccu [ 1.991377] xenfs: not registering filesystem on non-xen platform [ 1.994455] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 1.995214] bus: 'platform': driver_probe_device: matched device serial8250 with driver serial8250 [ 1.995223] bus: 'platform': really_probe: probing driver serial8250 with device serial8250 [ 1.995250] driver: 'serial8250': driver_bound: bound to device 'serial8250' [ 1.995255] bus: 'platform': really_probe: bound device serial8250 to driver serial8250 [ 1.995323] bus: 'platform': driver_probe_device: matched device 1c28000.serial with driver dw-apb-uart [ 1.995329] bus: 'platform': really_probe: probing driver dw-apb-uart with device 1c28000.serial [ 1.995357] dw-apb-uart 1c28000.serial: could not find pctldev for node /soc/pinctrl@1c20800/uart0@0, deferring probe [ 1.995369] platform 1c28000.serial: Driver dw-apb-uart requests probe deferral [ 1.995374] platform 1c28000.serial: Added to deferred list [ 1.995819] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 2.002493] loop: module loaded [ 2.003003] hisi_sas: driver version v1.6 [ 2.004214] libphy: Fixed MDIO Bus: probed [ 2.004669] tun: Universal TUN/TAP device driver, 1.6 [ 2.005467] VFIO - User Level meta-driver version: 0.3 [ 2.005515] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 2.005518] ehci-platform: EHCI generic platform driver [ 2.005554] bus: 'platform': driver_probe_device: matched device 1c1b000.usb with driver ehci-platform [ 2.005564] bus: 'platform': really_probe: probing driver ehci-platform with device 1c1b000.usb [ 2.005673] platform 1c1b000.usb: Driver ehci-platform requests probe deferral [ 2.005678] platform 1c1b000.usb: Added to deferred list [ 2.005727] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 2.005744] ohci-platform: OHCI generic platform driver [ 2.005772] bus: 'platform': driver_probe_device: matched device 1c1b400.usb with driver ohci-platform [ 2.005777] bus: 'platform': really_probe: probing driver ohci-platform with device 1c1b400.usb [ 2.005829] platform 1c1b400.usb: Driver ohci-platform requests probe deferral [ 2.005834] platform 1c1b400.usb: Added to deferred list [ 2.006052] usbcore: registered new interface driver uas [ 2.006101] usbcore: registered new interface driver usb-storage [ 2.006133] usbcore: registered new interface driver ums-alauda [ 2.006168] usbcore: registered new interface driver ums-cypress [ 2.006210] usbcore: registered new interface driver ums-datafab [ 2.006245] usbcore: registered new interface driver ums_eneub6250 [ 2.006286] usbcore: registered new interface driver ums-freecom [ 2.006319] usbcore: registered new interface driver ums-isd200 [ 2.006353] usbcore: registered new interface driver ums-jumpshot [ 2.006391] usbcore: registered new interface driver ums-karma [ 2.006427] usbcore: registered new interface driver ums-onetouch [ 2.006470] usbcore: registered new interface driver ums-realtek [ 2.006506] usbcore: registered new interface driver ums-sddr09 [ 2.006542] usbcore: registered new interface driver ums-sddr55 [ 2.006582] usbcore: registered new interface driver ums-usbat [ 2.006682] usbcore: registered new interface driver usbserial [ 2.006720] usbcore: registered new interface driver ftdi_sio [ 2.006755] usbserial: USB Serial support registered for FTDI USB Serial Device [ 2.006792] usbcore: registered new interface driver pl2303 [ 2.006822] usbserial: USB Serial support registered for pl2303 [ 2.007006] bus: 'platform': driver_probe_device: matched device 1c19000.usb with driver musb-sunxi [ 2.007015] bus: 'platform': really_probe: probing driver musb-sunxi with device 1c19000.usb [ 2.007095] platform 1c19000.usb: Driver musb-sunxi requests probe deferral [ 2.007100] platform 1c19000.usb: Added to deferred list [ 2.008128] mousedev: PS/2 mouse device common for all mice [ 2.008408] usbcore: registered new interface driver synaptics_usb [ 2.008789] bus: 'platform': driver_probe_device: matched device 1f00000.rtc with driver sun6i-rtc [ 2.008798] bus: 'platform': really_probe: probing driver sun6i-rtc with device 1f00000.rtc [ 2.009056] sun6i-rtc 1f00000.rtc: rtc core: registered rtc-sun6i as rtc0 [ 2.009061] sun6i-rtc 1f00000.rtc: RTC enabled [ 2.009065] driver: 'sun6i-rtc': driver_bound: bound to device '1f00000.rtc' [ 2.009071] bus: 'platform': really_probe: bound device 1f00000.rtc to driver sun6i-rtc [ 2.009104] i2c /dev entries driver [ 2.009183] bus: 'platform': driver_probe_device: matched device 1c2b000.i2c with driver mv64xxx_i2c [ 2.009189] bus: 'platform': really_probe: probing driver mv64xxx_i2c with device 1c2b000.i2c [ 2.009208] mv64xxx_i2c 1c2b000.i2c: could not find pctldev for node /soc/pinctrl@1c20800/i2c1_pins, deferring probe [ 2.009219] platform 1c2b000.i2c: Driver mv64xxx_i2c requests probe deferral [ 2.009223] platform 1c2b000.i2c: Added to deferred list [ 2.009947] bus: 'platform': driver_probe_device: matched device wifi_pwrseq with driver pwrseq_simple [ 2.009953] bus: 'platform': really_probe: probing driver pwrseq_simple with device wifi_pwrseq [ 2.010029] driver: 'pwrseq_simple': driver_bound: bound to device 'wifi_pwrseq' [ 2.010034] bus: 'platform': really_probe: bound device wifi_pwrseq to driver pwrseq_simple [ 2.010183] sdhci: Secure Digital Host Controller Interface driver [ 2.010185] sdhci: Copyright(c) Pierre Ossman [ 2.010263] Synopsys Designware Multimedia Card Interface Driver [ 2.010499] bus: 'platform': driver_probe_device: matched device 1c0f000.mmc with driver sunxi-mmc [ 2.010504] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c0f000.mmc [ 2.010522] sunxi-mmc 1c0f000.mmc: could not find pctldev for node /soc/pinctrl@1c20800/mmc0-pins, deferring probe [ 2.010533] platform 1c0f000.mmc: Driver sunxi-mmc requests probe deferral [ 2.010537] platform 1c0f000.mmc: Added to deferred list [ 2.010546] bus: 'platform': driver_probe_device: matched device 1c10000.mmc with driver sunxi-mmc [ 2.010551] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c10000.mmc [ 2.010564] sunxi-mmc 1c10000.mmc: could not find pctldev for node /soc/pinctrl@1c20800/mmc1-pins, deferring probe [ 2.010572] platform 1c10000.mmc: Driver sunxi-mmc requests probe deferral [ 2.010576] platform 1c10000.mmc: Added to deferred list [ 2.010650] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.011633] ledtrig-cpu: registered to indicate activity on CPUs [ 2.012602] usbcore: registered new interface driver usbhid [ 2.012604] usbhid: USB HID core driver [ 2.013365] bus: 'platform': driver_probe_device: matched device snd-soc-dummy with driver snd-soc-dummy [ 2.013372] bus: 'platform': really_probe: probing driver snd-soc-dummy with device snd-soc-dummy [ 2.013413] driver: 'snd-soc-dummy': driver_bound: bound to device 'snd-soc-dummy' [ 2.013418] bus: 'platform': really_probe: bound device snd-soc-dummy to driver snd-soc-dummy [ 2.013944] NET: Registered protocol family 17 [ 2.014030] 9pnet: Installing 9P2000 support [ 2.014091] Key type dns_resolver registered [ 2.014738] registered taskstats version 1 [ 2.016126] Btrfs loaded, crc32c=crc32c-generic [ 2.020642] platform 1c19400.phy: Retrying from deferred list [ 2.020710] bus: 'platform': driver_probe_device: matched device 1c19400.phy with driver sun4i-usb-phy [ 2.020719] bus: 'platform': really_probe: probing driver sun4i-usb-phy with device 1c19400.phy [ 2.021192] driver: 'sun4i-usb-phy': driver_bound: bound to device '1c19400.phy' [ 2.021200] bus: 'platform': really_probe: bound device 1c19400.phy to driver sun4i-usb-phy [ 2.021214] platform 1c20800.pinctrl: Retrying from deferred list [ 2.021263] bus: 'platform': driver_probe_device: matched device 1c20800.pinctrl with driver sun50i-a64-pinctrl [ 2.021268] bus: 'platform': really_probe: probing driver sun50i-a64-pinctrl with device 1c20800.pinctrl [ 2.024455] sun50i-a64-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver [ 2.024461] driver: 'sun50i-a64-pinctrl': driver_bound: bound to device '1c20800.pinctrl' [ 2.024468] bus: 'platform': really_probe: bound device 1c20800.pinctrl to driver sun50i-a64-pinctrl [ 2.024481] platform 1c28000.serial: Retrying from deferred list [ 2.024603] bus: 'platform': driver_probe_device: matched device 1c28000.serial with driver dw-apb-uart [ 2.024609] bus: 'platform': really_probe: probing driver dw-apb-uart with device 1c28000.serial [ 2.025321] console [ttyS0] disabled [ 2.983052] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 15, base_baud = 1500000) is a U6_16550A [ 2.992039] console [ttyS0] enabled [ 2.992039] console [ttyS0] enabled [ 2.999010] bootconsole [uart0] disabled [ 2.999010] bootconsole [uart0] disabled [ 3.007066] driver: 'dw-apb-uart': driver_bound: bound to device '1c28000.serial' [ 3.014554] bus: 'platform': really_probe: bound device 1c28000.serial to driver dw-apb-uart [ 3.023006] platform 1c1b000.usb: Retrying from deferred list [ 3.028967] bus: 'platform': driver_probe_device: matched device 1c1b000.usb with driver ehci-platform [ 3.038274] bus: 'platform': really_probe: probing driver ehci-platform with device 1c1b000.usb [ 3.047151] ehci-platform 1c1b000.usb: EHCI Host Controller [ 3.052745] ehci-platform 1c1b000.usb: new USB bus registered, assigned bus number 1 [ 3.060720] ehci-platform 1c1b000.usb: irq 10, io mem 0x01c1b000 [ 3.079159] ehci-platform 1c1b000.usb: USB 2.0 started, EHCI 1.00 [ 3.085692] bus: 'usb': driver_probe_device: matched device usb1 with driver usb [ 3.093091] bus: 'usb': really_probe: probing driver usb with device usb1 [ 3.100004] bus: 'usb': driver_probe_device: matched device 1-0:1.0 with driver hub [ 3.107662] bus: 'usb': really_probe: probing driver hub with device 1-0:1.0 [ 3.114730] hub 1-0:1.0: USB hub found [ 3.118506] hub 1-0:1.0: 1 port detected [ 3.122533] driver: 'hub': driver_bound: bound to device '1-0:1.0' [ 3.128717] bus: 'usb': really_probe: bound device 1-0:1.0 to driver hub [ 3.135477] driver: 'usb': driver_bound: bound to device 'usb1' [ 3.141399] bus: 'usb': really_probe: bound device usb1 to driver usb [ 3.147897] driver: 'ehci-platform': driver_bound: bound to device '1c1b000.usb' [ 3.155294] bus: 'platform': really_probe: bound device 1c1b000.usb to driver ehci-platform [ 3.163651] platform 1c1b400.usb: Retrying from deferred list [ 3.169603] bus: 'platform': driver_probe_device: matched device 1c1b400.usb with driver ohci-platform [ 3.178907] bus: 'platform': really_probe: probing driver ohci-platform with device 1c1b400.usb [ 3.187726] ohci-platform 1c1b400.usb: Generic Platform OHCI controller [ 3.194358] ohci-platform 1c1b400.usb: new USB bus registered, assigned bus number 2 [ 3.202226] ohci-platform 1c1b400.usb: irq 11, io mem 0x01c1b400 [ 3.271530] bus: 'usb': driver_probe_device: matched device usb2 with driver usb [ 3.278929] bus: 'usb': really_probe: probing driver usb with device usb2 [ 3.285830] bus: 'usb': driver_probe_device: matched device 2-0:1.0 with driver hub [ 3.293488] bus: 'usb': really_probe: probing driver hub with device 2-0:1.0 [ 3.300555] hub 2-0:1.0: USB hub found [ 3.304330] hub 2-0:1.0: 1 port detected [ 3.308349] driver: 'hub': driver_bound: bound to device '2-0:1.0' [ 3.314533] bus: 'usb': really_probe: bound device 2-0:1.0 to driver hub [ 3.321282] driver: 'usb': driver_bound: bound to device 'usb2' [ 3.327203] bus: 'usb': really_probe: bound device usb2 to driver usb [ 3.333702] driver: 'ohci-platform': driver_bound: bound to device '1c1b400.usb' [ 3.341099] bus: 'platform': really_probe: bound device 1c1b400.usb to driver ohci-platform [ 3.349457] platform 1c19000.usb: Retrying from deferred list [ 3.355402] bus: 'platform': driver_probe_device: matched device 1c19000.usb with driver musb-sunxi [ 3.364446] bus: 'platform': really_probe: probing driver musb-sunxi with device 1c19000.usb [ 3.373016] bus: 'platform': driver_probe_device: matched device usb_phy_generic.0.auto with driver usb_phy_generic [ 3.383446] bus: 'platform': really_probe: probing driver usb_phy_generic with device usb_phy_generic.0.auto [ 3.393298] usb_phy_generic usb_phy_generic.0.auto: usb_phy_generic.0.auto supply vcc not found, using dummy regulator [ 3.404034] driver: 'usb_phy_generic': driver_bound: bound to device 'usb_phy_generic.0.auto' [ 3.412564] bus: 'platform': really_probe: bound device usb_phy_generic.0.auto to driver usb_phy_generic [ 3.422146] bus: 'platform': driver_probe_device: matched device musb-hdrc.1.auto with driver musb-hdrc [ 3.431543] bus: 'platform': really_probe: probing driver musb-hdrc with device musb-hdrc.1.auto [ 3.440473] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver [ 3.446235] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 3 [ 3.454496] bus: 'usb': driver_probe_device: matched device usb3 with driver usb [ 3.461902] bus: 'usb': really_probe: probing driver usb with device usb3 [ 3.468804] bus: 'usb': driver_probe_device: matched device 3-0:1.0 with driver hub [ 3.476466] bus: 'usb': really_probe: probing driver hub with device 3-0:1.0 [ 3.483532] hub 3-0:1.0: USB hub found [ 3.487313] hub 3-0:1.0: 1 port detected [ 3.491357] driver: 'hub': driver_bound: bound to device '3-0:1.0' [ 3.497543] bus: 'usb': really_probe: bound device 3-0:1.0 to driver hub [ 3.504290] driver: 'usb': driver_bound: bound to device 'usb3' [ 3.510218] bus: 'usb': really_probe: bound device usb3 to driver usb [ 3.516789] driver: 'musb-hdrc': driver_bound: bound to device 'musb-hdrc.1.auto' [ 3.524283] bus: 'platform': really_probe: bound device musb-hdrc.1.auto to driver musb-hdrc [ 3.532722] driver: 'musb-sunxi': driver_bound: bound to device '1c19000.usb' [ 3.539860] bus: 'platform': really_probe: bound device 1c19000.usb to driver musb-sunxi [ 3.547952] platform 1c2b000.i2c: Retrying from deferred list [ 3.553900] bus: 'platform': driver_probe_device: matched device 1c2b000.i2c with driver mv64xxx_i2c [ 3.563031] bus: 'platform': really_probe: probing driver mv64xxx_i2c with device 1c2b000.i2c [ 3.572044] driver: 'mv64xxx_i2c': driver_bound: bound to device '1c2b000.i2c' [ 3.579273] bus: 'platform': really_probe: bound device 1c2b000.i2c to driver mv64xxx_i2c [ 3.587461] platform 1c0f000.mmc: Retrying from deferred list [ 3.593428] bus: 'platform': driver_probe_device: matched device 1c0f000.mmc with driver sunxi-mmc [ 3.602387] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c0f000.mmc [ 3.611332] sunxi-mmc 1c0f000.mmc: Got CD GPIO [ 3.655164] sunxi-mmc 1c0f000.mmc: base:0xffff00000807c000 irq:7 [ 3.661180] driver: 'sunxi-mmc': driver_bound: bound to device '1c0f000.mmc' [ 3.668233] bus: 'platform': really_probe: bound device 1c0f000.mmc to driver sunxi-mmc [ 3.676241] platform 1c10000.mmc: Retrying from deferred list [ 3.682218] bus: 'platform': driver_probe_device: matched device 1c10000.mmc with driver sunxi-mmc [ 3.691176] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c10000.mmc [ 3.699799] platform 1c10000.mmc: Driver sunxi-mmc requests probe deferral [ 3.706678] platform 1c10000.mmc: Added to deferred list [ 3.712220] sun6i-rtc 1f00000.rtc: setting system clock to 2017-04-29 02:07:09 UTC (1493431629) [ 3.721141] ALSA device list: [ 3.724129] No soundcards found. [ 3.727806] Waiting for root device /dev/mmcblk0p5... [ 3.732876] driver_probe_done: probe_count = 0 [ 3.751145] driver_probe_done: probe_count = 0 [ 3.771150] driver_probe_done: probe_count = 0 [ 3.788395] mmc0: new high speed SDHC card at address 0007 [ 3.791145] driver_probe_done: probe_count = 0 [ 3.798455] bus: 'mmc': driver_probe_device: matched device mmc0:0007 with driver mmcblk [ 3.806549] bus: 'mmc': really_probe: probing driver mmcblk with device mmc0:0007 [ 3.807149] driver_probe_done: probe_count = 1 [ 3.818766] mmcblk0: mmc0:0007 SD16G 14.5 GiB [ 3.823145] driver_probe_done: probe_count = 1 [ 3.829954] mmcblk0: p1 p2 < p5 > [ 3.834002] driver: 'mmcblk': driver_bound: bound to device 'mmc0:0007' [ 3.839145] driver_probe_done: probe_count = 1 [ 3.845076] bus: 'mmc': really_probe: bound device mmc0:0007 to driver mmcblk [ 3.852237] platform 1c10000.mmc: Retrying from deferred list [ 3.855169] driver_probe_done: probe_count = 0 [ 3.862774] bus: 'platform': driver_probe_device: matched device 1c10000.mmc with driver sunxi-mmc [ 3.871775] bus: 'platform': really_probe: probing driver sunxi-mmc with device 1c10000.mmc [ 3.880476] platform 1c10000.mmc: Driver sunxi-mmc requests probe deferral [ 3.887365] platform 1c10000.mmc: Added to deferred list [ 3.958882] F2FS-fs (mmcblk0p5): Mounted with checkpoint version = 1a55b [ 3.965662] VFS: Mounted root (f2fs filesystem) readonly on device 179:5. [ 3.974611] devtmpfs: mounted [ 3.977964] Freeing unused kernel memory: 576K [ 4.036695] random: fast init done INIT: version 2.88 booting OpenRC 0.23 is starting up Linux 4.11.0-rc7-iceconfig-00064-gf4aec3d9ff13 (aarch64) * Mounting /proc ... [ ok ] * Mounting /run ... * /run/openrc: creating directory * /run/lock: creating directory * /run/lock: correcting owner * Caching service dependencies ... [ ok ] [....] Starting the hotplug events dispatcher: systemd-udevdstarting version 232 . ok [ ok ] Synthesizing the initial hotplug events...done. [....] Waiting for /dev to be fully populated...[ 6.325314] bus: 'platform': driver_probe_device: matched device 1c30000.ethernet with driver sun8i-emac [ 6.334842] bus: 'platform': really_probe: probing driver sun8i-emac with device 1c30000.ethernet [ 6.344253] platform 1c30000.ethernet: Driver sun8i-emac requests probe deferral [ 6.351662] platform 1c30000.ethernet: Added to deferred list done. Starting fake hwclock: loading system time. Current system time: 2017-04-29 02:07:14 fake-hwclock saved clock information is in the past: 2017-04-29 02:05:57 To set system time to this saved clock anyway, use "force" [ ok ] Activating swap...done. [ ok ] Activating lvm and md swap...done. [....] Checking file systems...fsck from util-linux 2.29.2 /dev/mmcblk0p1: clean, 42/25376 files, 32541/101376 blocks done. [ ok ] Cleaning up temporary files... /tmp. [....] Mounting local filesystems...[ 10.041068] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null) done. [ ok ] Activating swapfile swap...done. [ ok ] Cleaning up temporary files.... [ ok ] Setting kernel variables...done. [....] Configuring network interfaces...Cannot find device "eth0" ifup: failed to bring up eth0 failed. Starting NBD client process: Connecting...[ 12.715295] NET: Registered protocol family 10 [ 12.723755] Segment Routing with IPv6 Error: Socket failed: Network is unreachable Error: Socket failed: Network is unreachable [ ok ] Cleaning up temporary files.... [info] Loading kernel module 8723bs. modprobe: FATAL: Module 8723bs not found in directory /lib/modules/4.11.0-rc7-iceconfig-00064-gf4aec3d9ff13 INIT: Entering runlevel: 2 [ ok ] Starting enhanced syslogd: rsyslogd. [ ok ] Starting periodic command scheduler: cron. [ ok ] Starting MTA: exim4. [ ok ] Starting NTP server: ntpd. [....] Recovering schroot sessions:E: 10mount: mount: special device /srv/sbuild/snapshots/unstable-arm64-sbuild-fcca4b13-74fe-432f-9cc9-788969157840 does not exist E: unstable-arm64-sbuild-fcca4b13-74fe-432f-9cc9-788969157840: Chroot setup failed: stage=setup-recover [ ok ion:unstable-arm64-sbuild-fcca4b13-74fe-432f-9cc9-788969157840. [ ok ] Starting OpenBSD Secure Shell server: sshd. Debian GNU/Linux 9 sirius ttyS0 sirius login: [ 73.043009] random: crng init done ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-29 21:28 ` Adam Borowski @ 2017-04-29 23:34 ` André Przywara 2017-05-01 19:22 ` Tejun Heo 0 siblings, 1 reply; 12+ messages in thread From: André Przywara @ 2017-04-29 23:34 UTC (permalink / raw) To: Adam Borowski, Tejun Heo; +Cc: Icenowy Zheng, Greg Kroah-Hartman, linux-kernel On 29/04/17 22:28, Adam Borowski wrote: > On Fri, Apr 28, 2017 at 06:03:14PM -0400, Tejun Heo wrote: >> On Tue, Apr 18, 2017 at 10:12:16AM +0100, Andre Przywara wrote: >>> Yeah, so I stack-dumped on the zero allocations and indeed they are >>> called from cleanup functions: >>> drivers/pinctrl/pinmux.c:pinmux_generic_free_functions(): >>> devm_kzalloc(sizeof(*indices) * pctldev->num_functions, ...) >>> (and another one I don't know from the top of the my head, logs at home) >>> >>> So my hunch was that once EPROBE_DEFER triggers the devres cleanup, it >>> uses some reverse list traversal to release all allocated resources >>> (backwards!), so missing those which get (appended) during the process. >>> But I don't think that would not work with the locking. >>> So I have to dig deeper tonight in my logs. >> >> If this is a valid use case, we can change devm to repeat till empty >> but it's a weird thing to do to allocate from a release function. >> >> So, something like this. Only compile tested. I was wondering if using devm_*alloc in a _release_ function is valid at all, given that it is called as part of the DEFER clean-up routine. Looking at pinmux_generic_free_functions() it looks like we could replace it with a (non-devm_) kmalloc version and it would still work (given we add a kfree at the end). Either that or we bail out early if pctldev->num_functions is zero. > I've tested this, and the results are mixed. It _does_ make the WARN go > away, but on the other hand, much later during the boot, another driver > (sun8i-emac) gets deferred and never undefers. > > Without the patch, there's: > [ ok ] Synthesizing the initial hotplug events...done. > [ 6.666984] bus: 'platform': driver_probe_device: matched device 1c30000.ethernet with driver sun8i-emac > [ 6.676520] bus: 'platform': really_probe: probing driver sun8i-emac with device 1c30000.ethernet > [ 6.686479] driver: 'sun8i-emac': driver_bound: bound to device '1c30000.ethernet' > [....] [ 6.694132] bus: 'platform': really_probe: bound device 1c30000.ethernet to driver sun8i-emac > [ ok ng for /dev to be fully populated...done. > <snip> > [....] Configuring network interfaces...[ 11.414884] libphy: 1c30000.ethernet: probed > [ 11.449742] bus: 'mdio_bus': driver_probe_device: matched device 1c30000.ethernet-0:00 with driver RTL8211E Gigabit Ethernet > [ 11.461013] bus: 'mdio_bus': really_probe: probing driver RTL8211E Gigabit Ethernet with device 1c30000.ethernet-0:00 > [ 11.471666] driver: 'RTL8211E Gigabit Ethernet': driver_bound: bound to device '1c30000.ethernet-0:00' > [ 11.481007] bus: 'mdio_bus': really_probe: bound device 1c30000.ethernet-0:00 to driver RTL8211E Gigabit Ethernet > [ 11.491527] RTL8211E Gigabit Ethernet 1c30000.ethernet-0:00: attached PHY driver [RTL8211E Gigabit Ethernet] (mii_bus:phy_addr=1c30000.ethernet-0:00, irq=-1) > [ 11.505608] sun8i-emac 1c30000.ethernet: device MAC address slot 0 02:ba:8f:9e:44:8f > > With the patch: > [ ok ] Synthesizing the initial hotplug events...done. > [....] Waiting for /dev to be fully populated...[ 6.325314] bus: 'platform': driver_probe_device: matched device 1c30000.ethernet with driver sun8i-emac > [ 6.334842] bus: 'platform': really_probe: probing driver sun8i-emac with device 1c30000.ethernet > [ 6.344253] platform 1c30000.ethernet: Driver sun8i-emac requests probe deferral > [ 6.351662] platform 1c30000.ethernet: Added to deferred list > done. > <snip> > [....] Configuring network interfaces...Cannot find device "eth0" > ifup: failed to bring up eth0 > failed. > > > There's a concern, though, that this second driver is not in mainline (and > won't ever be -- it's being replaced by dwmac-sun8i, but the latter is not > yet up to scratch), thus I'm not sure how relevant this is. > > The second concern is that I'm using the same tree > (icenowy/sunxi64-4.11-rc7) and config (plus filesystems) as Icenowy, on the > same hardware, yet I did see this warning in versions much earlier than her, > and it doesn't go away in -next for me either. All that differs are > toolchain versions (I'm on Debian unstable) and perhaps some u-boot bits. My understanding is that the probing order is determined during link time of the kernel - at least for functions using the same initcall level. For drivers in the same subsystem this is mostly set by the order in the Makefile, but for drivers from different subsystems their order might by slightly different depending on parallel compilation. So on some builds the pinctrl driver gets called late and everything is fine, whereas on other build it gets called early and returns with -EDEFER, leading to the issue. > Because of the amount of churn in sunxi land and the driver's mainlining > status, I think it'd be good to wait after the merge window that starts > tomorrow, and re-test then. Well, I see this issue with pure mainline and defconfig, so no sunxi specialities here. Will do more investigation tomorrow. Cheers, Andre. > > Full console output with the patch applied attached. > > > Meow! > > >> diff --git a/drivers/base/devres.c b/drivers/base/devres.c >> index 71d5770..d2a9f34 100644 >> --- a/drivers/base/devres.c >> +++ b/drivers/base/devres.c >> @@ -509,13 +509,21 @@ static int release_nodes(struct device *dev, struct list_head *first, >> int devres_release_all(struct device *dev) >> { >> unsigned long flags; >> + int cnt = 0, ret; >> >> /* Looks like an uninitialized device structure */ >> if (WARN_ON(dev->devres_head.next == NULL)) >> return -ENODEV; >> - spin_lock_irqsave(&dev->devres_lock, flags); >> - return release_nodes(dev, dev->devres_head.next, &dev->devres_head, >> - flags); >> + >> + /* Release callbacks may create new nodes, repeat till empty */ >> + do { >> + spin_lock_irqsave(&dev->devres_lock, flags); >> + ret = release_nodes(dev, dev->devres_head.next, >> + &dev->devres_head, flags); >> + cnt += ret; >> + } while (ret); >> + >> + return cnt; >> } >> >> /** >> > ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-29 23:34 ` André Przywara @ 2017-05-01 19:22 ` Tejun Heo 0 siblings, 0 replies; 12+ messages in thread From: Tejun Heo @ 2017-05-01 19:22 UTC (permalink / raw) To: André Przywara Cc: Adam Borowski, Icenowy Zheng, Greg Kroah-Hartman, linux-kernel Hello, On Sun, Apr 30, 2017 at 12:34:40AM +0100, André Przywara wrote: > >> If this is a valid use case, we can change devm to repeat till empty > >> but it's a weird thing to do to allocate from a release function. > >> > >> So, something like this. Only compile tested. > > I was wondering if using devm_*alloc in a _release_ function is valid at > all, given that it is called as part of the DEFER clean-up routine. > Looking at pinmux_generic_free_functions() it looks like we could > replace it with a (non-devm_) kmalloc version and it would still work > (given we add a kfree at the end). > Either that or we bail out early if pctldev->num_functions is zero. I was just throwing the idea but the more I think about it, the less sense it makes to me. So, I really think this should be fixed by dropping dev_kzalloc() call from the release function. It makes no sense. Thanks. -- tejun ^ permalink raw reply [flat|nested] 12+ messages in thread
* Re: sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 2017-04-18 7:25 ` Tejun Heo 2017-04-18 9:12 ` Andre Przywara @ 2017-04-18 10:51 ` Icenowy Zheng 1 sibling, 0 replies; 12+ messages in thread From: Icenowy Zheng @ 2017-04-18 10:51 UTC (permalink / raw) To: Tejun Heo, André Przywara Cc: Icenowy Zheng, Greg Kroah-Hartman, Adam Borowski, linux-kernel 于 2017年4月18日 GMT+08:00 下午3:25:05, Tejun Heo <tj@kernel.org> 写到: >Hello, > >On Mon, Apr 03, 2017 at 12:48:16AM +0100, André Przywara wrote: >> So I see this problem easily now - on every boot - with an unpatched >> 4.11-rc3 kernel and the (arm64) defconfig on a Pine64 or BananaPi >M64. >> I enabled devres.log and see that pinctrl probes early, but >apparently >> gets deferred, pretty late actually (after 43 ADDs). >> >> Now what sticks out from the sequence (see the attached log) is that >> there are two un-matches ADDs with a devm_kmalloc of size 0: >> sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84200 >> devm_kzalloc_release (0 bytes) >> sun50i-a64-pinctrl 1c20800.pinctrl: DEVRES ADD ffff80007bd84100 >> devm_kzalloc_release (0 bytes) P.S. mysteriously the warn disappeared in newest -next, but still exist in 4.11-rc. >> >> While all the other ADDs have a matching REL, those two have not. I >> guess it's due to the size being 0. Does that ring a bell? > >AFAICS, 0 size allocs should be fine. > >> Or is due to the fact that these two ADDs are after the RELs have >> already started, so at a point where the driver is already cleaned >up? > >But this sounds problematic to me. So, these zero length allocations >are happening after release of the device is initiated? Where are >they coming from? > >Thanks. ^ permalink raw reply [flat|nested] 12+ messages in thread
end of thread, other threads:[~2017-05-01 19:22 UTC | newest]
Thread overview: 12+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
[not found] <20170315161406.smd4na25two55jjh@angband.pl>
[not found] ` <197431489595078@web8g.yandex.ru>
2017-03-16 1:06 ` sun50i-a64-pinctrl WARN_ON drivers/base/dd.c:349 Greg Kroah-Hartman
2017-03-17 14:08 ` Tejun Heo
[not found] ` <785901489760914@web50g.yandex.ru>
2017-03-17 14:44 ` Tejun Heo
2017-03-17 16:32 ` Adam Borowski
2017-04-02 23:48 ` André Przywara
2017-04-18 7:25 ` Tejun Heo
2017-04-18 9:12 ` Andre Przywara
2017-04-28 22:03 ` Tejun Heo
2017-04-29 21:28 ` Adam Borowski
2017-04-29 23:34 ` André Przywara
2017-05-01 19:22 ` Tejun Heo
2017-04-18 10:51 ` Icenowy Zheng
This is a public inbox, see mirroring instructions for how to clone and mirror all data and code used for this inbox; as well as URLs for NNTP newsgroup(s).