lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
Date:	Thu, 04 Jun 2015 08:50:11 +0800
From:	Huang Ying <ying.huang@...el.com>
To:	Trond Myklebust <trond.myklebust@...marydata.com>
CC:	LKML <linux-kernel@...r.kernel.org>, LKP ML <lkp@...org>
Subject: [lkp] [NFSv4] 5cae02f4279: INFO: task dmesg:3593 blocked for more
 than 120 seconds.

FYI, we noticed the below changes on

git://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 5cae02f42793130e1387f4ec09c4d07056ce9fa5 ("NFSv4: Always drain the slot table before re-establishing the lease")


+--------------------------------------------------+------------+------------+
|                                                  | e8d975e73e | 5cae02f427 |
+--------------------------------------------------+------------+------------+
| boot_successes                                   | 2          | 1          |
| boot_failures                                    | 20         | 20         |
| BUG:kernel_test_crashed                          | 20         |            |
| INFO:task_blocked_for_more_than#seconds          | 0          | 16         |
| RIP:native_safe_halt                             | 0          | 16         |
| RIP:flat_send_IPI_mask                           | 0          | 16         |
| RIP:mark_lock                                    | 0          | 1          |
| Kernel_panic-not_syncing:hung_task:blocked_tasks | 0          | 16         |
| backtrace:__close_fd                             | 0          | 15         |
| backtrace:SyS_close                              | 0          | 15         |
| backtrace:cpu_startup_entry                      | 0          | 13         |
| backtrace:watchdog                               | 0          | 16         |
| backtrace:ep_poll                                | 0          | 7          |
| backtrace:SyS_epoll_wait                         | 0          | 8          |
| BUG:kernel_test_hang                             | 0          | 4          |
| RIP:lock_is_held                                 | 0          | 1          |
| RIP:__switch_to                                  | 0          | 1          |
| RIP:ep_send_events_proc                          | 0          | 1          |
| RIP:ep_poll                                      | 0          | 1          |
| RIP:__schedule                                   | 0          | 1          |
| RIP:find_busiest_group                           | 0          | 1          |
| RIP:__lock_is_held                               | 0          | 1          |
| RIP:pvclock_clocksource_read                     | 0          | 2          |
| RIP:lock_acquired                                | 0          | 1          |
+--------------------------------------------------+------------+------------+


[  185.679164] ??? Writer stall state 8 g1925 c1925 f0x0
[  185.680231] rcu_sched: wait state: 1 ->state: 0x1
[  185.681258] rcu_bh: wait state: 1 ->state: 0x1
[  240.431123] INFO: task dmesg:3593 blocked for more than 120 seconds.
[  240.432384]       Not tainted 4.1.0-rc6-wl-ath-03998-g482548f #15
[  240.433582] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  240.435373] dmesg           D ffff88007da07b08 13320  3593   3554 0x00000000
[  240.436804]  ffff88007da07b08 0000000000000000 ffff8800763d83c0 ffff88007db10000
[  240.438839]  ffff8800744d0000 ffff88007da07ae8 ffff88007da08000 ffff8800763d7840
[  240.440869]  7fffffffffffffff ffffffff81c6812e 0000000000000002 ffff88007da07b28
[  240.442897] Call Trace:
[  240.443677]  [<ffffffff81c6812e>] ? bit_wait+0x4b/0x4b
[  240.444755]  [<ffffffff81c67a8c>] schedule+0x74/0x83
[  240.445810]  [<ffffffff81c6b3c8>] schedule_timeout+0x30/0x336
[  240.446953]  [<ffffffff810c08fb>] ? local_clock+0x19/0x22
[  240.448056]  [<ffffffff810d59b7>] ? lock_release_holdtime+0xd1/0xdd
[  240.449328]  [<ffffffff81109bd2>] ? ktime_get+0x90/0x119
[  240.450428]  [<ffffffff81c6812e>] ? bit_wait+0x4b/0x4b
[  240.451513]  [<ffffffff8107edd6>] ? kvm_clock_read+0x25/0x2e
[  240.452639]  [<ffffffff8107ede8>] ? kvm_clock_get_cycles+0x9/0xb
[  240.453804]  [<ffffffff81109c01>] ? ktime_get+0xbf/0x119
[  240.454898]  [<ffffffff81c6812e>] ? bit_wait+0x4b/0x4b
[  240.455970]  [<ffffffff81c66a72>] io_schedule_timeout+0xba/0x131
[  240.457136]  [<ffffffff810ceaef>] ? prepare_to_wait+0x7e/0x89
[  240.458283]  [<ffffffff81c6817f>] bit_wait_io+0x51/0x55
[  240.459371]  [<ffffffff81c67e21>] __wait_on_bit+0x4d/0x82
[  240.460482]  [<ffffffff81178bbb>] ? find_get_pages_tag+0x1fd/0x25b
[  240.461673]  [<ffffffff81178206>] wait_on_page_bit+0x76/0x78
[  240.462806]  [<ffffffff810ceffd>] ? autoremove_wake_function+0x3d/0x3d
[  240.464034]  [<ffffffff81178315>] filemap_fdatawait_range+0xc9/0x13a
[  240.465244]  [<ffffffff81179e8e>] filemap_write_and_wait_range+0x42/0x5b
[  240.466500]  [<ffffffff81315fec>] nfs4_file_fsync+0x10d/0x3bb
[  240.467645]  [<ffffffff811fcb73>] vfs_fsync_range+0x8f/0x9e
[  240.468771]  [<ffffffff811fcb9e>] vfs_fsync+0x1c/0x1e
[  240.469838]  [<ffffffff812ebcf9>] nfs_file_flush+0x73/0x77
[  240.470952]  [<ffffffff811cc8f0>] filp_close+0x40/0x75
[  240.472026]  [<ffffffff811ea9e6>] __close_fd+0xc2/0xdd
[  240.473097]  [<ffffffff811cc948>] SyS_close+0x23/0x4c
[  240.474165]  [<ffffffff81c6cf2e>] system_call_fastpath+0x12/0x76
[  240.475332] no locks held by dmesg/3593.
[  240.476270] sending NMI to all CPUs:
[  240.478218] NMI backtrace for cpu 0
[  240.479100] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.1.0-rc6-wl-ath-03998-g482548f #15
[  240.480926] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[  240.482964] task: ffffffff82264580 ti: ffffffff82254000 task.ti: ffffffff82254000
[  240.484709] RIP: 0010:[<ffffffff8107efd1>]  [<ffffffff8107efd1>] native_safe_halt+0x6/0x8
[  240.486530] RSP: 0018:ffffffff82257e88  EFLAGS: 00000202
[  240.487622] RAX: ffffffff82264580 RBX: 0000000000000000 RCX: 000000000000000f
[  240.488965] RDX: 0000000000000001 RSI: 0000000000000004 RDI: ffffffff82264580
[  240.490290] RBP: ffffffff82257e88 R08: 0000000000000006 R09: 0000000000000001
[  240.491619] R10: 0000000000000040 R11: ffffffff83530ff8 R12: 0000000000000000
[  240.492958] R13: ffffffff82254000 R14: ffffffff82254000 R15: ffffffff82254000
[  240.494250] FS:  0000000000000000(0000) GS:ffff880075c00000(0000) knlGS:0000000000000000
[  240.496065] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  240.497202] CR2: 00007fe0cf6fd000 CR3: 000000000225f000 CR4: 00000000000006f0
[  240.498505] Stack:
[  240.499227]  ffffffff82257ea8 ffffffff8104f427 0000000000000000 0000000000000000
[  240.500962]  ffffffff82257eb8 ffffffff8104fdd3 ffffffff82257f18 ffffffff810cf58e
[  240.502700]  ffffffff82257ed8 ffffffff82258000 ffff880078c88c80 ffffffff82258000
[  240.504429] Call Trace:
[  240.505203]  [<ffffffff8104f427>] default_idle+0x62/0x1db
[  240.506304]  [<ffffffff8104fdd3>] arch_cpu_idle+0xf/0x11
[  240.507392]  [<ffffffff810cf58e>] cpu_idle_loop+0x394/0x50f
[  240.508513]  [<ffffffff810cf71c>] cpu_startup_entry+0x13/0x13
[  240.509649]  [<ffffffff81c4d8b6>] rest_init+0x13d/0x143
[  240.510726]  [<ffffffff825f7f0a>] start_kernel+0x433/0x440
[  240.511838]  [<ffffffff825f7120>] ? early_idt_handler_array+0x120/0x120
[  240.513070]  [<ffffffff825f74a2>] x86_64_start_reservations+0x2a/0x2c
[  240.514284]  [<ffffffff825f75dc>] x86_64_start_kernel+0x138/0x147
[  240.515467] Code: 48 89 e5 0f 09 5d c3 55 48 89 e5 9c 58 5d c3 55 48 89 e5 57 9d 5d c3 55 48 89 e5 fa 5d c3 55 48 89 e5 fb 5d c3 55 48 89 e5 fb f4 <5d> c3 55 48 89 e5 f4 5d c3 55 49 89 ca 49 89 d1 8b 07 48 89 e5 
[  240.518874] NMI backtrace for cpu 1
[  240.519766] CPU: 1 PID: 620 Comm: khungtaskd Not tainted 4.1.0-rc6-wl-ath-03998-g482548f #15
[  240.521624] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[  240.523661] task: ffff8800744d25b0 ti: ffff88007527c000 task.ti: ffff88007527c000
[  240.525397] RIP: 0010:[<ffffffff810785b4>]  [<ffffffff810785b4>] flat_send_IPI_mask+0x77/0xa9
[  240.527255] RSP: 0018:ffff88007527fd58  EFLAGS: 00000046
[  240.528343] RAX: 000000000f000000 RBX: 0000000000000c00 RCX: 0000000000000000
[  240.529639] RDX: 0000000000000c00 RSI: ffffffff81078569 RDI: 0000000000000300
[  240.530930] RBP: ffff88007527fd78 R08: 0000000000000001 R09: 0000000000000000
[  240.532229] R10: ffff880078a023a0 R11: 00000000000fffa0 R12: 0000000000000246
[  240.533525] R13: 000000000000000f R14: 0000000000000002 R15: ffff8800744d0330
[  240.534813] FS:  0000000000000000(0000) GS:ffff880075e00000(0000) knlGS:0000000000000000
[  240.536621] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  240.537760] CR2: 00007f84d08c2220 CR3: 000000000225f000 CR4: 00000000000006e0
[  240.539051] Stack:
[  240.539756]  0000000000000040 000000000000c700 0000000000000001 0000000000000078
[  240.541483]  ffff88007527fdc8 ffffffff81075bf6 ffff88007527fdb8 ffffffff810d5e8d
[  240.543204]  ffff88007527fc8c ffff8800744d0000 ffff8800744d0000 0000000000000003
[  240.544936] Call Trace:
[  240.545709]  [<ffffffff81075bf6>] arch_trigger_all_cpu_backtrace+0x162/0x294
[  240.546993]  [<ffffffff810d5e8d>] ? lockdep_print_held_locks+0x3b/0x9d
[  240.548209]  [<ffffffff8113d035>] watchdog+0x47b/0x5bc
[  240.549283]  [<ffffffff8113cbba>] ? reset_hung_task_detector+0x15/0x15
[  240.550510]  [<ffffffff810aefa3>] kthread+0x116/0x11e
[  240.551574]  [<ffffffff810d7f0a>] ? trace_hardirqs_on+0xd/0xf
[  240.552712]  [<ffffffff810aee8d>] ? kthread_create_on_node+0x1b6/0x1b6
[  240.553935]  [<ffffffff81c6d362>] ret_from_fork+0x42/0x70
[  240.555039]  [<ffffffff810aee8d>] ? kthread_create_on_node+0x1b6/0x1b6
[  240.556262] Code: c4 10 74 04 f3 90 eb f0 44 89 e8 c1 e0 18 89 04 25 10 c3 5f ff 44 89 f2 09 da 80 cf 04 41 83 fe 02 0f 44 d3 89 14 25 00 c3 5f ff <41> f7 c4 00 02 00 00 75 11 4c 89 e7 57 9d 66 66 90 66 90 e8 fa 
[  240.559680] NMI backtrace for cpu 2
[  240.560568] CPU: 2 PID: 2085 Comm: systemd-journal Not tainted 4.1.0-rc6-wl-ath-03998-g482548f #15
[  240.562477] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[  240.564516] task: ffff88007ddc8000 ti: ffff88007d8dc000 task.ti: ffff88007d8dc000
[  240.566256] RIP: 0010:[<ffffffff810c066f>]  [<ffffffff810c066f>] sched_clock_stable+0x6/0x14
[  240.568107] RSP: 0018:ffff88007d8dfd20  EFLAGS: 00000046
[  240.569196] RAX: 0000000000000000 RBX: ffff88007ddc8000 RCX: ffff88007ddc8898
[  240.570492] RDX: 0000000000000038 RSI: 0000000000000001 RDI: 0000000000000002
[  240.571788] RBP: ffff88007d8dfd48 R08: 0000000000000004 R09: 0000000000000001
[  240.573078] R10: ffff88007ddc8000 R11: 000000000000bc45 R12: ffff88007ddc8898
[  240.574370] R13: 0000000000000002 R14: 0000000000000001 R15: 0000000000000046
[  240.575666] FS:  00007f86bba7d840(0000) GS:ffff880076000000(0000) knlGS:0000000000000000
[  240.577471] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  240.578608] CR2: 00007f86b9b76018 CR3: 000000007f35f000 CR4: 00000000000006e0
[  240.579901] Stack:
[  240.580630]  ffffffff810c080e ffff88007ddc8898 ffff88007ddc8000 ffff88007ddc8898
[  240.582357]  ffffffff8120d086 ffff88007d8dfd58 ffffffff810c08fb ffff88007d8dfd78
[  240.584090]  ffffffff810d58f5 ffff88007ddc8000 ffff88007d861a58 ffff88007d8dfdb8
[  240.585824] Call Trace:
[  240.586600]  [<ffffffff810c080e>] ? sched_clock_cpu+0x12/0xaf
[  240.587734]  [<ffffffff8120d086>] ? ep_scan_ready_list+0x98/0x221
[  240.588913]  [<ffffffff810c08fb>] local_clock+0x19/0x22
[  240.589996]  [<ffffffff810d58f5>] lock_release_holdtime+0xf/0xdd
[  240.591232]  [<ffffffff810da3f2>] lock_release+0x23a/0x33f
[  240.592344]  [<ffffffff81c6c582>] _raw_spin_unlock_irqrestore+0x24/0x5d
[  240.593587]  [<ffffffff8120c5be>] ? ep_read_events_proc+0xf2/0xf2
[  240.594758]  [<ffffffff8120d086>] ep_scan_ready_list+0x98/0x221
[  240.595913]  [<ffffffff8120d3aa>] ep_poll+0x177/0x2f9
[  240.596973]  [<ffffffff810bca6d>] ? wake_up_process+0x39/0x39
[  240.598108]  [<ffffffff8120e51e>] SyS_epoll_wait+0x96/0xbb
[  240.599214]  [<ffffffff81c6cf2e>] system_call_fastpath+0x12/0x76
[  240.600379] Code: 48 85 c9 48 0f 4e d7 48 39 c2 48 0f 49 d0 48 89 f0 f0 49 0f b1 54 24 10 48 39 f0 75 9c 5b 48 89 d0 41 5c 5d c3 8b 05 f5 33 71 01 <55> 48 89 e5 5d 85 c0 0f 9f c0 0f b6 c0 c3 55 48 89 e5 e8 e3 ff 
[  240.603794] NMI backtrace for cpu 3
[  240.604684] CPU: 3 PID: 0 Comm: swapper/3 Not tainted 4.1.0-rc6-wl-ath-03998-g482548f #15
[  240.617413] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[  240.619454] task: ffff8800757825b0 ti: ffff880075794000 task.ti: ffff880075794000
[  240.621189] RIP: 0010:[<ffffffff8107efd1>]  [<ffffffff8107efd1>] native_safe_halt+0x6/0x8
[  240.623012] RSP: 0018:ffff880075797e88  EFLAGS: 00000206
[  240.624099] RAX: ffff8800757825b0 RBX: 0000000000000000 RCX: 000000000000000f
[  240.625386] RDX: 0000000000000001 RSI: 0000000000000004 RDI: ffff8800757825b0
[  240.626679] RBP: ffff880075797e88 R08: 0000000000000004 R09: 0000000000000001
[  240.627968] R10: 0000000000000016 R11: 0000000000000005 R12: 0000000000000003
[  240.629264] R13: ffff880075794000 R14: ffff880075794000 R15: ffff880075794000
[  240.630560] FS:  0000000000000000(0000) GS:ffff880076200000(0000) knlGS:0000000000000000
[  240.632369] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[  240.633507] CR2: 00007f9ba94e9000 CR3: 000000000225f000 CR4: 00000000000006e0
[  240.634795] Stack:
[  240.635519]  ffff880075797ea8 ffffffff8104f427 0000000000000000 0000000000000000
[  240.637242]  ffff880075797eb8 ffffffff8104fdd3 ffff880075797f18 ffffffff810cf58e
[  240.638980]  ffffffff822b6750 0000000000000286 ffff880075797f08 ffff880075798000
[  240.640719] Call Trace:
[  240.641491]  [<ffffffff8104f427>] default_idle+0x62/0x1db
[  240.642590]  [<ffffffff8104fdd3>] arch_cpu_idle+0xf/0x11
[  240.643676]  [<ffffffff810cf58e>] cpu_idle_loop+0x394/0x50f
[  240.644796]  [<ffffffff810cf71c>] cpu_startup_entry+0x13/0x13
[  240.645933]  [<ffffffff81071f57>] start_secondary+0x14d/0x151
[  240.647068] Code: 48 89 e5 0f 09 5d c3 55 48 89 e5 9c 58 5d c3 55 48 89 e5 57 9d 5d c3 55 48 89 e5 fa 5d c3 55 48 89 e5 fb 5d c3 55 48 89 e5 fb f4 <5d> c3 55 48 89 e5 f4 5d c3 55 49 89 ca 49 89 d1 8b 07 48 89 e5 
[  240.650548] Kernel panic - not syncing: hung_task: blocked tasks
[  240.651483] CPU: 1 PID: 620 Comm: khungtaskd Not tainted 4.1.0-rc6-wl-ath-03998-g482548f #15
[  240.651483] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[  240.651483]  ffff8800744d0000 ffff88007527fd48 ffffffff81c5d7a5 0000000000000006
[  240.651483]  ffffffff820c4053 ffff88007527fdc8 ffffffff81c57323 ffff88007620cc98
[  240.651483]  0000000000000008 ffff88007527fdd8 ffff88007527fd78 ffff88007527fdc8
[  240.651483] Call Trace:
[  240.651483]  [<ffffffff81c5d7a5>] dump_stack+0x4c/0x65
[  240.651483]  [<ffffffff81c57323>] panic+0xc9/0x1f7
[  240.651483]  [<ffffffff8113d043>] watchdog+0x489/0x5bc
[  240.651483]  [<ffffffff8113cbba>] ? reset_hung_task_detector+0x15/0x15
[  240.651483]  [<ffffffff810aefa3>] kthread+0x116/0x11e
[  240.651483]  [<ffffffff810d7f0a>] ? trace_hardirqs_on+0xd/0xf
[  240.651483]  [<ffffffff810aee8d>] ? kthread_create_on_node+0x1b6/0x1b6
[  240.651483]  [<ffffffff81c6d362>] ret_from_fork+0x42/0x70
[  240.651483]  [<ffffffff810aee8d>] ? kthread_create_on_node+0x1b6/0x1b6
[  240.651483] Kernel Offset: disabled

Elapsed time: 250



Thanks,
Ying Huang
-------------------------------------
lkp@...ists.intel.com
https://eclists.intel.com/sympa/info/lkp
Unsubscribe by sending email to sympa@...ists.intel.com with subject "Unsubscribe lkp"



View attachment "config-4.1.0-rc6-00202-g5cae02f" of type "text/plain" (103213 bytes)

View attachment ".dmesg" of type "text/plain" (46792 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ