[lkp] [x86/build] b2c51106c75: -18.1% will-it-scale.per_process_ops
by kernel test robot
FYI, we noticed the below changes on
git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip.git x86/asm
commit b2c51106c7581866c37ffc77c5d739f3d4b7cbc9 ("x86/build: Fix detection of GCC -mpreferred-stack-boundary support")
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/test:
wsm/will-it-scale/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/readseek2
commit:
f2a50f8b7da45ff2de93a71393e715a2ab9f3b68
b2c51106c7581866c37ffc77c5d739f3d4b7cbc9
f2a50f8b7da45ff2 b2c51106c7581866c37ffc77c5
---------------- --------------------------
%stddev %change %stddev
\ | \
879002 ± 0% -18.1% 720270 ± 7% will-it-scale.per_process_ops
0.02 ± 0% +34.5% 0.02 ± 7% will-it-scale.scalability
26153173 ± 0% +7.0% 27977076 ± 0% will-it-scale.time.voluntary_context_switches
15.70 ± 2% +14.5% 17.98 ± 0% time.user_time
370683 ± 0% +6.2% 393491 ± 0% vmstat.system.cs
830343 ± 56% -54.0% 382128 ± 39% cpuidle.C1E-NHM.time
788.25 ± 14% -21.7% 617.25 ± 16% cpuidle.C1E-NHM.usage
3947 ± 2% +10.6% 4363 ± 3% slabinfo.kmalloc-192.active_objs
3947 ± 2% +10.6% 4363 ± 3% slabinfo.kmalloc-192.num_objs
1082762 ±162% -100.0% 0.00 ± -1% latency_stats.avg.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
1082762 ±162% -100.0% 0.00 ± -1% latency_stats.max.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
1082762 ±162% -100.0% 0.00 ± -1% latency_stats.sum.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
2.58 ± 8% +19.5% 3.09 ± 3% perf-profile.cpu-cycles._raw_spin_lock_irqsave.finish_wait.__wait_on_bit_lock.__lock_page.find_lock_entry
7.02 ± 3% +9.2% 7.67 ± 2% perf-profile.cpu-cycles._raw_spin_lock_irqsave.prepare_to_wait_exclusive.__wait_on_bit_lock.__lock_page.find_lock_entry
3.07 ± 2% +14.8% 3.53 ± 3% perf-profile.cpu-cycles.finish_wait.__wait_on_bit_lock.__lock_page.find_lock_entry.shmem_getpage_gfp
3.05 ± 5% -8.4% 2.79 ± 4% perf-profile.cpu-cycles.hrtimer_start_range_ns.tick_nohz_stop_sched_tick.__tick_nohz_idle_enter.tick_nohz_idle_enter.cpu_startup_entry
0.98 ± 3% -25.1% 0.74 ± 7% perf-profile.cpu-cycles.is_ftrace_trampoline.print_context_stack.dump_trace.save_stack_trace_tsk.__account_scheduler_latency
1.82 ± 18% +46.6% 2.67 ± 3% perf-profile.cpu-cycles.native_queued_spin_lock_slowpath._raw_spin_lock_irqsave.finish_wait.__wait_on_bit_lock.__lock_page
8.05 ± 3% +9.5% 8.82 ± 3% perf-profile.cpu-cycles.prepare_to_wait_exclusive.__wait_on_bit_lock.__lock_page.find_lock_entry.shmem_getpage_gfp
7.75 ± 34% -64.5% 2.75 ± 64% sched_debug.cfs_rq[2]:/.nr_spread_over
1135 ± 20% -43.6% 640.75 ± 49% sched_debug.cfs_rq[3]:/.blocked_load_avg
1215 ± 21% -43.1% 691.50 ± 46% sched_debug.cfs_rq[3]:/.tg_load_contrib
38.50 ± 21% +129.9% 88.50 ± 36% sched_debug.cfs_rq[4]:/.load
26.00 ± 20% +98.1% 51.50 ± 46% sched_debug.cfs_rq[4]:/.runnable_load_avg
128.25 ± 18% +227.5% 420.00 ± 43% sched_debug.cfs_rq[4]:/.utilization_load_avg
1015 ± 78% +101.1% 2042 ± 25% sched_debug.cfs_rq[6]:/.blocked_load_avg
1069 ± 72% +100.2% 2140 ± 23% sched_debug.cfs_rq[6]:/.tg_load_contrib
88.75 ± 14% -47.3% 46.75 ± 36% sched_debug.cfs_rq[9]:/.load
59.25 ± 23% -41.4% 34.75 ± 34% sched_debug.cfs_rq[9]:/.runnable_load_avg
315.50 ± 45% -64.6% 111.67 ± 1% sched_debug.cfs_rq[9]:/.utilization_load_avg
2246758 ± 7% +87.6% 4213925 ± 65% sched_debug.cpu#0.nr_switches
2249376 ± 7% +87.4% 4215969 ± 65% sched_debug.cpu#0.sched_count
1121438 ± 7% +81.0% 2030313 ± 61% sched_debug.cpu#0.sched_goidle
1151160 ± 7% +86.5% 2146608 ± 64% sched_debug.cpu#0.ttwu_count
33.75 ± 15% -22.2% 26.25 ± 6% sched_debug.cpu#1.cpu_load[3]
33.25 ± 10% -18.0% 27.25 ± 7% sched_debug.cpu#1.cpu_load[4]
40.00 ± 18% +24.4% 49.75 ± 18% sched_debug.cpu#10.cpu_load[2]
39.25 ± 14% +22.3% 48.00 ± 10% sched_debug.cpu#10.cpu_load[3]
39.50 ± 15% +20.3% 47.50 ± 6% sched_debug.cpu#10.cpu_load[4]
5269004 ± 1% +27.8% 6731790 ± 30% sched_debug.cpu#10.nr_switches
5273193 ± 1% +27.8% 6736526 ± 30% sched_debug.cpu#10.sched_count
2633974 ± 1% +27.8% 3365271 ± 30% sched_debug.cpu#10.sched_goidle
2644149 ± 1% +26.9% 3356318 ± 30% sched_debug.cpu#10.ttwu_count
30.75 ± 15% +66.7% 51.25 ± 31% sched_debug.cpu#11.cpu_load[1]
5116609 ± 4% +34.5% 6884238 ± 33% sched_debug.cpu#9.nr_switches
5120531 ± 4% +34.5% 6889156 ± 33% sched_debug.cpu#9.sched_count
2557822 ± 4% +34.5% 3441428 ± 33% sched_debug.cpu#9.sched_goidle
0.00 ±141% +4.2e+05% 4.76 ±173% sched_debug.rt_rq[10]:/.rt_time
wsm: Westmere
Memory: 6G
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Ying Huang
5 years, 11 months
[PCI, x86] 991de2e590: EIP is at _raw_spin_unlock_irqrestore
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 991de2e59090e55c65a7f59a049142e3c480f7bd
Author: Jiang Liu <jiang.liu(a)linux.intel.com>
AuthorDate: Wed Jun 10 16:54:59 2015 +0800
Commit: Bjorn Helgaas <bhelgaas(a)google.com>
CommitDate: Thu Jul 30 14:05:57 2015 -0500
PCI, x86: Implement pcibios_alloc_irq() and pcibios_free_irq()
To support IOAPIC hotplug, we need to allocate PCI IRQ resources on demand
and free them when not used anymore.
Implement pcibios_alloc_irq() and pcibios_free_irq() to dynamically
allocate and free PCI IRQs.
Remove mp_should_keep_irq(), which is no longer used.
[bhelgaas: changelog]
Signed-off-by: Jiang Liu <jiang.liu(a)linux.intel.com>
Signed-off-by: Bjorn Helgaas <bhelgaas(a)google.com>
Acked-by: Thomas Gleixner <tglx(a)linutronix.de>
+------------------------------------------------+------------+------------+------------+
| | 890e484758 | 991de2e590 | ccce1c6482 |
+------------------------------------------------+------------+------------+------------+
| boot_successes | 1009 | 682 | 26 |
| boot_failures | 0 | 228 | 15 |
| EIP_is_at_lock_is_held | 0 | 4 | |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 228 | 12 |
| backtrace:__pci_register_driver | 0 | 228 | 12 |
| backtrace:pcistub_init | 0 | 228 | 12 |
| backtrace:kernel_init_freeable | 0 | 228 | 12 |
| EIP_is_at_rcu_lockdep_current_cpu_online | 0 | 1 | |
| EIP_is_at__slab_alloc | 0 | 96 | 7 |
| EIP_is_at_raw_spin_unlock_irqrestore | 0 | 107 | 5 |
| EIP_is_at__phys_addr | 0 | 1 | |
| EIP_is_at_kmem_cache_free | 0 | 6 | |
| EIP_is_at_lockdep_trace_alloc | 0 | 3 | |
| EIP_is_at_acpi_ps_parse_loop | 0 | 2 | |
| EIP_is_at_check_bytes_and_report | 0 | 1 | |
| EIP_is_at__free_pages | 0 | 1 | |
| EIP_is_at_ftrace_likely_update | 0 | 1 | |
| EIP_is_at_acpi_ut_update_object_reference | 0 | 1 | |
| EIP_is_at_acpi_ps_complete_this_op | 0 | 1 | |
| EIP_is_at_acpi_ut_push_generic_state | 0 | 1 | |
| EIP_is_at__slab_free | 0 | 1 | |
| EIP_is_at_acpi_os_acquire_lock | 0 | 1 | |
| BUG:kernel_boot_crashed | 0 | 0 | 1 |
| IP-Config:Auto-configuration_of_network_failed | 0 | 0 | 2 |
+------------------------------------------------+------------+------------+------------+
[ 93.225024] irq event stamp: 24182770
[ 93.225024] irq event stamp: 24182770
[ 93.225935] hardirqs last enabled at (24182769):
[ 93.225935] hardirqs last enabled at (24182769): [<c1921a0d>] _raw_spin_unlock_irqrestore+0x2d/0x90
[<c1921a0d>] _raw_spin_unlock_irqrestore+0x2d/0x90
[ 93.228345] hardirqs last disabled at (24182770):
[ 93.228345] hardirqs last disabled at (24182770): [<c192336c>] apic_timer_interrupt+0x28/0x34
[<c192336c>] apic_timer_interrupt+0x28/0x34
[ 93.250462] softirqs last enabled at (24181726):
[ 93.250462] softirqs last enabled at (24181726): [<c108106a>] __do_softirq+0x3ca/0x720
[<c108106a>] __do_softirq+0x3ca/0x720
[ 93.252371] softirqs last disabled at (24181721):
[ 93.252371] softirqs last disabled at (24181721): [<c1020f44>] do_softirq_own_stack+0x24/0x30
[<c1020f44>] do_softirq_own_stack+0x24/0x30
[ 93.254601] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.2.0-rc2-00002-g991de2e #1
[ 93.254601] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.2.0-rc2-00002-g991de2e #1
[ 93.256430] task: d0508000 ti: d0502000 task.ti: d0502000
[ 93.256430] task: d0508000 ti: d0502000 task.ti: d0502000
[ 93.257864] EIP: 0060:[<c1921a2e>] EFLAGS: 00000246 CPU: 0
[ 93.257864] EIP: 0060:[<c1921a2e>] EFLAGS: 00000246 CPU: 0
[ 93.259135] EIP is at _raw_spin_unlock_irqrestore+0x4e/0x90
[ 93.259135] EIP is at _raw_spin_unlock_irqrestore+0x4e/0x90
[ 93.260542] EAX: 00000246 EBX: d0484600 ECX: 00000000 EDX: 00000000
[ 93.260542] EAX: 00000246 EBX: d0484600 ECX: 00000000 EDX: 00000000
[ 93.272202] ESI: 00000246 EDI: 00000001 EBP: d0503c2c ESP: d0503c24
[ 93.272202] ESI: 00000246 EDI: 00000001 EBP: d0503c2c ESP: d0503c24
[ 93.273797] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 93.273797] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 93.275076] CR0: 8005003b CR2: ffffffff CR3: 01fa7000 CR4: 000006b0
[ 93.275076] CR0: 8005003b CR2: ffffffff CR3: 01fa7000 CR4: 000006b0
[ 93.276554] Stack:
[ 93.276554] Stack:
[ 93.277102] cfc00ca8
[ 93.277102] cfc00ca8 00000002 00000002 d0503c34 d0503c34 c160b5e4 c160b5e4 d0503c4c d0503c4c c162d2fe c162d2fe d04aa5e8 d04aa5e8 cfc00288 cfc00288
[ 93.279291] 00000003
[ 93.279291] 00000003 00000000 00000000 d0503c68 d0503c68 c162d5b8 c162d5b8 c162d524 c162d524 d07e6000 d07e6000 d07d1288 d07d1288 d0503c9c d0503c9c
[ 93.301331] 00000000
[ 93.301331] 00000000 d0503c74 d0503c74 c162d688 c162d688 d07e8000 d07e8000 d0503c8c d0503c8c c161717b c161717b d0731d20 d0731d20 d07e8000 d07e8000
[ 93.303287] Call Trace:
[ 93.303287] Call Trace:
[ 93.303931] [<c160b5e4>] acpi_os_release_lock+0x8/0xa
[ 93.303931] [<c160b5e4>] acpi_os_release_lock+0x8/0xa
[ 93.305276] [<c162d2fe>] acpi_ut_update_ref_count+0x42/0x270
[ 93.305276] [<c162d2fe>] acpi_ut_update_ref_count+0x42/0x270
[ 93.306774] [<c162d5b8>] acpi_ut_update_object_reference+0x8c/0x144
[ 93.306774] [<c162d5b8>] acpi_ut_update_object_reference+0x8c/0x144
[ 93.308359] [<c162d524>] ? acpi_ut_update_ref_count+0x268/0x270
[ 93.308359] [<c162d524>] ? acpi_ut_update_ref_count+0x268/0x270
[ 93.330943] [<c162d688>] acpi_ut_add_reference+0x18/0x1b
[ 93.330943] [<c162d688>] acpi_ut_add_reference+0x18/0x1b
[ 93.332316] [<c161717b>] acpi_ds_method_data_get_value+0xcc/0xd8
[ 93.332316] [<c161717b>] acpi_ds_method_data_get_value+0xcc/0xd8
[ 93.333917] [<c162156d>] acpi_ex_resolve_to_value+0x6c/0x1bb
[ 93.333917] [<c162156d>] acpi_ex_resolve_to_value+0x6c/0x1bb
[ 93.335313] [<c1621a6e>] acpi_ex_resolve_operands+0x19d/0x41f
[ 93.335313] [<c1621a6e>] acpi_ex_resolve_operands+0x19d/0x41f
[ 93.336670] [<c161873b>] acpi_ds_exec_end_op+0xd3/0x37b
[ 93.336670] [<c161873b>] acpi_ds_exec_end_op+0xd3/0x37b
[ 93.337934] [<c16284eb>] acpi_ps_parse_loop+0x478/0x4c3
[ 93.337934] [<c16284eb>] acpi_ps_parse_loop+0x478/0x4c3
[ 93.339192] [<c1628e16>] acpi_ps_parse_aml+0x85/0x230
[ 93.339192] [<c1628e16>] acpi_ps_parse_aml+0x85/0x230
[ 93.350455] [<c162952f>] acpi_ps_execute_method+0x18a/0x220
[ 93.350455] [<c162952f>] acpi_ps_execute_method+0x18a/0x220
[ 93.360873] [<c1624a2d>] acpi_ns_evaluate+0x185/0x1ff
[ 93.360873] [<c1624a2d>] acpi_ns_evaluate+0x185/0x1ff
[ 93.362098] [<c162d8e0>] acpi_ut_evaluate_object+0x79/0x175
[ 93.362098] [<c162d8e0>] acpi_ut_evaluate_object+0x79/0x175
[ 93.363405] [<c162a71f>] acpi_rs_get_prt_method_data+0x1a/0x3a
[ 93.363405] [<c162a71f>] acpi_rs_get_prt_method_data+0x1a/0x3a
[ 93.364840] [<c162a9b9>] acpi_get_irq_routing_table+0x1d/0x21
[ 93.364840] [<c162a9b9>] acpi_get_irq_routing_table+0x1d/0x21
[ 93.366222] [<c16136ae>] acpi_pci_irq_find_prt_entry+0x73/0x173
[ 93.366222] [<c16136ae>] acpi_pci_irq_find_prt_entry+0x73/0x173
[ 93.367693] [<c16137cb>] acpi_pci_irq_lookup+0x1d/0x11c
[ 93.367693] [<c16137cb>] acpi_pci_irq_lookup+0x1d/0x11c
[ 93.368950] [<c1613a67>] acpi_pci_irq_disable+0x27/0x68
[ 93.368950] [<c1613a67>] acpi_pci_irq_disable+0x27/0x68
[ 93.391267] [<c18bbebf>] pcibios_free_irq+0xf/0x20
[ 93.391267] [<c18bbebf>] pcibios_free_irq+0xf/0x20
[ 93.392433] [<c15e1b5a>] pci_device_probe+0xca/0x100
[ 93.392433] [<c15e1b5a>] pci_device_probe+0xca/0x100
[ 93.393619] [<c124b470>] ? sysfs_create_link+0x20/0x40
[ 93.393619] [<c124b470>] ? sysfs_create_link+0x20/0x40
[ 93.394856] [<c16a06c5>] driver_probe_device+0x135/0x2d0
[ 93.394856] [<c16a06c5>] driver_probe_device+0x135/0x2d0
[ 93.396114] [<c15e1701>] ? pci_match_device+0x21/0x100
[ 93.396114] [<c15e1701>] ? pci_match_device+0x21/0x100
[ 93.397361] [<c16a08f1>] __driver_attach+0x91/0xa0
[ 93.397361] [<c16a08f1>] __driver_attach+0x91/0xa0
[ 93.398490] [<c16a0860>] ? driver_probe_device+0x2d0/0x2d0
[ 93.398490] [<c16a0860>] ? driver_probe_device+0x2d0/0x2d0
[ 93.399826] [<c169ecef>] bus_for_each_dev+0x4f/0x80
[ 93.399826] [<c169ecef>] bus_for_each_dev+0x4f/0x80
[ 93.420048] [<c16a09e9>] driver_attach+0x19/0x20
[ 93.420048] [<c16a09e9>] driver_attach+0x19/0x20
[ 93.421164] [<c16a0860>] ? driver_probe_device+0x2d0/0x2d0
[ 93.421164] [<c16a0860>] ? driver_probe_device+0x2d0/0x2d0
[ 93.422470] [<c169f687>] bus_add_driver+0x197/0x210
[ 93.422470] [<c169f687>] bus_add_driver+0x197/0x210
[ 93.423619] [<c16a1a9b>] driver_register+0x6b/0xf0
[ 93.423619] [<c16a1a9b>] driver_register+0x6b/0xf0
[ 93.424786] [<c1df8f61>] ? platform_pci_module_init+0x16/0x16
[ 93.424786] [<c1df8f61>] ? platform_pci_module_init+0x16/0x16
[ 93.426157] [<c15e1bd5>] __pci_register_driver+0x45/0x50
[ 93.426157] [<c15e1bd5>] __pci_register_driver+0x45/0x50
[ 93.427402] [<c1df8f84>] pcistub_init+0x23/0x243
[ 93.427402] [<c1df8f84>] pcistub_init+0x23/0x243
[ 93.428557] [<c11c5242>] ? kfree+0x3e2/0x460
[ 93.428557] [<c11c5242>] ? kfree+0x3e2/0x460
[ 93.429560] [<c1dbcc2c>] ? do_one_initcall+0x77/0x177
[ 93.429560] [<c1dbcc2c>] ? do_one_initcall+0x77/0x177
[ 93.441915] [<c1dbcc2c>] ? do_one_initcall+0x77/0x177
[ 93.441915] [<c1dbcc2c>] ? do_one_initcall+0x77/0x177
[ 93.443223] [<c1df8f61>] ? platform_pci_module_init+0x16/0x16
[ 93.443223] [<c1df8f61>] ? platform_pci_module_init+0x16/0x16
[ 93.451777] [<c1dbcc85>] do_one_initcall+0xd0/0x177
[ 93.451777] [<c1dbcc85>] do_one_initcall+0xd0/0x177
[ 93.456115] [<c1dbcec4>] ? kernel_init_freeable+0x198/0x235
[ 93.456115] [<c1dbcec4>] ? kernel_init_freeable+0x198/0x235
[ 93.457441] [<c1dbcee4>] kernel_init_freeable+0x1b8/0x235
[ 93.457441] [<c1dbcee4>] kernel_init_freeable+0x1b8/0x235
[ 93.461852] [<c190d53b>] kernel_init+0xb/0xe0
[ 93.461852] [<c190d53b>] kernel_init+0xb/0xe0
[ 93.468044] [<c1922761>] ret_from_kernel_thread+0x21/0x30
[ 93.468044] [<c1922761>] ret_from_kernel_thread+0x21/0x30
[ 93.469464] [<c190d530>] ? rest_init+0x120/0x120
[ 93.469464] [<c190d530>] ? rest_init+0x120/0x120
[ 93.470690] Code:
[ 93.470690] Code: 02 02 00 00 00 00 74 74 38 38 e8 e8 73 73 ac ac 7b 7b ff ff a1 a1 78 78 6d 6d ca ca c1 c1 85 85 c0 c0 b8 b8 1c 1c 91 91 d7 d7 c1 c1 0f 0f 94 94 c3 c3 31 31 c9 c9 0f 0f b6 b6 d3 d3 e8 e8 1a 1a 31 31 83 83 ff ff 84 84 db db 75 75 43 43 89 89 f0 f0 50 50 9d 9d <8d> <8d> 74 74 26 26 00 00 5b 5b 64 64 ff ff 0d 0d c4 c4 41 41 e5 e5 c1 c1 5e 5e 5d 5d c3 c3 8d 8d 76 76 00 00 8b 8b 15 15 78 78
[ 93.494087] Kernel panic - not syncing: softlockup: hung tasks
[ 93.494087] Kernel panic - not syncing: softlockup: hung tasks
[ 93.495567] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L 4.2.0-rc2-00002-g991de2e #1
[ 93.495567] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L 4.2.0-rc2-00002-g991de2e #1
[ 93.497762] 00000016
[ 93.497762] 00000016 00000000 00000000 d0503af0 d0503af0 c1917c51 c1917c51 d0c30200 d0c30200 d0503b08 d0503b08 c19119c8 c19119c8 00000016 00000016
[ 93.519979] d0c30201
[ 93.519979] d0c30201 00000016 00000016 00000040 00000040 d0503b3c d0503b3c c113fd11 c113fd11 c1bb2ef4 c1bb2ef4 00000000 00000000 00000016 00000016
[ 93.522114] d05083e4
[ 93.522114] d05083e4 00000001 00000001 d0503be8 d0503be8 00000000 00000000 00000040 00000040 d0c2fd01 d0c2fd01 00000000 00000000 d0c30200 d0c30200
[ 93.524249] Call Trace:
[ 93.524249] Call Trace:
[ 93.524885] [<c1917c51>] dump_stack+0x48/0x60
[ 93.524885] [<c1917c51>] dump_stack+0x48/0x60
[ 93.526027] [<c19119c8>] panic+0xa0/0x1c5
[ 93.526027] [<c19119c8>] panic+0xa0/0x1c5
[ 93.527083] [<c113fd11>] watchdog_timer_fn+0x231/0x240
[ 93.527083] [<c113fd11>] watchdog_timer_fn+0x231/0x240
[ 93.528413] [<c110f54d>] __hrtimer_run_queues+0x17d/0x860
[ 93.528413] [<c110f54d>] __hrtimer_run_queues+0x17d/0x860
[ 93.549840] [<c113fae0>] ? watchdog+0x40/0x40
[ 93.549840] [<c113fae0>] ? watchdog+0x40/0x40
[ 93.551023] [<c1110e51>] hrtimer_interrupt+0xa1/0x1b0
[ 93.551023] [<c1110e51>] hrtimer_interrupt+0xa1/0x1b0
[ 93.552333] [<c10ff2b7>] ? rcu_irq_enter+0x147/0x320
[ 93.552333] [<c10ff2b7>] ? rcu_irq_enter+0x147/0x320
[ 93.553621] [<c1055b8e>] local_apic_timer_interrupt+0x2e/0x60
[ 93.553621] [<c1055b8e>] local_apic_timer_interrupt+0x2e/0x60
[ 93.555096] [<c10562ce>] smp_apic_timer_interrupt+0x2e/0x50
[ 93.555096] [<c10562ce>] smp_apic_timer_interrupt+0x2e/0x50
[ 93.556512] [<c1923373>] apic_timer_interrupt+0x2f/0x34
[ 93.556512] [<c1923373>] apic_timer_interrupt+0x2f/0x34
[ 93.557902] [<c1921a2e>] ? _raw_spin_unlock_irqrestore+0x4e/0x90
[ 93.557902] [<c1921a2e>] ? _raw_spin_unlock_irqrestore+0x4e/0x90
[ 93.559427] [<c160b5e4>] acpi_os_release_lock+0x8/0xa
[ 93.559427] [<c160b5e4>] acpi_os_release_lock+0x8/0xa
[ 93.580750] [<c162d2fe>] acpi_ut_update_ref_count+0x42/0x270
[ 93.580750] [<c162d2fe>] acpi_ut_update_ref_count+0x42/0x270
[ 93.582206] [<c162d5b8>] acpi_ut_update_object_reference+0x8c/0x144
[ 93.582206] [<c162d5b8>] acpi_ut_update_object_reference+0x8c/0x144
[ 93.583787] [<c162d524>] ? acpi_ut_update_ref_count+0x268/0x270
[ 93.583787] [<c162d524>] ? acpi_ut_update_ref_count+0x268/0x270
[ 93.585303] [<c162d688>] acpi_ut_add_reference+0x18/0x1b
[ 93.585303] [<c162d688>] acpi_ut_add_reference+0x18/0x1b
[ 93.586644] [<c161717b>] acpi_ds_method_data_get_value+0xcc/0xd8
[ 93.586644] [<c161717b>] acpi_ds_method_data_get_value+0xcc/0xd8
[ 93.588195] [<c162156d>] acpi_ex_resolve_to_value+0x6c/0x1bb
[ 93.588195] [<c162156d>] acpi_ex_resolve_to_value+0x6c/0x1bb
[ 93.589635] [<c1621a6e>] acpi_ex_resolve_operands+0x19d/0x41f
[ 93.589635] [<c1621a6e>] acpi_ex_resolve_operands+0x19d/0x41f
[ 93.602078] [<c161873b>] acpi_ds_exec_end_op+0xd3/0x37b
[ 93.602078] [<c161873b>] acpi_ds_exec_end_op+0xd3/0x37b
[ 93.603348] [<c16284eb>] acpi_ps_parse_loop+0x478/0x4c3
[ 93.603348] [<c16284eb>] acpi_ps_parse_loop+0x478/0x4c3
[ 93.604603] [<c1628e16>] acpi_ps_parse_aml+0x85/0x230
[ 93.604603] [<c1628e16>] acpi_ps_parse_aml+0x85/0x230
[ 93.605814] [<c162952f>] acpi_ps_execute_method+0x18a/0x220
[ 93.605814] [<c162952f>] acpi_ps_execute_method+0x18a/0x220
[ 93.607137] [<c1624a2d>] acpi_ns_evaluate+0x185/0x1ff
[ 93.607137] [<c1624a2d>] acpi_ns_evaluate+0x185/0x1ff
[ 93.608338] [<c162d8e0>] acpi_ut_evaluate_object+0x79/0x175
[ 93.608338] [<c162d8e0>] acpi_ut_evaluate_object+0x79/0x175
[ 93.609660] [<c162a71f>] acpi_rs_get_prt_method_data+0x1a/0x3a
[ 93.609660] [<c162a71f>] acpi_rs_get_prt_method_data+0x1a/0x3a
git bisect start ccce1c6482a6b7d65dbcc3b5a19f75b21593084d c13dcf9f2d6f5f06ef1bf79ec456df614c5e058b --
git bisect bad 2d8122fc32cc4a851d314cf12ee26a305b16a423 # 08:28 0- 67 Merge 'omap-dss2/for-next' into devel-spot-201508242215
git bisect bad a992f0007c196a9b34abb2c36e7ff74705e6cd97 # 08:28 0- 492 Merge 'hid/for-4.3/upstream' into devel-spot-201508242215
git bisect good 27fcb4e74ef3616949d7bc368beccfae56059a8c # 09:49 903+ 25 Merge 'block/for-next' into devel-spot-201508242215
git bisect good 5a9338b97096963d071ac2ffcaa8329e027bd6a3 # 11:08 910+ 8 Merge 'pci/pci/misc' into devel-spot-201508242215
git bisect bad 7585be060dc998dbb71d4a100e62aea5d6995b56 # 11:20 7- 12 Merge 'pci/next' into devel-spot-201508242215
git bisect good faf512cd1c5d1b8d6ee13f48915e099469313d3f # 12:50 907+ 97 Merge 'hch-pmem/nvdimm-no-pfn' into devel-spot-201508242215
git bisect good 3bd2e6fa9c4857054f6dbaedccd9d10ed79ac713 # 14:12 910+ 40 Merge 'hch-pmem/dma-mapping-cleanups-post' into devel-spot-201508242215
git bisect good 8faae0b69e773695066c21829f261199eb35613b # 15:28 910+ 125 Merge 'krzk/track/kgene-for-next' into devel-spot-201508242215
git bisect good 24b390aad2930b889b6e15fc7cf7bfa7c99fad21 # 16:48 910+ 0 PCI: Remove unused "pci_probe" flags
git bisect good cd66d5c3df7c96cbf75010b964b94032ceca8889 # 18:11 910+ 1 Merge branches 'pci/host-designware', 'pci/host-xgene' and 'pci/host-xilinx' into next
git bisect bad 5f2269916b0e509f2926346b58209abfa8316143 # 18:27 111- 8 PCI/MSI: Free legacy IRQ when enabling MSI/MSI-X
git bisect bad 991de2e59090e55c65a7f59a049142e3c480f7bd # 18:34 11- 17 PCI, x86: Implement pcibios_alloc_irq() and pcibios_free_irq()
git bisect good 890e4847587fcff5eb0438e90992ad7d2a261f33 # 19:54 910+ 0 PCI: Add pcibios_alloc_irq() and pcibios_free_irq()
# first bad commit: [991de2e59090e55c65a7f59a049142e3c480f7bd] PCI, x86: Implement pcibios_alloc_irq() and pcibios_free_irq()
git bisect good 890e4847587fcff5eb0438e90992ad7d2a261f33 # 20:00 1005+ 0 PCI: Add pcibios_alloc_irq() and pcibios_free_irq()
# extra tests with DEBUG_INFO
git bisect bad 991de2e59090e55c65a7f59a049142e3c480f7bd # 20:21 124- 6 PCI, x86: Implement pcibios_alloc_irq() and pcibios_free_irq()
# extra tests on HEAD of linux-devel/devel-spot-201508242215
git bisect bad ccce1c6482a6b7d65dbcc3b5a19f75b21593084d # 20:21 0- 15 0day head guard for 'devel-spot-201508242215'
# extra tests on tree/branch linux-next/master
# extra tests on tree/branch linus/master
git bisect good c13dcf9f2d6f5f06ef1bf79ec456df614c5e058b # 21:45 1010+ 11 Linux 4.2-rc8
# extra tests on tree/branch linux-next/master
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
console=ttyS0,115200
console=tty0
vga=normal
root=/dev/ram0
rw
drbd.minor_count=8
)
"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 11 months
[rhashtable-test] f4a3e90ba5: EIP is at __rcu_is_watching
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://git.kernel.org/pub/scm/linux/kernel/git/bluetooth/bluetooth-next.git master
commit f4a3e90ba5739cfd761b6befadae9728bd3641ed
Author: Phil Sutter <phil(a)nwl.cc>
AuthorDate: Sat Aug 15 00:37:15 2015 +0200
Commit: David S. Miller <davem(a)davemloft.net>
CommitDate: Mon Aug 17 14:33:47 2015 -0700
rhashtable-test: extend to test concurrency
After having tested insertion, lookup, table walk and removal, spawn a
number of threads running operations on the same rhashtable. Each of
them will:
1) insert it's own set of objects,
2) lookup every successfully inserted object and finally
3) remove objects in several rounds until all of them have been removed,
making sure the remaining ones are still found after each round.
This should put a good amount of load onto the system and due to
synchronising thread startup via two semaphores also extensive
concurrent table access.
The default number of ten threads returned within half a second on my
local VM with two cores. Running 200 threads took about four seconds. If
slow systems suffer too much from this though, the default could be
lowered or even set to zero so this extended test does not run at all by
default.
Signed-off-by: Phil Sutter <phil(a)nwl.cc>
Acked-by: Thomas Graf <tgraf(a)suug.ch>
Signed-off-by: David S. Miller <davem(a)davemloft.net>
+------------------------------------------------------+------------+------------+------------+
| | c1f066d4ee | f4a3e90ba5 | f51955394f |
+------------------------------------------------------+------------+------------+------------+
| boot_successes | 1009 | 844 | 104 |
| boot_failures | 0 | 66 | 9 |
| EIP_is_at_lock_is_held | 0 | 27 | 4 |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 0 | 63 | 7 |
| backtrace:threadfunc | 0 | 65 | 7 |
| EIP_is_at_lock_acquire | 0 | 4 | 1 |
| EIP_is_at__rcu_is_watching | 0 | 3 | |
| EIP_is_at_debug_lockdep_rcu_enabled | 0 | 6 | 2 |
| EIP_is_at_rcu_lockdep_current_cpu_online | 0 | 5 | 1 |
| EIP_is_at_thread_lookup_test | 0 | 5 | 1 |
| EIP_is_at_lockdep_rht_bucket_is_held | 0 | 3 | |
| EIP_is_at_jhash | 0 | 2 | |
| EIP_is_at_rcu_read_lock_held | 0 | 7 | 1 |
| EIP_is_at_lock_release | 0 | 1 | |
| EIP_is_at_rcu_is_watching | 0 | 3 | 1 |
| INFO:rcu_sched_detected_stalls_on_CPUs/tasks | 0 | 2 | |
| WARNING:at_mm/page_alloc.c:#__alloc_pages_nodemask() | 0 | 1 | |
| backtrace:packet_setsockopt | 0 | 1 | |
| backtrace:SyS_setsockopt | 0 | 1 | |
| backtrace:SyS_socketcall | 0 | 1 | |
| IP-Config:Auto-configuration_of_network_failed | 0 | 0 | 2 |
+------------------------------------------------------+------------+------------+------------+
[ 31.548753] Testing concurrent rhashtable access from 10 threads
[ 52.242900] NMI watchdog: BUG: soft lockup - CPU#1 stuck for 22s! [rhashtable_thra:88]
[ 52.242900] NMI watchdog: BUG: soft lockup - CPU#1 stuck for 22s! [rhashtable_thra:88]
[ 52.242900] Modules linked in:
[ 52.242900] Modules linked in:
[ 52.242900] irq event stamp: 1845856
[ 52.242900] irq event stamp: 1845856
[ 52.242900] hardirqs last enabled at (1845855):
[ 52.242900] hardirqs last enabled at (1845855): [<c1b71e6c>] restore_all_notrace+0x0/0x18
[<c1b71e6c>] restore_all_notrace+0x0/0x18
[ 52.242900] hardirqs last disabled at (1845856):
[ 52.242900] hardirqs last disabled at (1845856): [<c1b7290c>] apic_timer_interrupt+0x28/0x34
[<c1b7290c>] apic_timer_interrupt+0x28/0x34
[ 52.242900] softirqs last enabled at (1845854):
[ 52.242900] softirqs last enabled at (1845854): [<c1076b9c>] __do_softirq+0x70b/0xb0a
[<c1076b9c>] __do_softirq+0x70b/0xb0a
[ 52.242900] softirqs last disabled at (1845851):
[ 52.242900] softirqs last disabled at (1845851): [<c100790b>] do_softirq_own_stack+0x3e/0x60
[<c100790b>] do_softirq_own_stack+0x3e/0x60
[ 52.242900] CPU: 1 PID: 88 Comm: rhashtable_thra Not tainted 4.2.0-rc6-00979-gf4a3e90 #1
[ 52.242900] CPU: 1 PID: 88 Comm: rhashtable_thra Not tainted 4.2.0-rc6-00979-gf4a3e90 #1
[ 52.242900] task: d22b0000 ti: d22b2000 task.ti: d22b2000
[ 52.242900] task: d22b0000 ti: d22b2000 task.ti: d22b2000
[ 52.242900] EIP: 0060:[<c112260e>] EFLAGS: 00000246 CPU: 1
[ 52.242900] EIP: 0060:[<c112260e>] EFLAGS: 00000246 CPU: 1
[ 52.242900] EIP is at __rcu_is_watching+0x0/0x32
[ 52.242900] EIP is at __rcu_is_watching+0x0/0x32
[ 52.242900] EAX: 00000001 EBX: 30000053 ECX: 00000000 EDX: 00000001
[ 52.242900] EAX: 00000001 EBX: 30000053 ECX: 00000000 EDX: 00000001
[ 52.242900] ESI: d22af618 EDI: d35f3000 EBP: d22b3eb8 ESP: d22b3eb4
[ 52.242900] ESI: d22af618 EDI: d35f3000 EBP: d22b3eb8 ESP: d22b3eb4
[ 52.242900] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 52.242900] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 52.242900] CR0: 8005003b CR2: 00000000 CR3: 02247000 CR4: 000006b0
[ 52.242900] CR0: 8005003b CR2: 00000000 CR3: 02247000 CR4: 000006b0
[ 52.242900] Stack:
[ 52.242900] Stack:
[ 52.242900] c112266b
[ 52.242900] c112266b d22b3ec0 d22b3ec0 c111f492 c111f492 d22b3ef0 d22b3ef0 c14ebf56 c14ebf56 00000029 00000029 00000000 00000000 00000000 00000000
[ 52.242900] 0000b430
[ 52.242900] 0000b430 0000b430 0000b430 c2bdbfc0 c2bdbfc0 d22b3ed8 d22b3ed8 00000000 00000000 00000003 00000003 d35f3000 d35f3000 d22b3f34 d22b3f34
[ 52.242900] c14ed0cf
[ 52.242900] c14ed0cf 00000000 00000000 d1c02e84 d1c02e84 d2313220 d2313220 00000000 00000000 00000004 00000004 d3656a2c d3656a2c 00000003 00000003
[ 52.242900] Call Trace:
[ 52.242900] Call Trace:
[ 52.242900] [<c112266b>] ? rcu_is_watching+0x2b/0x50
[ 52.242900] [<c112266b>] ? rcu_is_watching+0x2b/0x50
[ 52.242900] [<c111f492>] rcu_read_lock_held+0x47/0xaa
[ 52.242900] [<c111f492>] rcu_read_lock_held+0x47/0xaa
[ 52.242900] [<c14ebf56>] thread_lookup_test+0x4d8/0x6ea
[ 52.242900] [<c14ebf56>] thread_lookup_test+0x4d8/0x6ea
[ 52.242900] [<c14ed0cf>] threadfunc+0xf67/0x1380
[ 52.242900] [<c14ed0cf>] threadfunc+0xf67/0x1380
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c10a928f>] kthread+0x151/0x164
[ 52.242900] [<c10a928f>] kthread+0x151/0x164
[ 52.242900] [<c1b70da1>] ? _raw_spin_unlock_irq+0x5f/0x99
[ 52.242900] [<c1b70da1>] ? _raw_spin_unlock_irq+0x5f/0x99
[ 52.242900] [<c1b71cc1>] ret_from_kernel_thread+0x21/0x30
[ 52.242900] [<c1b71cc1>] ret_from_kernel_thread+0x21/0x30
[ 52.242900] [<c10a913e>] ? kthread_stop+0x8db/0x8db
[ 52.242900] [<c10a913e>] ? kthread_stop+0x8db/0x8db
[ 52.242900] Code:
[ 52.242900] Code: 0c 0c 86 86 a5 a5 c2 c2 00 00 eb eb 21 21 83 83 05 05 b8 b8 85 85 a5 a5 c2 c2 01 01 83 83 15 15 bc bc 85 85 a5 a5 c2 c2 00 00 e9 e9 37 37 ff ff ff ff ff ff 83 83 05 05 00 00 86 86 a5 a5 c2 c2 01 01 83 83 15 15 04 04 86 86 a5 a5 c2 c2 00 00 5b 5b 5d 5d c3 c3 <55> <55> 83 83 05 05 10 10 86 86 a5 a5 c2 c2 01 01 b8 b8 0c 0c 6d 6d 23 23 c2 c2 83 83 15 15 14 14 86 86 a5 a5 c2 c2 00 00 89 89
[ 52.242900] Kernel panic - not syncing: softlockup: hung tasks
[ 52.242900] Kernel panic - not syncing: softlockup: hung tasks
[ 52.242900] CPU: 1 PID: 88 Comm: rhashtable_thra Tainted: G L 4.2.0-rc6-00979-gf4a3e90 #1
[ 52.242900] CPU: 1 PID: 88 Comm: rhashtable_thra Tainted: G L 4.2.0-rc6-00979-gf4a3e90 #1
[ 52.242900] 00000000
[ 52.242900] 00000000 00000000 00000000 d22b3da0 d22b3da0 c1b5d1fe c1b5d1fe 00000016 00000016 d22b3db8 d22b3db8 c1b57e97 c1b57e97 00000000 00000000
[ 52.242900] 00000016
[ 52.242900] 00000016 00000000 00000000 d0f80980 d0f80980 d22b3ddc d22b3ddc c11922d4 c11922d4 c1eb9c6c c1eb9c6c ee6b2800 ee6b2800 00000000 00000000
[ 52.242900] d22b3e78
[ 52.242900] d22b3e78 d0f80980 d0f80980 d0f80580 d0f80580 00000001 00000001 d22b3df8 d22b3df8 c1144e59 c1144e59 c1191f59 c1191f59 d0f805e0 d0f805e0
[ 52.242900] Call Trace:
[ 52.242900] Call Trace:
[ 52.242900] [<c1b5d1fe>] dump_stack+0xb8/0x108
[ 52.242900] [<c1b5d1fe>] dump_stack+0xb8/0x108
[ 52.242900] [<c1b57e97>] panic+0x143/0x404
[ 52.242900] [<c1b57e97>] panic+0x143/0x404
[ 52.242900] [<c11922d4>] watchdog_timer_fn+0x37b/0x3ce
[ 52.242900] [<c11922d4>] watchdog_timer_fn+0x37b/0x3ce
[ 52.242900] [<c1144e59>] __run_hrtimer+0x53b/0xdb8
[ 52.242900] [<c1144e59>] __run_hrtimer+0x53b/0xdb8
[ 52.242900] [<c1191f59>] ? watchdog+0x6a/0x6a
[ 52.242900] [<c1191f59>] ? watchdog+0x6a/0x6a
[ 52.242900] [<c1146477>] hrtimer_run_queues+0x103/0x157
[ 52.242900] [<c1146477>] hrtimer_run_queues+0x103/0x157
[ 52.242900] [<c11430db>] run_local_timers+0x1b/0x43
[ 52.242900] [<c11430db>] run_local_timers+0x1b/0x43
[ 52.242900] [<c1143140>] update_process_times+0x3d/0xba
[ 52.242900] [<c1143140>] update_process_times+0x3d/0xba
[ 52.242900] [<c115c050>] tick_periodic+0x148/0x170
[ 52.242900] [<c115c050>] tick_periodic+0x148/0x170
[ 52.242900] [<c115c277>] tick_handle_periodic+0x30/0xd7
[ 52.242900] [<c115c277>] tick_handle_periodic+0x30/0xd7
[ 52.242900] [<c10410a9>] local_apic_timer_interrupt+0x7e/0x91
[ 52.242900] [<c10410a9>] local_apic_timer_interrupt+0x7e/0x91
[ 52.242900] [<c1041b25>] smp_apic_timer_interrupt+0x5d/0x88
[ 52.242900] [<c1041b25>] smp_apic_timer_interrupt+0x5d/0x88
[ 52.242900] [<c1b72913>] apic_timer_interrupt+0x2f/0x34
[ 52.242900] [<c1b72913>] apic_timer_interrupt+0x2f/0x34
[ 52.242900] [<c10e007b>] ? nsec_high+0x82/0xa8
[ 52.242900] [<c10e007b>] ? nsec_high+0x82/0xa8
[ 52.242900] [<c112260e>] ? rcu_nmi_exit+0x1ae/0x1ae
[ 52.242900] [<c112260e>] ? rcu_nmi_exit+0x1ae/0x1ae
[ 52.242900] [<c112266b>] ? rcu_is_watching+0x2b/0x50
[ 52.242900] [<c112266b>] ? rcu_is_watching+0x2b/0x50
[ 52.242900] [<c111f492>] rcu_read_lock_held+0x47/0xaa
[ 52.242900] [<c111f492>] rcu_read_lock_held+0x47/0xaa
[ 52.242900] [<c14ebf56>] thread_lookup_test+0x4d8/0x6ea
[ 52.242900] [<c14ebf56>] thread_lookup_test+0x4d8/0x6ea
[ 52.242900] [<c14ed0cf>] threadfunc+0xf67/0x1380
[ 52.242900] [<c14ed0cf>] threadfunc+0xf67/0x1380
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c14ec168>] ? thread_lookup_test+0x6ea/0x6ea
[ 52.242900] [<c10a928f>] kthread+0x151/0x164
[ 52.242900] [<c10a928f>] kthread+0x151/0x164
[ 52.242900] [<c1b70da1>] ? _raw_spin_unlock_irq+0x5f/0x99
[ 52.242900] [<c1b70da1>] ? _raw_spin_unlock_irq+0x5f/0x99
[ 52.242900] [<c1b71cc1>] ret_from_kernel_thread+0x21/0x30
[ 52.242900] [<c1b71cc1>] ret_from_kernel_thread+0x21/0x30
[ 52.242900] [<c10a913e>] ? kthread_stop+0x8db/0x8db
[ 52.242900] [<c10a913e>] ? kthread_stop+0x8db/0x8db
[ 52.242900] Kernel Offset: disabled
[ 52.242900] Kernel Offset: disabled
git bisect start f51955394f18bb091cf4381e77094d97d1ba6cce c13dcf9f2d6f5f06ef1bf79ec456df614c5e058b --
git bisect bad 7417d6ced7c8176546d076c8c411cce6c5466f01 # 14:33 0- 9 Merge 'arm-soc/next/soc' into devel-spot-201508271354
git bisect bad 56765655282f225f6602464632009c7f4ba9941d # 14:34 0- 4 Merge 'dledford/applied/mlx4-for-4.3' into devel-spot-201508271354
git bisect bad 2721a327a10e8dcafe0b148648158744f0a6d366 # 14:34 0- 5 Merge 'cgroup/for-4.3' into devel-spot-201508271354
git bisect bad 591c1cae093a84f34af29db68d0273e6724560fe # 14:34 0- 4 Merge 'amirv/for-upstream' into devel-spot-201508271354
git bisect good 185891c7f048c39abc6335b00b27e12d68feed65 # 14:34 910+ 0 Merge 'pm/bleeding-edge' into devel-spot-201508271354
git bisect bad 4afd181cb81fb1b788ad352156822128876b5921 # 14:34 0- 3 Merge 'tj-libata/for-next' into devel-spot-201508271354
git bisect bad 40e118d8076713b7886d3e06caf035f008fcb16c # 14:34 0- 5 Merge 'jkirsher-next-queue/dev-queue' into devel-spot-201508271354
git bisect good 0208bc8803918d7e84f247f1d4f1730171a24c16 # 14:34 910+ 0 Bluetooth: Fix breakage in amp_write_rem_assoc_frag()
git bisect bad e01286ef03a9c7b1d4937309f923c226ab05bc4d # 14:34 0- 2 ipv4: Make fib_encap_match static
git bisect good 2bd736fa0d8a1da90e6ccaa6a79e56a8d2ae60c4 # 14:34 910+ 0 Merge tag 'mac80211-next-for-davem-2015-08-14' of git://git.kernel.org/pub/scm/linux/kernel/git/jberg/mac80211-next
git bisect bad abc5d1ff3e8f9b4a9d274818459b123e31981dc9 # 14:34 0- 4 net: Add inet_proto_csum_replace_by_diff utility function
git bisect good c0015bf3a34961342a27b021672049e535ab36a1 # 14:34 910+ 0 ieee802154: 6lowpan: fix non-lowpan wpan interfaces
git bisect bad 863960b4c5c564819b153d53cffdf3f68283eaa2 # 14:34 0- 36 Merge branch 'enic-devcmd2'
git bisect good 3f1e08d0ae6746379b9e21264dae52f4f35c7ad2 # 14:34 910+ 0 batman-adv: remove broadcast packets scheduled for purged outgoing if
git bisect bad 2ea273d76a4869c097c6b1a1070162add2f66a6e # 14:34 0- 45 net: Export bpf_prog_create_from_user().
git bisect good c1f066d4ee0bde4bb0ff4ac295218b631729e0de # 14:34 1009+ 0 Merge tag 'batman-adv-for-davem' of git://git.open-mesh.org/linux-merge
git bisect bad ec120da6f0fe59f175c2a8faa0a7700280c39644 # 14:34 0- 56 ipv6: trivial whitespace fix
git bisect bad f4a3e90ba5739cfd761b6befadae9728bd3641ed # 14:34 0- 66 rhashtable-test: extend to test concurrency
# first bad commit: [f4a3e90ba5739cfd761b6befadae9728bd3641ed] rhashtable-test: extend to test concurrency
git bisect good c1f066d4ee0bde4bb0ff4ac295218b631729e0de # 14:35 1009+ 0 Merge tag 'batman-adv-for-davem' of git://git.open-mesh.org/linux-merge
# extra tests with DEBUG_INFO
git bisect bad f4a3e90ba5739cfd761b6befadae9728bd3641ed # 14:35 0- 103 rhashtable-test: extend to test concurrency
# extra tests on HEAD of linux-devel/devel-spot-201508271354
git bisect bad f51955394f18bb091cf4381e77094d97d1ba6cce # 14:35 0- 9 0day head guard for 'devel-spot-201508271354'
# extra tests on tree/branch bluetooth/master
git bisect bad 2573d78872f242a05b842e9c553da45a3d9924e5 # 14:44 62- 1 Merge branch 'ovs-vport-cleanup'
# extra tests with first bad commit reverted
git bisect good bd0944fd8c9b40317add8cd18d7515c43abc7a7c # 16:49 910+ 0 Revert "rhashtable-test: extend to test concurrency"
# extra tests on tree/branch linus/master
git bisect good 9751a9e449da2a7749d89968039d532c615beeaa # 18:32 910+ 0 thermal: power_allocator: allocate with kcalloc what you free with kfree
# extra tests on tree/branch linux-next/master
git bisect bad d1c9b4e0a86149145e56e3f65a4287a2397ea028 # 18:32 0- 110 Add linux-next specific files for 20150828
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
initrd=quantal-core-i386.cgz
wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-initrd $initrd
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
console=ttyS0,115200
console=tty0
vga=normal
root=/dev/ram0
rw
drbd.minor_count=8
)
"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 11 months
Re: [LKP] [PATCH 2/3] rhashtable-test: retry insert operations in threads
by Thomas Graf
On 08/28/15 at 03:34pm, Phil Sutter wrote:
> Quite ugly, IMHO: rhashtable_insert_fast() may return -ENOMEM as
> non-permanent error, if allocation in GFP_ATOMIC failed. In this case,
> allocation in GFP_KERNEL is retried by rht_deferred_worker(). Sadly,
> there is no way to determine if that has already been tried and failed.
>
> The thread test triggers GFP_ATOMIC allocation failure quite easily, so
> I can't really just ignore this issue. :)
Return EBUSY or ENOBUFS in the non-permanent case? It is definitely
helpful if the API allows to differ between permanent and
non-permanent errors.
5 years, 11 months
Re: [LKP] [PATCH 3/3] rhashtable-test: calculate max_entries value by default
by Thomas Graf
On 08/28/15 at 12:28pm, Phil Sutter wrote:
> A maximum table size of 64k entries is insufficient for the multiple
> threads test even in default configuration (10 threads * 50000 objects =
> 500000 objects in total). Since we know how many objects will be
> inserted, calculate the max size unless overridden by parameter.
>
> Note that specifying the exact number of objects upon table init won't
> suffice as that value is being rounded down to the next power of two -
> anticipate this by rounding up to the next power of two in beforehand.
>
> Signed-off-by: Phil Sutter <phil(a)nwl.cc>
Acked-by: Thomas Graf <tgraf(a)suug.ch>
Thanks for doing this work.
5 years, 11 months
Re: [LKP] [PATCH 2/3] rhashtable-test: retry insert operations in threads
by Thomas Graf
On 08/28/15 at 12:28pm, Phil Sutter wrote:
> After adding cond_resched() calls to threadfunc(), a surprisingly high
> rate of insert failures occurred probably due to table resizes getting a
> better chance to run in background. To not soften up the remaining
> tests, retry inserts until they either succeed or fail permanently.
>
> Signed-off-by: Phil Sutter <phil(a)nwl.cc>
> ---
> lib/test_rhashtable.c | 13 +++++++------
> 1 file changed, 7 insertions(+), 6 deletions(-)
>
> diff --git a/lib/test_rhashtable.c b/lib/test_rhashtable.c
> index 63654e3..093cf84 100644
> --- a/lib/test_rhashtable.c
> +++ b/lib/test_rhashtable.c
> @@ -244,7 +244,7 @@ static int thread_lookup_test(struct thread_data *tdata)
>
> static int threadfunc(void *data)
> {
> - int i, step, err = 0, insert_fails = 0;
> + int i, step, err = 0, retries = 0;
> struct thread_data *tdata = data;
>
> up(&prestart_sem);
> @@ -253,21 +253,22 @@ static int threadfunc(void *data)
>
> for (i = 0; i < entries; i++) {
> tdata->objs[i].value = (tdata->id << 16) | i;
> +insert_retry:
> cond_resched();
> err = rhashtable_insert_fast(&ht, &tdata->objs[i].node,
> test_rht_params);
> if (err == -ENOMEM || err == -EBUSY) {
> - tdata->objs[i].value = TEST_INSERT_FAIL;
> - insert_fails++;
> + retries++;
> + goto insert_retry;
Is it safe to retry indefinitely on ENOMEM? Retrying on EBUSY is
definitely an improvement and we should do the same in the non
threaded test as well.
5 years, 11 months
[lkp] [ACPI] 239708a3af: Warning: Processor Platform Limit event detected, but not handled.
by kernel test robot
FYI, we noticed the below changes on
git://internal_merge_and_test_tree devel-catchup-201508251337
commit 239708a3af44064366f1af0eea02dc1e8991c11b ("ACPI: Split out ACPI PSS from ACPI Processor driver")
We found the following new message in kernel log after your commit.
[ 8.316777] Warning: Processor Platform Limit event detected, but not handled.
[ 8.317857] Consider compiling CPUfreq support into your kernel.
Thanks,
Ying Huang
5 years, 11 months
[x86/vdso] 1c26267b7d: Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000007
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/entry_compat
commit 1c26267b7d2eedef3e5438fa94f837cd8dcabd6b
Author: Andy Lutomirski <luto(a)kernel.org>
AuthorDate: Wed Aug 26 11:07:35 2015 -0700
Commit: Andy Lutomirski <luto(a)kernel.org>
CommitDate: Wed Aug 26 17:46:59 2015 -0700
x86/vdso: Remove runtime 32-bit vDSO selection
32-bit userspace will now always see the save vDSO, which is exactly
what used to be the int80 vDSO. Subsequent patches will clean it up
and make it support SYSENTER and SYSCALL using alternatives.
Signed-off-by: Andy Lutomirski <luto(a)kernel.org>
+-----------------------------------------------------------+------------+------------+------------+
| | 3d239c0778 | 1c26267b7d | ebdf22afca |
+-----------------------------------------------------------+------------+------------+------------+
| boot_successes | 63 | 0 | 0 |
| boot_failures | 0 | 22 | 13 |
| Kernel_panic-not_syncing:Attempted_to_kill_init!exitcode= | 0 | 22 | 13 |
+-----------------------------------------------------------+------------+------------+------------+
[ 3.414131] Write protecting the kernel text: 9688k
[ 3.414747] Write protecting the kernel read-only data: 5132k
[ 3.415380] NX-protecting the kernel data: 10792k
[ 3.416857] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000007
[ 3.416857]
[ 3.418170] CPU: 0 PID: 1 Comm: init Not tainted 4.2.0-rc7-00087-g1c26267 #1
[ 3.419363] 41e7f387 00000001 00000001 40253dec 4196a39c 40253e04 41967899 00000001
[ 3.420613] 00000001 00000001 41e9e300 40253e50 41064bff 41d87790 00000007 40253e20
[ 3.422057] 4106fca6 0106f07a 00000001 4b3b5d60 40240040 00400500 40240040 00000000
[ 3.423560] Call Trace:
[ 3.423851] [<4196a39c>] dump_stack+0x16/0x18
[ 3.424539] [<41967899>] panic+0xcb/0x258
[ 3.425267] [<41064bff>] do_exit+0x101f/0x10a0
[ 3.425861] [<4106fca6>] ? __sigqueue_free+0x46/0x50
[ 3.426719] [<41064d49>] do_group_exit+0x69/0x100
[ 3.427220] [<41074ae2>] get_signal+0xa62/0xc10
[ 3.427728] [<4100402e>] do_signal+0x1e/0xd40
[ 3.428191] [<4104b313>] ? kvm_clock_read+0x13/0x20
[ 3.428747] [<4100fb38>] ? sched_clock+0x8/0x10
[ 3.429245] [<4101860e>] ? convert_ip_to_linear+0xe/0x150
[ 3.430145] [<4104e5ec>] ? is_prefetch+0x11c/0x310
[ 3.431119] [<4104f797>] ? __do_page_fault+0x987/0x9c0
[ 3.431740] [<4104af00>] ? kvm_async_pf_task_wake+0x1c0/0x1c0
[ 3.432310] [<410021f7>] prepare_exit_to_usermode+0x127/0x220
[ 3.432889] [<4104af00>] ? kvm_async_pf_task_wake+0x1c0/0x1c0
[ 3.433464] [<41974209>] resume_userspace+0x19/0x20
[ 3.433961] Kernel Offset: disabled
Elapsed time: 10
git bisect start ebdf22afca6568fd3ccbf01965d0cf2a8bfec706 a9c909ce8c7853b4fc16055c50eb50d91e20cb93 --
git bisect good 4b4d383594561d90d0b8a142c2f949da40eba6c4 # 09:57 20+ 0 x86/entry/64: Remove execve stubs
git bisect bad 5becf6c59f5d58e71b8dceb75c4b448b0daf52e5 # 10:19 0- 19 x86/asm: Re-add manual CFI infrastructure
git bisect good 3d239c077870c4ef0b2209437dbdadc4466d0529 # 10:31 22+ 0 selftests/x86: Add a test for vDSO unwinding
git bisect bad 1c26267b7d2eedef3e5438fa94f837cd8dcabd6b # 10:47 0- 22 x86/vdso: Remove runtime 32-bit vDSO selection
# first bad commit: [1c26267b7d2eedef3e5438fa94f837cd8dcabd6b] x86/vdso: Remove runtime 32-bit vDSO selection
git bisect good 3d239c077870c4ef0b2209437dbdadc4466d0529 # 10:55 63+ 0 selftests/x86: Add a test for vDSO unwinding
# extra tests with DEBUG_INFO
git bisect bad 1c26267b7d2eedef3e5438fa94f837cd8dcabd6b # 11:03 0- 56 x86/vdso: Remove runtime 32-bit vDSO selection
# extra tests on HEAD of luto/x86/entry_compat
git bisect bad ebdf22afca6568fd3ccbf01965d0cf2a8bfec706 # 11:03 0- 13 x86/vdso/32: Save extra registers in the INT80 vsyscall path
# extra tests on tree/branch luto/x86/entry_compat
git bisect bad ebdf22afca6568fd3ccbf01965d0cf2a8bfec706 # 11:03 0- 13 x86/vdso/32: Save extra registers in the INT80 vsyscall path
# extra tests on tree/branch linus/master
git bisect good f9ed72dde34e3d5253e206e4bc3edbc6373015d6 # 11:20 66+ 0 Merge branch 'for-linus' of git://git.kernel.dk/linux-block
# extra tests on tree/branch linux-next/master
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
initrd=quantal-core-i386.cgz
wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-initrd $initrd
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
console=ttyS0,115200
console=tty0
vga=normal
root=/dev/ram0
rw
drbd.minor_count=8
)
"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 11 months
