[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]
Date: Fri, 11 Nov 2011 21:41:55 +0800
From: Shawn Guo <shawn.guo@...escale.com>
To: Yong Zhang <yong.zhang0@...il.com>
CC: <linux-kernel@...r.kernel.org>, Pekka Enberg <penberg@...nel.org>,
"Paul E. McKenney" <paulmck@...ux.vnet.ibm.com>,
Thomas Gleixner <tglx@...utronix.de>
Subject: INFO: possible recursive locking detected: do_lookup() on 3.2-rc1
[Was: Re: 3.2-rc1: INFO: possible recursive locking detected]
Here is another one. And the .config is attached.
Regards,
Shawn
Starting kernel ...
[ 0.000000] Booting Linux on physical CPU 0
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Linux version 3.2.0-rc1+ (r65073@...01-09) (gcc version 4.5.2 (Ub
untu/Linaro 4.5.2-8ubuntu3) ) #54 SMP PREEMPT Fri Nov 11 19:56:10 CST 2011
[ 0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c53c7f
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instructio
n cache
[ 0.000000] Machine: Freescale i.MX6 Quad (Device Tree), model: Freescale i.M
X6 Quad SABRE Automotive Board
[ 0.000000] bootconsole [earlycon0] enabled
[ 0.000000] Memory policy: ECC disabled, Data cache writealloc
[ 0.000000] On node 0 totalpages: 524288
[ 0.000000] free_area_init_node: node 0, pgdat 806c2c60, node_mem_map 80c3800
0
[ 0.000000] Normal zone: 3456 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 438912 pages, LIFO batch:31
[ 0.000000] HighMem zone: 640 pages used for memmap
[ 0.000000] HighMem zone: 81280 pages, LIFO batch:15
[ 0.000000] PERCPU: Embedded 8 pages/cpu @81c5b000 s10656 r8192 d13920 u32768
[ 0.000000] pcpu-alloc: s10656 r8192 d13920 u32768 alloc=8*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pag
es: 520192
[ 0.000000] Kernel command line: console=ttymxc3,115200 debug earlyprintk roo
t=/dev/mmcblk0p3 rootwait
[ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Dentry cache hash table entries: 262144 (order: 8, 1048576 bytes)
[ 0.000000] Inode-cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Memory: 2048MB = 2048MB total
[ 0.000000] Memory: 2064988k/2064988k available, 32164k reserved, 327680K hig
hmem
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xec800000 - 0xf4000000 ( 120 MB)
[ 0.000000] lowmem : 0x80000000 - 0xec000000 (1728 MB)
[ 0.000000] pkmap : 0x7fe00000 - 0x80000000 ( 2 MB)
[ 0.000000] modules : 0x7f000000 - 0x7fe00000 ( 14 MB)
[ 0.000000] .text : 0x80008000 - 0x806320f0 (6313 kB)
[ 0.000000] .init : 0x80633000 - 0x806749a0 ( 263 kB)
[ 0.000000] .data : 0x80676000 - 0x806c4fb0 ( 316 kB)
[ 0.000000] .bss : 0x806c4fd4 - 0x80c34880 (5567 kB)
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] NR_IRQS:432
[ 0.000000] L310 cache controller enabled
[ 0.000000] l2x0: 16 ways, CACHE_ID 0x410000c7, AUX_CTRL 0x02070000, Cache si
ze: 1048576 B
[ 0.000000] of_irq_init: children remain, but no parents
[ 0.000000] sched_clock: 32 bits at 66MHz, resolution 15ns, wraps every 65075
ms
[ 0.000000] Console: colour dummy device 80x30
[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo
Molnar
[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.000000] ... MAX_LOCK_DEPTH: 48
[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.000000] ... CLASSHASH_SIZE: 4096
[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384
[ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768
[ 0.000000] ... CHAINHASH_SIZE: 16384
[ 0.000000] memory used by lock dependency info: 3695 kB
[ 0.000000] per task-struct memory footprint: 1152 bytes
[ 0.055702] Calibrating delay loop... 1581.05 BogoMIPS (lpj=7905280)
[ 0.143147] pid_max: default: 32768 minimum: 301
[ 0.148615] Security Framework initialized
[ 0.152951] AppArmor: AppArmor initialized
[ 0.157405] Mount-cache hash table entries: 512
[ 0.167635] CPU: Testing write buffer coherency: ok
[ 0.172770] ftrace: allocating 16849 entries in 50 pages
[ 0.202113] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.208093] Calibrating local timer... 396.36MHz.
[ 0.373992] CPU1: Booted secondary processor
[ 0.413184] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.453729] CPU2: Booted secondary processor
[ 0.493256] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.533804] CPU3: Booted secondary processor
[ 0.573329] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.573424] Brought up 4 CPUs
[ 0.606842] SMP: Total of 4 processors activated (6324.22 BogoMIPS).
[ 0.616960] devtmpfs: initialized
[ 0.633728] NET: Registered protocol family 16
[ 0.649527] gpiochip_find_base: found new base at 224
[ 0.657510] gpiochip_find_base: found new base at 192
[ 0.664539] gpiochip_find_base: found new base at 160
[ 0.671491] gpiochip_find_base: found new base at 128
[ 0.678473] gpiochip_find_base: found new base at 96
[ 0.685342] gpiochip_find_base: found new base at 64
[ 0.692159] gpiochip_find_base: found new base at 32
[ 0.706861] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint
registers.
[ 0.715017] hw-breakpoint: maximum watchpoint size is 4 bytes.
[ 0.757601] bio: create slab <bio-0> at 0
[ 0.771575] NetLabel: Initializing
[ 0.775116] NetLabel: domain hash size = 128
[ 0.779576] NetLabel: protocols = UNLABELED CIPSOv4
[ 0.784948] NetLabel: unlabeled traffic allowed by default
[ 0.790688] Switching to clocksource mxc_timer1
[ 0.862282] AppArmor: AppArmor Filesystem Enabled
[ 0.918235] NET: Registered protocol family 2
[ 0.923426] IP route cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.932780] TCP established hash table entries: 262144 (order: 9, 2097152 byt
es)
[ 0.945033] TCP bind hash table entries: 65536 (order: 9, 2621440 bytes)
[ 0.977742] TCP: Hash tables configured (established 262144 bind 65536)
[ 0.984593] TCP reno registered
[ 0.987914] UDP hash table entries: 1024 (order: 4, 98304 bytes)
[ 0.994884] UDP-Lite hash table entries: 1024 (order: 4, 98304 bytes)
[ 1.003124] NET: Registered protocol family 1
[ 1.009301] RPC: Registered named UNIX socket transport module.
[ 1.015333] RPC: Registered udp transport module.
[ 1.020199] RPC: Registered tcp transport module.
[ 1.025009] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1.032381] Unpacking initramfs...
[ 1.171869] Freeing initrd memory: 1432K
[ 1.178532] audit: initializing netlink socket (disabled)
[ 1.184257] type=2000 audit(1.120:1): initialized
[ 1.255753] Kprobe smoke test started
[ 2.085897] Kprobe smoke test passed successfully
[ 2.123587] highmem bounce pool size: 64 pages
[ 2.134829] Btrfs loaded
[ 2.137657] msgmni has been set to 3395
[ 2.144903] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 2
54)
[ 2.152534] io scheduler noop registered
[ 2.156590] io scheduler deadline registered
[ 2.161166] io scheduler cfq registered (default)
[ 2.167506] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 2.836264] Serial: IMX driver
[ 2.840165] 21f0000.uart: ttymxc3 at MMIO 0x21f0000 (irq = 61) is a IMX
[ 2.847408] console [ttymxc3] enabled, bootconsole disabled
[ 2.847408] console [ttymxc3] enabled, bootconsole disabled
[ 3.107010] FEC Ethernet Driver
[ 3.110633] gpio_request: gpio--2 (phy-reset) status -22
[ 3.135785] fec_enet_mii_bus: probed
[ 3.144278] sdhci: Secure Digital Host Controller Interface driver
[ 3.150512] sdhci: Copyright(c) Pierre Ossman
[ 3.154880] sdhci-pltfm: SDHCI platform and OF driver helper
[ 3.161538] mxc: set GPIO 75 to high trigger
[ 3.171679] mmc0: SDHCI controller on 2198000.usdhc [2198000.usdhc] using ADM
A
[ 3.181123] mmc1: SDHCI controller on 219c000.usdhc [219c000.usdhc] using ADM
A
[ 3.216198] oprofile: hardware counters not available
[ 3.221263] oprofile: using timer interrupt.
[ 3.388750] TCP cubic registered
[ 3.391992] Initializing XFRM netlink socket
[ 3.397040] NET: Registered protocol family 10
[ 3.404235] IPv6 over IPv4 tunneling driver
[ 3.410979] NET: Registered protocol family 17
[ 3.415519] NET: Registered protocol family 15
[ 3.420323] Registering the dns_resolver key type
[ 3.425514] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9
rev 4
[ 3.520947] Freeing init memory: 260K
Loading, please wait...
[ 3.713140]
[ 3.714647] ======================================================
[ 3.720836] [ INFO: possible circular locking dependency detected ]
[ 3.727112] 3.2.0-rc1+ #54
[ 3.729825] -------------------------------------------------------
[ 3.736102] udevd/368 is trying to acquire lock:
[ 3.740728] (&sig->cred_guard_mutex){+.+.+.}, at: [<8013c688>] lock_trace+0x
28/0x5c
[ 3.748544]
[ 3.748547] but task is already holding lock:
[ 3.754398] (&sb->s_type->i_mutex_key#6){+.+.+.}, at: [<800fc954>] do_lookup
+0x1e4/0x32c
[ 3.762665]
[ 3.762668] which lock already depends on the new lock.
[ 3.762674]
[ 3.770879]
[ 3.770882] the existing dependency chain (in reverse order) is:
[ 3.778383]
[ 3.778386] -> #1 (&sb->s_type->i_mutex_key#6){+.+.+.}:
[ 3.785170] [<8006a7d8>] __lock_acquire+0x14c4/0x1a04
[ 3.790871] [<8006b34c>] lock_acquire+0x124/0x148
[ 3.796211] [<8047999c>] mutex_lock_nested+0x5c/0x394
[ 3.801903] [<800fc954>] do_lookup+0x1e4/0x32c
[ 3.806982] [<800fcc6c>] link_path_walk+0x1d0/0x7d0
[ 3.812495] [<800fe750>] path_openat+0xac/0x374
[ 3.817661] [<800feb48>] do_filp_open+0x3c/0x88
[ 3.822826] [<800f5e98>] open_exec+0x2c/0x100
[ 3.827818] [<800f7544>] do_execve+0xd4/0x2a8
[ 3.832810] [<80011790>] sys_execve+0x44/0x64
[ 3.837815] [<8000db00>] ret_fast_syscall+0x0/0x3c
[ 3.843245]
[ 3.843248] -> #0 (&sig->cred_guard_mutex){+.+.+.}:
[ 3.849667] [<80067aa4>] print_circular_bug+0x68/0x2b8
[ 3.855445] [<8006a5ac>] __lock_acquire+0x1298/0x1a04
[ 3.861132] [<8006b34c>] lock_acquire+0x124/0x148
[ 3.866470] [<804791a8>] mutex_lock_killable_nested+0x60/0x464
[ 3.872940] [<8013c688>] lock_trace+0x28/0x5c
[ 3.877932] [<8013e79c>] proc_lookupfd_common+0x60/0xc8
[ 3.883795] [<8013e844>] proc_lookupfd+0x1c/0x24
[ 3.889047] [<800fa96c>] d_alloc_and_lookup+0x54/0x70
[ 3.894738] [<800fc978>] do_lookup+0x208/0x32c
[ 3.899816] [<800fd434>] path_lookupat+0xfc/0x6c8
[ 3.905155] [<800fda2c>] do_path_lookup+0x2c/0x68
[ 3.910495] [<800feab0>] user_path_at_empty+0x68/0x98
[ 3.916181] [<800feb04>] user_path_at+0x24/0x2c
[ 3.921346] [<800f4eb4>] vfs_fstatat+0x44/0x74
[ 3.926425] [<800f4f40>] vfs_stat+0x2c/0x30
[ 3.931243] [<800f5164>] sys_stat64+0x24/0x40
[ 3.936234] [<8000db00>] ret_fast_syscall+0x0/0x3c
[ 3.941662]
[ 3.941666] other info that might help us debug this:
[ 3.941671]
[ 3.949702] Possible unsafe locking scenario:
[ 3.949708]
[ 3.955644] CPU0 CPU1
[ 3.960181] ---- ----
[ 3.964718] lock(&sb->s_type->i_mutex_key);
[ 3.969114] lock(&sig->cred_guard_mutex);
[ 3.975853] lock(&sb->s_type->i_mutex_key);
[ 3.982766] lock(&sig->cred_guard_mutex);
[ 3.986984]
[ 3.986987] *** DEADLOCK ***
[ 3.986991]
[ 3.992941] 1 lock held by udevd/368:
[ 3.996609] #0: (&sb->s_type->i_mutex_key#6){+.+.+.}, at: [<800fc954>] do_l
ookup+0x1e4/0x32c
[ 4.005314]
[ 4.005317] stack backtrace:
[ 4.009712] [<800151dc>] (unwind_backtrace+0x0/0xec) from [<80477088>] (dump_
stack+0x20/0x24)
[ 4.018260] [<80477088>] (dump_stack+0x20/0x24) from [<80067ca8>] (print_circ
ular_bug+0x26c/0x2b8)
[ 4.027242] [<80067ca8>] (print_circular_bug+0x26c/0x2b8) from [<8006a5ac>] (
__lock_acquire+0x1298/0x1a04)
[ 4.036918] [<8006a5ac>] (__lock_acquire+0x1298/0x1a04) from [<8006b34c>] (lo
ck_acquire+0x124/0x148)
[ 4.046072] [<8006b34c>] (lock_acquire+0x124/0x148) from [<804791a8>] (mutex_
lock_killable_nested+0x60/0x464)
[ 4.056007] [<804791a8>] (mutex_lock_killable_nested+0x60/0x464) from [<8013c
688>] (lock_trace+0x28/0x5c)
[ 4.065593] [<8013c688>] (lock_trace+0x28/0x5c) from [<8013e79c>] (proc_looku
pfd_common+0x60/0xc8)
[ 4.074572] [<8013e79c>] (proc_lookupfd_common+0x60/0xc8) from [<8013e844>] (
proc_lookupfd+0x1c/0x24)
[ 4.083812] [<8013e844>] (proc_lookupfd+0x1c/0x24) from [<800fa96c>] (d_alloc
_and_lookup+0x54/0x70)
[ 4.092878] [<800fa96c>] (d_alloc_and_lookup+0x54/0x70) from [<800fc978>] (do
_lookup+0x208/0x32c)
[ 4.101771] [<800fc978>] (do_lookup+0x208/0x32c) from [<800fd434>] (path_look
upat+0xfc/0x6c8)
[ 4.110317] [<800fd434>] (path_lookupat+0xfc/0x6c8) from [<800fda2c>] (do_pat
h_lookup+0x2c/0x68)
[ 4.119123] [<800fda2c>] (do_path_lookup+0x2c/0x68) from [<800feab0>] (user_p
ath_at_empty+0x68/0x98)
[ 4.128275] [<800feab0>] (user_path_at_empty+0x68/0x98) from [<800feb04>] (us
er_path_at+0x24/0x2c)
[ 4.137253] [<800feb04>] (user_path_at+0x24/0x2c) from [<800f4eb4>] (vfs_fsta
tat+0x44/0x74)
[ 4.145621] [<800f4eb4>] (vfs_fstatat+0x44/0x74) from [<800f4f40>] (vfs_stat+
0x2c/0x30)
[ 4.153642] [<800f4f40>] (vfs_stat+0x2c/0x30) from [<800f5164>] (sys_stat64+0
x24/0x40)
[ 4.161579] [<800f5164>] (sys_stat64+0x24/0x40) from [<8000db00>] (ret_fast_s
yscall+0x0/0x3c)
[ 4.173173] udevd[369]: starting version 173
[ 4.585095] mmc0: new high speed SDHC card at address aaaa
[ 4.591330] mmcblk0: mmc0:aaaa SD04G 3.69 GiB
[ 4.597998] mmcblk0: p1 p2 p3
[ 5.384460] mmc1: host does not support reading read-only switch. assuming wr
ite-enable.
[ 5.796502] mmc1: new high speed SDHC card at address 1234
[ 5.803063] mmcblk1: mmc1:1234 SA04G 3.68 GiB
[ 5.810156] mmcblk1: p1 p2 p3
[ 6.032487] EXT4-fs (mmcblk0p3): INFO: recovery required on readonly filesyst
em
[ 6.039849] EXT4-fs (mmcblk0p3): write access will be enabled during recovery
[ 6.064099] EXT4-fs (mmcblk0p3): recovery complete
[ 6.074078] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. O
pts: (null)
Last login: Thu Jan 1 00:00:27 UTC 1970 on tty1
Welcome to Linaro 11.10 (development branch) (GNU/Linux 3.2.0-rc1+ armv7l)
* Documentation: https://wiki.linaro.org/
root@...aro-nano:~#
View attachment ".config" of type "text/plain" (39057 bytes)
Powered by blists - more mailing lists