[Gta04-owner] Kernel 3.15

Dr. H. Nikolaus Schaller hns at goldelico.com
Tue Apr 8 18:03:27 CEST 2014


Hi,
although the merge window for 3.15-rc1 is still open, I have merged the linus/master branch snapshot from this morning onto our latest branch. And fixed some merge conflicts (mostly the DSS patches that did appear in 3.15 are not 100% compatible - needed some cosmetics like s/pixel_clock/pixelclock/).

Otherwise I did not see significant issues by merge conflicts and after manually inspecting the diffs I could in most cases just revert to the linus/master variant. In some cases I have kept/merged both patches where our functionality still goes beyond linus/master.

The kernel now compiles and boots - but shows new behaviour of the HDQ lock issue (which was already visible in 3.14 - but only with Device Tree). See attached log. The main difference is that the kernel now hangs after this event... Well, the GTA04 not completely hangs, but reports some stalls.

Another minor observation: again something has changed in NAND ECC: (null): Unsupported NAND ECC scheme selected

If anyone has an idea on any of these (or other) topics and wants to hep to fix bugs, please let me know for discussion.

And some statistics FYI:

GTA04 3.15-rc0 is away from Linus/linus/master by 104 files with differences to be upstreamed

BR,
Nikolaus

NOTEs:
* call_modprobe: w1-family-0x1 comes from a (not pushed) patch to print when the kernel wants to load a kernel module
* the kernel identifies still as 3.14.0-gta04 because Linus has not "published" (i.e. tagged) it yet

Here is the branch on the server:

http://git.goldelico.com/?p=gta04-kernel.git;a=shortlog;h=refs/heads/3.15-rc0


Environment size: 3563/131068 bytes
lcm state set to deep-standby
display power off
## Booting kernel from Legacy Image at 82000000 ...
  Image Name:   Linux-3.14.0-gta04
  Image Type:   ARM Linux Kernel Image (uncompressed)
  Data Size:    4016832 Bytes = 3.8 MiB
  Load Address: 80008000
  Entry Point:  80008000
  Verifying Checksum ... OK
## Flattened Device Tree blob at 81c00000
  Booting using the fdt blob at 0x81c00000
  Loading Kernel Image ... OK
OK
  Loading Device Tree to 8ffed000, end 8fffff20 ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 3.14.0-gta04 (hns at iMac.local) (gcc version 4.6.3 (GCC) ) #556 SMP Tue Apr 8 10:58:08 CEST 2014
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: OMAP3 GTA04
[    0.000000] cma: CMA: reserved 16 MiB at 9e800000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] OMAP3630 ES1.2 (l2cache iva sgx neon isp 192mhz_clk )
[    0.000000] PERCPU: Embedded 9 pages/cpu @dfaa1000 s14720 r8192 d13952 u36864
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 129792
[    0.000000] Kernel command line: console=ttyO2,115200n8 mux=GTA04A3+ mpurate=800 vram=12M cma=12M omapfb.vram=0:8M,1:4M omapfb.mode=dvi:1024x768MR-16 at 60 omapfb.debug=y omapdss.def_disp=lcd ubi.mtd=4 root=/dev/mmcblk0p1 rw roott
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 486156K/523264K available (5313K kernel code, 506K rwdata, 2108K rodata, 374K init, 7465K bss, 37108K reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
[    0.000000]     vmalloc : 0xe0800000 - 0xff000000   ( 488 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0747614   (7422 kB)
[    0.000000]       .init : 0xc0748000 - 0xc07a5980   ( 375 kB)
[    0.000000]       .data : 0xc07a6000 - 0xc0824af8   ( 507 kB)
[    0.000000]        .bss : 0xc0824af8 - 0xc0f6efb0   (7466 kB)
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU restricting CPUs from NR_CPUS=2 to nr_cpu_ids=1.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
[    0.000000] Total of 96 interrupts on 1 active controller
[    0.000000] Clocking rate (Crystal/Core/MPU): 26.0/332/600 MHz
[    0.000000] OMAP clockevent source: timer1 at 32768 Hz
[    0.000030] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65536000000000ns
[    0.000061] OMAP clocksource: 32k_counter at 32768 Hz
[    0.001373] Console: colour dummy device 80x30
[    0.001434] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.001464] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.001464] ... MAX_LOCK_DEPTH:          48
[    0.001495] ... MAX_LOCKDEP_KEYS:        8191
[    0.001495] ... CLASSHASH_SIZE:          4096
[    0.001495] ... MAX_LOCKDEP_ENTRIES:     16384
[    0.001525] ... MAX_LOCKDEP_CHAINS:      32768
[    0.001525] ... CHAINHASH_SIZE:          16384
[    0.001556]  memory used by lock dependency info: 3695 kB
[    0.001556]  per task-struct memory footprint: 1152 bytes
[    0.001647] Calibrating delay loop... 594.73 BogoMIPS (lpj=2973696)
[    0.097473] pid_max: default: 32768 minimum: 301
[    0.098175] Security Framework initialized
[    0.098388] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.098419] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.102661] CPU: Testing write buffer coherency: ok
[    0.104644] CPU0: thread -1, cpu 0, socket -1, mpidr 0
[    0.104705] Setting up static identity map for 0x804ff2f0 - 0x804ff348
[    0.109802] Brought up 1 CPUs
[    0.109832] SMP: Total of 1 processors activated.
[    0.109832] CPU: All CPU(s) started in SVC mode.
[    0.114624] devtmpfs: initialized
[    0.126647] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.216644] omap_hwmod: mcbsp2_sidetone using broken dt data from mcbsp
[    0.219299] omap_hwmod: mcbsp3_sidetone using broken dt data from mcbsp
[    0.253387] omap_hwmod: usb_host_hs: could not associate to clkdm l3_init_clkdm
[    0.256103] omap_hwmod: usb_tll_hs: could not associate to clkdm l3_init_clkdm
[    0.353271] pinctrl core: initialized pinctrl subsystem
[    0.358123] regulator-dummy: no parameters
[    0.464660] NET: Registered protocol family 16
[    0.478607] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.492462] Reprogramming SDRC clock to 332000000 Hz
[    0.506225] OMAP GPIO hardware version 2.5
[    0.547637] omap-gpmc 6e000000.gpmc: GPMC revision 5.0
[    0.548095] gpmc_probe_nand_child: ti,elm-id property not found
[    0.548339]  (null): Unsupported NAND ECC scheme selected
[    0.564392] No ATAGs?
[    0.564422] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.568969] OMAP DMA hardware revision 5.0
[    0.603302] edma-dma-engine edma-dma-engine.0: Can't allocate PaRAM dummy slot
[    0.603424] edma-dma-engine: probe of edma-dma-engine.0 failed with error -5
[    0.663635] omap-dma-engine 48056000.dma-controller: OMAP DMA engine driver
[    0.666473] bl-enable: 825241 mV 
[    0.673645] SCSI subsystem initialized
[    0.674926] usbcore: registered new interface driver usbfs
[    0.675231] usbcore: registered new interface driver hub
[    0.677886] usbcore: registered new device driver usb
[    0.678710] hsusb2_phy.4 supply vcc not found, using dummy regulator
[    0.684967] call_modprobe: i2c:twl4030
[    0.838500] i2c i2c-0: of_i2c: invalid reg on /ocp/i2c at 48070000/audio
[    0.838562] omap_i2c 48070000.i2c: bus 0 rev4.4 at 2600 kHz
[    0.841094] call_modprobe: i2c:bmp085
[    0.843109] call_modprobe: i2c:bma180
[    0.844757] call_modprobe: i2c:tca6507
[    0.847198] call_modprobe: i2c:tsc2007
[    0.849456] call_modprobe: i2c:hmc5883l
[    0.851043] call_modprobe: i2c:itg3200
[    0.852600] call_modprobe: i2c:24c64
[    0.854156] call_modprobe: i2c:ov9655
[    0.855682] call_modprobe: i2c:si4721
[    0.858032] omap_i2c 48072000.i2c: bus 1 rev4.4 at 400 kHz
[    0.859680] omap_i2c 48060000.i2c: bus 2 rev4.4 at 100 kHz
[    0.862030] Advanced Linux Sound Architecture Driver Initialized.
[    0.871063] Switched to clocksource 32k_counter
[    1.086242] NET: Registered protocol family 2
[    1.088928] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    1.089233] TCP bind hash table entries: 4096 (order: 5, 147456 bytes)
[    1.091766] TCP: Hash tables configured (established 4096 bind 4096)
[    1.092102] TCP: reno registered
[    1.092132] UDP hash table entries: 256 (order: 2, 20480 bytes)
[    1.092468] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes)
[    1.094207] NET: Registered protocol family 1
[    1.099700] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[    1.108520] futex hash table entries: 256 (order: 2, 16384 bytes)
[    1.362426] VFS: Disk quotas dquot_6.5.2
[    1.362640] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.364562] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.365295] msgmni has been set to 981
[    1.374450] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    1.374633] io scheduler noop registered
[    1.374664] io scheduler deadline registered
[    1.374755] io scheduler cfq registered (default)
[    1.378601] pinctrl-single 48002030.pinmux: 284 pins at pa fa002030 size 568
[    1.379486] pinctrl-single 48002a00.pinmux: 46 pins at pa fa002a00 size 92
[    1.381439] pinctrl-single 480025a0.pinmux: 46 pins at pa fa0025a0 size 92
[    1.393280] OMAP DSS rev 2.0
[    1.401214] omapfb omapfb: no displays
[    1.405242] omapfb omapfb: failed to setup omapfb
[    1.405334] platform omapfb: Driver omapfb requests probe deferral
[    1.413238] platform 4806a000.serial: Driver omap_uart requests probe deferral
[    1.413757] platform 4806c000.serial: Driver omap_uart requests probe deferral
[    1.414245] omap_uart 49020000.serial: no wakeirq for uart0
[    1.416717] 49020000.serial: ttyO2 at MMIO 0x49020000 (irq = 90, base_baud = 3000000) is a OMAP UART2
[    2.195678] console [ttyO2] enabled
[    2.203216] omap_uart 49042000.serial: no wakeirq for uart0
[    2.212463] 49042000.serial: ttyO3 at MMIO 0x49042000 (irq = 96, base_baud = 3000000) is a OMAP UART3
[    2.271881] brd: module loaded
[    2.304321] loop: module loaded
[    2.329132] twl 0-0048: PIH (irq 23) chaining IRQs 338..346
[    2.335784] twl 0-0048: power (irq 343) chaining IRQs 346..353
[    2.350097] VAUX1: 2500 <--> 3000 mV at 3000 mV 
[    2.368072] VAUX2_4030: 2800 mV 
[    2.376892] VAUX3: 2500 mV 
[    2.383605] VAUX4: 2800 mV 
[    2.392425] VSIM: 1800 <--> 3000 mV at 1800 mV 
[    2.405456] VDD1: 600 <--> 1450 mV at 1200 mV 
[    2.415985] VDAC: 1800 mV 
[    2.426483] VIO: at 1800 mV 
[    2.435363] VINTANA1: 1500 mV 
[    2.444213] VINTANA2: at 2750 mV 
[    2.453186] VINTDIG: 1500 mV 
[    2.460113] VMMC1: 1850 <--> 3150 mV at 3000 mV 
[    2.470306] VMMC2: 1850 <--> 3150 mV at 2600 mV 
[    2.480072] VUSB1V5: 1500 mV 
[    2.486816] VUSB1V8: 1800 mV 
[    2.494781] VUSB3V1: 3100 mV 
[    2.504913] VPLL1: at 1800 mV 
[    2.513122] VPLL2: 1800 mV 
[    2.530975] twl4030_usb twl4030-usb.41: Initialized TWL4030 USB module
[    2.550323] mtdoops: mtd device (mtddev=name/number) must be supplied
[    2.564758] call_modprobe: spi:td028ttec1
[    2.595825] usbcore: registered new interface driver usb-storage
[    2.622558] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[    2.632995] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 1
[    2.644134] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    2.651458] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.659057] usb usb1: Product: MUSB HDRC host driver
[    2.664337] usb usb1: Manufacturer: Linux 3.14.0-gta04 musb-hcd
[    2.670562] usb usb1: SerialNumber: musb-hdrc.0.auto
[    2.682983] hub 1-0:1.0: USB hub found
[    2.687408] hub 1-0:1.0: 1 port detected
[    2.702606] mousedev: PS/2 mouse device common for all mice
[    2.710052] twl4030_keypad keypad.45: OF: linux,keymap property not defined in /ocp/i2c at 48070000/twl at 48/keypad
[    2.720733] twl4030_keypad keypad.45: Failed to build keymap
[    2.726837] twl4030_keypad: probe of keypad.45 failed with error -2
[    2.737060] input: TSC2007 Touchscreen as /devices/68000000.ocp/48072000.i2c/i2c-1/1-0048/input/input1
[    2.755340] input: twl4030_pwrbutton as /devices/68000000.ocp/48070000.i2c/i2c-0/0-0048/pwrbutton.44/input/input2
[    2.775360] twl_rtc rtc.26: Enabling TWL-RTC
[    2.787414] twl_rtc rtc.26: rtc core: registered rtc.26 as rtc0
[    2.798950] i2c /dev entries driver
[    2.807434] Driver for 1-wire Dallas network protocol.
[    2.814117] omap_hdq omap_hdq: OMAP HDQ Hardware Rev 0.5. Driver in Interrupt mode
[    3.034271] call_modprobe: w1-family-0x1
[    3.051818] 
[    3.053405] =============================================
[    3.059051] [ INFO: possible recursive locking detected ]
[    3.064727] 3.14.0-gta04 #556 Not tainted
[    3.068939] ---------------------------------------------
[    3.074615] w1_bus_master1/795 is trying to acquire lock:
[    3.080261]  (&(&priv->bus_notifier)->rwsem){.+.+.+}, at: [<c0058ab0>] __blocking_notifier_call_chain+0x28/0x58
[    3.090911] 
[    3.090911] but task is already holding lock:
[    3.097045]  (&(&priv->bus_notifier)->rwsem){.+.+.+}, at: [<c0058ab0>] __blocking_notifier_call_chain+0x28/0x58
[    3.107666] 
[    3.107666] other info that might help us debug this:
[    3.114501]  Possible unsafe locking scenario:
[    3.114501] 
[    3.120727]        CPU0
[    3.123291]        ----
[    3.125854]   lock(&(&priv->bus_notifier)->rwsem);
[    3.130889]   lock(&(&priv->bus_notifier)->rwsem);
[    3.135925] 
[    3.135925]  *** DEADLOCK ***
[    3.135925] 
[    3.142150]  May be due to missing lock nesting notation
[    3.142150] 
[    3.149291] 2 locks held by w1_bus_master1/795:
[    3.154052]  #0:  (&dev->mutex#3){+.+.+.}, at: [<c03ba1c8>] w1_attach_slave_device+0xc4/0x1c8
[    3.163055]  #1:  (&(&priv->bus_notifier)->rwsem){.+.+.+}, at: [<c0058ab0>] __blocking_notifier_call_chain+0x28/0x58
[    3.174163] 
[    3.174163] stack backtrace:
[    3.178741] CPU: 0 PID: 795 Comm: w1_bus_master1 Not tainted 3.14.0-gta04 #556
[    3.186370] [<c0013ca0>] (unwind_backtrace) from [<c0010f68>] (show_stack+0x10/0x14)
[    3.194488] [<c0010f68>] (show_stack) from [<c04f0900>] (dump_stack+0x6c/0x88)
[    3.202117] [<c04f0900>] (dump_stack) from [<c00746bc>] (print_deadlock_bug+0xc0/0xf0)
[    3.210449] [<c00746bc>] (print_deadlock_bug) from [<c0075f80>] (validate_chain.isra.29+0x4dc/0x534)
[    3.220031] [<c0075f80>] (validate_chain.isra.29) from [<c0076dac>] (__lock_acquire+0x728/0x834)
[    3.229248] [<c0076dac>] (__lock_acquire) from [<c00774b8>] (lock_acquire+0xf4/0x118)
[    3.237487] [<c00774b8>] (lock_acquire) from [<c04f8e04>] (down_read+0x24/0x34)
[    3.245178] [<c04f8e04>] (down_read) from [<c0058ab0>] (__blocking_notifier_call_chain+0x28/0x58)
[    3.254516] [<c0058ab0>] (__blocking_notifier_call_chain) from [<c0058af4>] (blocking_notifier_call_chain+0x14/0x18)
[    3.265563] [<c0058af4>] (blocking_notifier_call_chain) from [<c0301db8>] (device_add+0x1f4/0x368)
[    3.274993] [<c0301db8>] (device_add) from [<c03055cc>] (platform_device_add+0x138/0x1c8)
[    3.283569] [<c03055cc>] (platform_device_add) from [<c03bd124>] (w1_bq27000_add_slave+0x44/0x74)
[    3.292907] [<c03bd124>] (w1_bq27000_add_slave) from [<c03b9ed8>] (w1_bus_notify.part.0+0x48/0xc8)
[    3.302337] [<c03b9ed8>] (w1_bus_notify.part.0) from [<c04fbe1c>] (notifier_call_chain+0x38/0x68)
[    3.311645] [<c04fbe1c>] (notifier_call_chain) from [<c0058acc>] (__blocking_notifier_call_chain+0x44/0x58)
[    3.321899] [<c0058acc>] (__blocking_notifier_call_chain) from [<c0058af4>] (blocking_notifier_call_chain+0x14/0x18)
[    3.332946] [<c0058af4>] (blocking_notifier_call_chain) from [<c0301db8>] (device_add+0x1f4/0x368)
[    3.342346] [<c0301db8>] (device_add) from [<c03b9ad0>] (__w1_attach_slave_device+0x9c/0x134)
[    3.351318] [<c03b9ad0>] (__w1_attach_slave_device) from [<c03ba238>] (w1_attach_slave_device+0x134/0x1c8)
[    3.361480] [<c03ba238>] (w1_attach_slave_device) from [<c03ba348>] (w1_slave_found+0x7c/0x98)
[    3.370513] [<c03ba348>] (w1_slave_found) from [<c03bc818>] (omap_w1_search_bus+0x54/0x5c)
[    3.379211] [<c03bc818>] (omap_w1_search_bus) from [<c03bc758>] (w1_search_devices+0x3c/0x48)
[    3.388153] [<c03bc758>] (w1_search_devices) from [<c03baaec>] (w1_search_process_cb+0x64/0x108)
[    3.397399] [<c03baaec>] (w1_search_process_cb) from [<c03bac60>] (w1_process+0x68/0x14c)
[    3.405975] [<c03bac60>] (w1_process) from [<c0054bdc>] (kthread+0xdc/0xf0)
[    3.413299] [<c0054bdc>] (kthread) from [<c000dca8>] (ret_from_fork+0x14/0x2c)
[   24.451049] INFO: rcu_sched detected stalls on CPUs/tasks: {} (detected by 0, t=2102 jiffies, g=4294967086, c=4294967085, q=19)
[   24.463134] INFO: Stall ended before state dump start
[   87.501037] INFO: rcu_sched detected stalls on CPUs/tasks: {} (detected by 0, t=8407 jiffies, g=4294967086, c=4294967085, q=19)
[   87.513122] INFO: Stall ended before state dump start
[  150.551055] INFO: rcu_sched detected stalls on CPUs/tasks: {} (detected by 0, t=14712 jiffies, g=4294967086, c=4294967085, q=19)
[  150.563232] INFO: Stall ended before state dump start
[  213.601043] INFO: rcu_sched detected stalls on CPUs/tasks: {} (detected by 0, t=21017 jiffies, g=4294967086, c=4294967085, q=19)
[  213.613220] INFO: Stall ended before state dump start
[  276.651062] INFO: rcu_sched detected stalls on CPUs/tasks: {} (detected by 0, t=27322 jiffies, g=4294967086, c=4294967085, q=19)
[  276.663238] INFO: Stall ended before state dump start



More information about the Gta04-owner mailing list