libspe2:test_context_create_error on 4.12-rc1 and 4.2-rc2
Sombat Tragolgosol
sombat3960 at gmail.com
Fri May 26 19:22:31 AEST 2017
Dear Sir,
I do run test libspe2. For For 4.4.69, 4.10.17 and 4.11.3, they all seem
PASS even having error almost the same, but for 4.12-rc1 and 4.12-rc2 , it
is hanged up on test_context_create_error.
please see my two files attached for 4.11.3 and 4.12-rc2.
4.11.3
[ 240.975893] WARNING: CPU: 0 PID: 3543 at fs/dcache.c:304
.dentry_free+0x18/0xc8
4.12-rc2
[ 1191.234695] kernel BUG at fs/dcache.c:304!
[ 1191.234705] Oops: Exception in kernel mode, sig: 5 [#1]
Best regards,
Sombat t.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ozlabs.org/pipermail/linuxppc-dev/attachments/20170526/c5bdb9da/attachment-0001.html>
-------------- next part --------------
[ 240.973779] list_del corruption. prev->next should be c00000000a7658d0, but was c00000000a48b460
[ 240.974548] ------------[ cut here ]------------
[ 240.974570] WARNING: CPU: 0 PID: 3543 at lib/list_debug.c:53 .__list_del_entry_valid+0xdc/0x108
[ 240.974579] Modules linked in: ps3nflash ps3flash cfg80211 bnep spufs input_leds evdev led_class usbhid btusb btrtl btbcm btintel bluetooth ps3_jupiter_sta ps3_jupiter ohci_hcd snd_ps3 snd_pcm snd_timer ehci_hcd ps3fb snd usbcore soundcore syscopyarea ps3_disp_manager ps3_lpm sysfillrect usb_common sysimgblt ps3rom ps3_gelic fb_sys_fops
[ 240.974876] CPU: 0 PID: 3543 Comm: test_context_cr Not tainted 4.11.3 #1
[ 240.974886] task: c000000006446400 task.stack: c000000009324000
[ 240.974896] NIP: c00000000036f0bc LR: c00000000036f0b8 CTR: 0000000000000000
[ 240.974906] REGS: c000000009327820 TRAP: 0700 Not tainted (4.11.3)
[ 240.974915] MSR: 8000000000028032 <SF,EE,IR,DR,RI>
[ 240.974978] CR: 22002822 XER: 20000000
[ 240.974986] SOFTE: 1
GPR00: c00000000036f0b8 c000000009327aa0 c0000000008c3300 0000000000000054
GPR04: c00000000de04620 c00000000de07f80 00000000a4d82c17 c000000006446b00
GPR08: 0000000000000007 0000000000000000 0000000000000000 0000000000000001
GPR12: 0000000022002884 c000000007ffe000 0000000024022422 00000000100e20a4
GPR16: 00000000100e5d40 0000000042222420 0000000000000000 00000000100e8780
GPR20: 0000000000000000 0000000000000000 00000000100e8788 c00000000a48b3e0
GPR24: 0000000000000000 c00000000a48b360 0000000000000001 0000000000000001
GPR28: 0000000000080060 c00000000a765860 c00000000a48b360 c00000000a7657e0
[ 240.975349] NIP [c00000000036f0bc] .__list_del_entry_valid+0xdc/0x108
[ 240.975360] LR [c00000000036f0b8] .__list_del_entry_valid+0xd8/0x108
[ 240.975368] Call Trace:
[ 240.975380] [c000000009327aa0] [c00000000036f0b8] .__list_del_entry_valid+0xd8/0x108 (unreliable)
[ 240.975409] [c000000009327b10] [c00000000018d068] .__dentry_kill+0x88/0x240
[ 240.975428] [c000000009327ba0] [c00000000018d5cc] .dput+0x3ac/0x508
[ 240.975449] [c000000009327c60] [c00000000017d09c] .done_path_create+0x1c/0x70
[ 240.975509] [c000000009327ce0] [d00000000030701c] .do_spu_create+0x7c/0xd0 [spufs]
[ 240.975531] [c000000009327d80] [c00000000003a100] .SyS_spu_create+0x190/0x3a8
[ 240.975555] [c000000009327e30] [c00000000000a3ac] system_call+0x38/0x104
[ 240.975571] Instruction dump:
[ 240.975588] 3c62ffe8 386381c0 482826e5 60000000 0fe00000 38600000 4bffffa4 7c641b78
[ 240.975660] 3c62ffe8 386381f8 482826c5 60000000 <0fe00000> 38600000 4bffff84 7c641b78
[ 240.975732] ---[ end trace 65eeb59a1a105781 ]---
[ 240.975843] ------------[ cut here ]------------
[ 240.975893] WARNING: CPU: 0 PID: 3543 at fs/dcache.c:304 .dentry_free+0x18/0xc8
[ 240.975901] Modules linked in: ps3nflash ps3flash cfg80211 bnep spufs input_leds evdev led_class usbhid btusb btrtl btbcm btintel bluetooth ps3_jupiter_sta ps3_jupiter ohci_hcd snd_ps3 snd_pcm snd_timer ehci_hcd ps3fb snd usbcore soundcore syscopyarea ps3_disp_manager ps3_lpm sysfillrect usb_common sysimgblt ps3rom ps3_gelic fb_sys_fops
[ 240.976172] CPU: 0 PID: 3543 Comm: test_context_cr Tainted: G W 4.11.3 #1
[ 240.976181] task: c000000006446400 task.stack: c000000009324000
[ 240.976190] NIP: c00000000018cf30 LR: c00000000018d5cc CTR: 0000000000000000
[ 240.976200] REGS: c0000000093278b0 TRAP: 0700 Tainted: G W (4.11.3)
[ 240.976208] MSR: 8000000000028032 <SF,EE,IR,DR,RI>
[ 240.976266] CR: 22002822 XER: 20000000
[ 240.976273] SOFTE: 1
GPR00: c00000000018d5cc c000000009327b30 c0000000008c3300 c00000000a7657e0
GPR04: 1a08200000000000 c00000000018d170 00000000e2ec2f41 c000000006446ab0
GPR08: fffffffff96264f1 0000000000000001 c00000000086ce48 0000000000000001
GPR12: 0000000022002824 c000000007ffe000 0000000024022422 00000000100e20a4
GPR16: 00000000100e5d40 0000000042222420 0000000000000000 00000000100e8780
GPR20: 0000000000000000 0000000000000000 00000000100e8788 c00000000a48b3e0
GPR24: 0000000000000000 c00000000a48b360 0000000000000001 0000000000000001
GPR28: 0000000000080060 0000000000080040 c00000000a765860 c00000000a7657e0
[ 240.976585] NIP [c00000000018cf30] .dentry_free+0x18/0xc8
[ 240.976596] LR [c00000000018d5cc] .dput+0x3ac/0x508
[ 240.976603] Call Trace:
[ 240.976614] [c000000009327ba0] [c00000000018d5cc] .dput+0x3ac/0x508
[ 240.976632] [c000000009327c60] [c00000000017d09c] .done_path_create+0x1c/0x70
[ 240.976670] [c000000009327ce0] [d00000000030701c] .do_spu_create+0x7c/0xd0 [spufs]
[ 240.976690] [c000000009327d80] [c00000000003a100] .SyS_spu_create+0x190/0x3a8
[ 240.976709] [c000000009327e30] [c00000000000a3ac] system_call+0x38/0x104
[ 240.976724] Instruction dump:
[ 240.976739] 4bfd6471 60000000 38210070 e8010010 7c0803a6 4e800020 7c0802a6 f8010010
[ 240.976806] f821ff91 e9430118 312affff 7d295110 <0b090000> e9430050 39230060 7faa4800
[ 240.976873] ---[ end trace 65eeb59a1a105782 ]---
[ 241.001754] ------------[ cut here ]------------
[ 241.001783] WARNING: CPU: 0 PID: 7 at kernel/rcu/tree.c:2919 .rcu_process_callbacks+0x65c/0x728
[ 241.001789] Modules linked in: ps3nflash ps3flash cfg80211 bnep spufs input_leds evdev led_class usbhid btusb btrtl btbcm btintel bluetooth ps3_jupiter_sta ps3_jupiter ohci_hcd snd_ps3 snd_pcm snd_timer ehci_hcd ps3fb snd usbcore soundcore syscopyarea ps3_disp_manager ps3_lpm sysfillrect usb_common sysimgblt ps3rom ps3_gelic fb_sys_fops
[ 241.002022] CPU: 0 PID: 7 Comm: ksoftirqd/0 Tainted: G W 4.11.3 #1
[ 241.002030] task: c00000000b076380 task.stack: c00000000b07c000
[ 241.002037] NIP: c0000000000c1fa4 LR: c0000000000c1fc4 CTR: c000000000170aa8
[ 241.002044] REGS: c00000000b07f7c0 TRAP: 0700 Tainted: G W (4.11.3)
[ 241.002050] MSR: 800000000202a032 <SF,VEC,EE,FP,IR,DR,RI>
[ 241.002096] CR: 24002822 XER: 20000000
[ 241.002101] SOFTE: 0
GPR00: c0000000000c1c7c c00000000b07fa40 c0000000008c3300 c0000000000c1fc4
GPR04: 0000000000000001 c0000000000c1c48 000000005e03bfb7 0000000000000000
GPR08: 0000000000000000 0000000000000000 c000000000803300 0000000000000001
GPR12: 0000000024002824 c000000007ffe000 0000000000000000 0000000000000001
GPR16: c0000000007fe100 7fffffffffffffff c00000000086ada8 c00000000de0b6d8
GPR20: c0000000064bea00 c00000000b07c000 c0000000007ff108 c0000000000c1c18
GPR24: 000000000000000a 0000000000000000 0000000000000001 c00000000de0b6b8
GPR28: 0000000000000001 0000000000000006 c0000000007fe100 c00000000de0b680
[ 241.002302] NIP [c0000000000c1fa4] .rcu_process_callbacks+0x65c/0x728
[ 241.002311] LR [c0000000000c1fc4] .rcu_process_callbacks+0x67c/0x728
[ 241.002316] Call Trace:
[ 241.002327] [c00000000b07fa40] [c0000000000c1c7c] .rcu_process_callbacks+0x334/0x728 (unreliable)
[ 241.002347] [c00000000b07fb40] [c00000000004cdc4] .__do_softirq+0x1d4/0x390
[ 241.002361] [c00000000b07fc50] [c00000000004cfbc] .run_ksoftirqd+0x3c/0xb0
[ 241.002375] [c00000000b07fcc0] [c0000000000773ec] .smpboot_thread_fn+0x284/0x290
[ 241.002390] [c00000000b07fd70] [c000000000071f78] .kthread+0x148/0x188
[ 241.002409] [c00000000b07fe30] [c00000000000a6fc] .ret_from_kernel_thread+0x58/0xdc
[ 241.002419] Instruction dump:
[ 241.002429] 7929d182 f95f00b8 e95f0038 7d4a0074 794ad182 7fa95000 419efdbc 3d42fff4
[ 241.002471] 892ad86d 2f890000 409efdac 99ead86d <0fe00000> 4bfffda0 60000000 7f9de378
[ 241.002514] ---[ end trace 65eeb59a1a105783 ]---
[ 250.433368] irq event stamp: 425132
[ 250.433377] ======================================================
[ 250.433379] [ INFO: possible circular locking dependency detected ]
[ 250.433385] 4.11.3 #1 Tainted: G W
[ 250.433387] -------------------------------------------------------
[ 250.433391] test_wbox.elf/3872 is trying to acquire lock:
[ 250.433394] (&mm->mmap_sem){++++++}, at: [<d000000000304b34>] .spufs_ps_fault+0x224/0x238 [spufs]
[ 250.433465]
but task is already holding lock:
[ 250.433467] (&ctx->state_mutex){+.+.+.}, at: [<d000000000304abc>] .spufs_ps_fault+0x1ac/0x238 [spufs]
[ 250.433499]
which lock already depends on the new lock.
[ 250.433502]
the existing dependency chain (in reverse order) is:
[ 250.433506]
-> #1 (&ctx->state_mutex){+.+.+.}:
[ 250.433528] .__mutex_lock+0x84/0xa90
[ 250.433549] .spufs_mem_mmap_fault+0x80/0x128 [spufs]
[ 250.433559] .__do_fault+0x38/0xc8
[ 250.433566] .__handle_mm_fault+0x9a0/0xfa0
[ 250.433572] .handle_mm_fault+0x8c/0x120
[ 250.433580] .do_page_fault+0x3dc/0x778
[ 250.433589] handle_page_fault+0x10/0x30
[ 250.433592]
-> #0 (&mm->mmap_sem){++++++}:
[ 250.433607] .lock_acquire+0x7c/0xf0
[ 250.433613] .down_read+0x34/0x90
[ 250.433633] .spufs_ps_fault+0x224/0x238 [spufs]
[ 250.433640] .__do_fault+0x38/0xc8
[ 250.433646] .__handle_mm_fault+0xcdc/0xfa0
[ 250.433652] .handle_mm_fault+0x8c/0x120
[ 250.433658] .do_page_fault+0x3dc/0x778
[ 250.433664] handle_page_fault+0x10/0x30
[ 250.433666]
other info that might help us debug this:
[ 250.433669] Possible unsafe locking scenario:
[ 250.433671] CPU0 CPU1
[ 250.433674] ---- ----
[ 250.433676] lock(&ctx->state_mutex);
[ 250.433682] lock(&mm->mmap_sem);
[ 250.433689] lock(&ctx->state_mutex);
[ 250.433695] lock(&mm->mmap_sem);
[ 250.433702]
*** DEADLOCK ***
[ 250.433705] 1 lock held by test_wbox.elf/3872:
[ 250.433708] #0: (&ctx->state_mutex){+.+.+.}, at: [<d000000000304abc>] .spufs_ps_fault+0x1ac/0x238 [spufs]
[ 250.433739]
stack backtrace:
[ 250.433746] CPU: 1 PID: 3872 Comm: test_wbox.elf Tainted: G W 4.11.3 #1
[ 250.433749] Call Trace:
[ 250.433759] [c000000006693660] [c0000000005f2ba8] .dump_stack+0xe0/0x14c (unreliable)
[ 250.433769] [c0000000066936f0] [c0000000000a2310] .print_circular_bug+0x288/0x3b8
[ 250.433778] [c0000000066937a0] [c0000000000a6604] .__lock_acquire+0x1bfc/0x1d60
[ 250.433787] [c000000006693920] [c0000000000a6e2c] .lock_acquire+0x7c/0xf0
[ 250.433796] [c0000000066939f0] [c0000000005ecdbc] .down_read+0x34/0x90
[ 250.433819] [c000000006693a70] [d000000000304b34] .spufs_ps_fault+0x224/0x238 [spufs]
[ 250.433829] [c000000006693b40] [c0000000001315d0] .__do_fault+0x38/0xc8
[ 250.433838] [c000000006693bc0] [c0000000001389b4] .__handle_mm_fault+0xcdc/0xfa0
[ 250.433848] [c000000006693ce0] [c000000000138d04] .handle_mm_fault+0x8c/0x120
[ 250.433857] [c000000006693d70] [c00000000002a0ac] .do_page_fault+0x3dc/0x778
[ 250.433867] [c000000006693e30] [c000000000009858] handle_page_fault+0x10/0x30
[ 250.692807] hardirqs last enabled at (425131): [<c0000000005eeea8>] ._raw_spin_unlock_irq+0x38/0x60
[ 250.697027] hardirqs last disabled at (425132): [<c0000000005eec44>] ._raw_spin_lock_irq+0x2c/0x78
[ 250.701155] softirqs last enabled at (391614): [<c00000000004ced8>] .__do_softirq+0x2e8/0x390
[ 250.705350] softirqs last disabled at (391605): [<c00000000004d20c>] .irq_exit+0xf4/0xf8
-------------- next part --------------
[ 1191.232668] list_del corruption. prev->next should be c00000000bd4c7b0, but was c00000000a1b7c40
[ 1191.233448] ------------[ cut here ]------------
[ 1191.233470] WARNING: CPU: 0 PID: 5589 at lib/list_debug.c:53 .__list_del_entry_valid+0xdc/0x108
[ 1191.233478] Modules linked in: ps3nflash ps3flash cfg80211 bnep spufs input_leds led_class evdev usbhid btusb btrtl btbcm btintel bluetooth ps3_jupiter_sta ecdh_generic ps3_jupiter snd_ps3 snd_pcm ohci_hcd ehci_hcd snd_timer usbcore ps3fb syscopyarea snd soundcore sysfillrect ps3_lpm ps3_disp_manager sysimgblt ps3_gelic fb_sys_fops ps3rom usb_common
[ 1191.233788] CPU: 0 PID: 5589 Comm: test_context_cr Not tainted 4.12.0-rc2 #1
[ 1191.233798] task: c00000000bdbe080 task.stack: c00000000bcc4000
[ 1191.233808] NIP: c0000000003737e4 LR: c0000000003737e0 CTR: 0000000000000000
[ 1191.233818] REGS: c00000000bcc7820 TRAP: 0700 Not tainted (4.12.0-rc2)
[ 1191.233826] MSR: 8000000000028032 <SF,EE,IR,DR,RI>
[ 1191.233891] CR: 22002822 XER: 20000000
[ 1191.233899] SOFTE: 1
GPR00: c0000000003737e0 c00000000bcc7aa0 c0000000008bb800 0000000000000054
GPR04: c00000000de04620 c00000000de08000 00000000b147bc39 c00000000bdbe788
GPR08: 0000000000000007 0000000000000000 0000000000000000 0000000000000001
GPR12: 0000000022002884 c000000007ffe000 0000000024022422 00000000100e20a4
GPR16: 00000000100e5d40 0000000042222420 0000000000000000 00000000100e8780
GPR20: 0000000000000000 0000000000000000 00000000100e8788 c00000000a1b7bc0
GPR24: 0000000000000000 c00000000a1b7b40 0000000000000001 0000000000000001
GPR28: 0000000000080060 c00000000bd4c740 c00000000a1b7b40 c00000000bd4c6c0
[ 1191.234239] NIP [c0000000003737e4] .__list_del_entry_valid+0xdc/0x108
[ 1191.234250] LR [c0000000003737e0] .__list_del_entry_valid+0xd8/0x108
[ 1191.234258] Call Trace:
[ 1191.234270] [c00000000bcc7aa0] [c0000000003737e0] .__list_del_entry_valid+0xd8/0x108 (unreliable)
[ 1191.234300] [c00000000bcc7b10] [c000000000191f90] .__dentry_kill+0x88/0x240
[ 1191.234320] [c00000000bcc7ba0] [c0000000001924e4] .dput+0x39c/0x4d0
[ 1191.234343] [c00000000bcc7c60] [c00000000017deec] .done_path_create+0x1c/0x70
[ 1191.234403] [c00000000bcc7ce0] [d00000000017e144] .do_spu_create+0x7c/0xd0 [spufs]
[ 1191.234426] [c00000000bcc7d80] [c00000000003ab18] .SyS_spu_create+0x190/0x3a8
[ 1191.234449] [c00000000bcc7e30] [c00000000000a2ac] system_call+0x38/0x104
[ 1191.234466] Instruction dump:
[ 1191.234483] 3c62ffe9 3863f818 48289f59 60000000 0fe00000 38600000 4bffffa4 7c641b78
[ 1191.234561] 3c62ffe9 3863f850 48289f39 60000000 <0fe00000> 38600000 4bffff84 7c641b78
[ 1191.234634] ---[ end trace 5a4687d9c9f4690e ]---
/*************************************************** Note no exception in 4.11.3 */
[ 1191.234667] ------------[ cut here ]------------
[ 1191.234695] kernel BUG at fs/dcache.c:304!
[ 1191.234705] Oops: Exception in kernel mode, sig: 5 [#1]
[ 1191.234854] SMP NR_CPUS=2
[ 1191.234858] PS3
[ 1191.234983] Modules linked in: ps3nflash ps3flash cfg80211 bnep spufs input_leds led_class evdev usbhid btusb btrtl btbcm btintel bluetooth ps3_jupiter_sta ecdh_generic ps3_jupiter snd_ps3 snd_pcm ohci_hcd ehci_hcd snd_timer usbcore ps3fb syscopyarea snd soundcore sysfillrect ps3_lpm ps3_disp_manager sysimgblt ps3_gelic fb_sys_fops ps3rom usb_common
[ 1191.235864] CPU: 0 PID: 5589 Comm: test_context_cr Tainted: G W 4.12.0-rc2 #1
[ 1191.236074] task: c00000000bdbe080 task.stack: c00000000bcc4000
[ 1191.236229] NIP: c00000000018f5e8 LR: c0000000001924e4 CTR: 0000000000000000
[ 1191.236411] REGS: c00000000bcc78b0 TRAP: 0700 Tainted: G W (4.12.0-rc2)
[ 1191.236614] MSR: 8000000000028032 <SF,EE,IR,DR,RI>
[ 1191.236644] CR: 82002822 XER: 20000000
[ 1191.236887] SOFTE: 1
GPR00: c0000000001924e4 c00000000bcc7b30 c0000000008bb800 c00000000bd4c6c0
GPR04: 1a70200000000000 0000000000000078 0000000004322d3f c00000000bdbe738
GPR08: 0000000057606a9e 0000000000000001 c0000000008646e0 0000000000000001
GPR12: 0000000082002824 c000000007ffe000 0000000024022422 00000000100e20a4
GPR16: 00000000100e5d40 0000000042222420 0000000000000000 00000000100e8780
GPR20: 0000000000000000 0000000000000000 00000000100e8788 c00000000a1b7bc0
GPR24: 0000000000000000 c00000000a1b7b40 0000000000000001 0000000000000001
GPR28: 0000000000080060 0000000000080040 c00000000bd4c740 c00000000bd4c6c0
[ 1191.251352] NIP [c00000000018f5e8] .dentry_free+0x18/0xc8
[ 1191.254707] LR [c0000000001924e4] .dput+0x39c/0x4d0
[ 1191.258062] Call Trace:
[ 1191.261374] [c00000000bcc7ba0] [c0000000001924e4] .dput+0x39c/0x4d0
[ 1191.264749] [c00000000bcc7c60] [c00000000017deec] .done_path_create+0x1c/0x70
[ 1191.268100] [c00000000bcc7ce0] [d00000000017e144] .do_spu_create+0x7c/0xd0 [spufs]
[ 1191.271368] [c00000000bcc7d80] [c00000000003ab18] .SyS_spu_create+0x190/0x3a8
[ 1191.274584] [c00000000bcc7e30] [c00000000000a2ac] system_call+0x38/0x104
[ 1191.277743] Instruction dump:
[ 1191.280735] 4bfd3a09 60000000 38210070 e8010010 7c0803a6 4e800020 7c0802a6 f8010010
[ 1191.283849] f821ff91 e9430118 312affff 7d295110 <0b090000> e9430050 39230060 7faa4800
[ 1191.286949] ---[ end trace 5a4687d9c9f4690f ]---
make -C tests -s check
test_cpu_info.elf: RUNNING
test_cpu_info.elf: PASS
test_direct_access.elf: RUNNING
test_direct_access.elf: PASS
test_single_context.elf: RUNNING
test_single_context.elf: PASS
test_run_image.elf: RUNNING
test_run_image.elf: PASS
test_stop.elf: RUNNING
test_stop.elf: PASS
test_multiple_context.elf: RUNNING
test_multiple_context.elf: PASS
test_nosched_context.elf: RUNNING
test_nosched_context.elf: PASS
test_single_gang_context.elf: RUNNING
test_single_gang_context.elf: PASS
test_context_create_error.elf: RUNNING
spu_create(): Too many open files
/bin/sh: line 6: 5589 Trace/breakpoint trap ./test_context_create_error.elf
test_context_create_error.elf: FAIL
make[4]: *** [/root/libspe2-2.3.0/tests/make.rules:50: check-test_context_create_error.elf] Error 1
test_run_error.elf: RUNNING
More information about the Linuxppc-dev
mailing list