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>] [<thread-prev] [day] [month] [year] [list]
Message-ID: <20170613061700.GE3723@yexl-desktop>
Date:   Tue, 13 Jun 2017 14:17:00 +0800
From:   kernel test robot <xiaolong.ye@...el.com>
To:     Lv Zheng <lv.zheng@...el.com>
Cc:     "Rafael J . Wysocki" <rafael.j.wysocki@...el.com>,
        "Rafael J . Wysocki" <rjw@...ysocki.net>,
        Len Brown <len.brown@...el.com>, Lv Zheng <lv.zheng@...el.com>,
        Lv Zheng <zetalog@...il.com>, linux-kernel@...r.kernel.org,
        linux-acpi@...r.kernel.org, systemd-devel@...ts.freedesktop.org,
        Benjamin Tissoires <benjamin.tissoires@...hat.com>,
        Peter Hutterer <peter.hutterer@...-t.net>, lkp@...org
Subject: [lkp-robot] [ACPI]  4d0c35c1af: BUG:scheduling_while_atomic


FYI, we noticed the following commit:

commit: 4d0c35c1af080033a9c2b0ac5734ca78f9bc3c63 ("ACPI: button: Fix issue that button notify cannot report stateful SW_LID state")
url: https://github.com/0day-ci/linux/commits/Lv-Zheng/ACPI-button-Fix-button-lid_init_state-method-mode/20170608-210525


in testcase: netperf
with following parameters:

	ip: ipv4
	runtime: 300s
	nr_threads: 200%
	cluster: cs-localhost
	test: TCP_CRR
	cpufreq_governor: performance

test-description: Netperf is a benchmark that can be use to measure various aspect of networking performance.
test-url: http://www.netperf.org/netperf/


on test machine: 4 threads Intel(R) Core(TM) i5-3317U CPU @ 1.70GHz with 4G memory

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):


+---------------------------------------------------------------------------+----------+------------+
|                                                                           | v4.9-rc8 | 4d0c35c1af |
+---------------------------------------------------------------------------+----------+------------+
| boot_successes                                                            | 1249     | 2          |
| boot_failures                                                             | 100      | 10         |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/irq/manage.c  | 17       |            |
| calltrace:SyS_write                                                       | 8        |            |
| calltrace:init_netconsole                                                 | 9        |            |
| calltrace:SyS_finit_module                                                | 40       | 1          |
| WARNING:at_drivers/gpu/drm/i915/intel_display.c:#intel_modeset_init[i915] | 20       | 1          |
| calltrace:i915_init                                                       | 20       | 1          |
| drm:intel_set_cpu_fifo_underrun_reporting[i915]]                          | 1        |            |
| drm:intel_cpu_fifo_underrun_irq_handler[i915]]                            | 1        |            |
| drm:intel_set_pch_fifo_underrun_reporting[i915]]                          | 1        |            |
| drm:intel_pch_fifo_underrun_irq_handler[i915]]                            | 1        |            |
| WARNING:at_fs/sysfs/dir.c:#sysfs_warn_dup                                 | 11       |            |
| calltrace:parport_pc_init                                                 | 11       |            |
| WARNING:at_lib/kobject.c:#kobject_add_internal                            | 11       |            |
| invoked_oom-killer:gfp_mask=0x                                            | 27       | 2          |
| Mem-Info                                                                  | 27       | 2          |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes          | 27       | 2          |
| BUG:kernel_hang_in_test_stage                                             | 11       |            |
| WARNING:at_net/mac80211/driver-ops.h:#ieee80211_set_default_key[mac80211] | 7        |            |
| calltrace:SyS_sendmsg                                                     | 7        |            |
| WARNING:at_net/mac80211/driver-ops.h:#ieee80211_key_replace[mac80211]     | 7        |            |
| BUG:kernel_hang_in_boot_stage                                             | 2        |            |
| BUG:kernel_in_stage                                                       | 11       |            |
| BUG:scheduling_while_atomic                                               | 0        | 8          |
| WARNING:at_kernel/time/timer.c:#call_timer_fn                             | 0        | 8          |
| BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h            | 0        | 8          |
| calltrace:intel_lid_notify                                                | 0        | 8          |
| calltrace:SyS_sendto                                                      | 0        | 8          |
| calltrace:SyS_connect                                                     | 0        | 8          |
| calltrace:SyS_read                                                        | 0        | 4          |
| calltrace:smpboot_thread_fn                                               | 0        | 8          |
| calltrace:SyS_recvfrom                                                    | 0        | 7          |
| calltrace:SyS_accept                                                      | 0        | 5          |
| BUG:unable_to_handle_kernel                                               | 0        | 6          |
| Oops:#[##]                                                                | 0        | 6          |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt                     | 0        | 6          |
| calltrace:SyS_socket                                                      | 0        | 3          |
| WARNING:at_net/sched/sch_generic.c:#dev_watchdog                          | 0        | 1          |
| BUG:soft_lockup-CPU##stuck_for#s                                          | 0        | 2          |
| Kernel_panic-not_syncing:softlockup:hung_tasks                            | 0        | 2          |
| WARNING:at_arch/x86/kernel/smp.c:#native_smp_send_reschedule              | 0        | 1          |
| INFO:rcu_sched_detected_stalls_on_CPUs/tasks                              | 0        | 1          |
| RIP:smp_call_function_many                                                | 0        | 1          |
| calltrace:SyS_seccomp                                                     | 0        | 1          |
| calltrace:SyS_nanosleep                                                   | 0        | 1          |
| calltrace:SyS_bind                                                        | 0        | 1          |
+---------------------------------------------------------------------------+----------+------------+



[   16.440882] BUG: scheduling while atomic: swapper/2/0/0x00000102
[   16.441400] Modules linked in:
[   16.441750] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 4.9.0-rc8-00001-g4d0c35c #1
[   16.442346] Hardware name: LENOVO IdeaPad U410    /Lenovo          , BIOS 65CN15WW 06/05/2012
[   16.443007]  ffff880112a837b0 ffffffff81477139 0000000000000000 ffff880112a993c0
[   16.443830]  ffff880112a837c0 ffffffff810a7d04 ffff880112a83818 ffffffff81962ed4
[   16.444651]  0000000000000422 0000000000000008 ffff880112a993c0 ffff880112a837f8
[   16.445473] Call Trace:
[   16.446670]  <IRQ> 
[   16.446857]  [<ffffffff81477139>] dump_stack+0x63/0x8a
[   16.447329]  [<ffffffff810a7d04>] __schedule_bug+0x54/0x70
[   16.447766]  [<ffffffff81962ed4>] __schedule+0x554/0x6f0
[   16.448191]  [<ffffffff819630ad>] schedule+0x3d/0x90
[   16.448594]  [<ffffffff819666f6>] schedule_timeout+0x1d6/0x3f0
[   16.449052]  [<ffffffff810f0340>] ? del_timer_sync+0x50/0x50
[   16.449501]  [<ffffffff810c634f>] ? prepare_to_wait_event+0x7f/0x100
[   16.449989]  [<ffffffff81506972>] ec_guard+0x173/0x1b1
[   16.450405]  [<ffffffff810c63d0>] ? prepare_to_wait_event+0x100/0x100
[   16.450897]  [<ffffffff81507956>] acpi_ec_transaction+0x13f/0x2f2
[   16.451369]  [<ffffffff81507b56>] acpi_ec_read+0x4d/0x59
[   16.451794]  [<ffffffff81507cb9>] acpi_ec_space_handler+0xce/0x183
[   16.452271]  [<ffffffff814fdb35>] ? acpi_os_signal_semaphore+0x74/0x84
[   16.452771]  [<ffffffff81542030>] ? acpi_ut_release_mutex+0x11c/0x127
[   16.453264]  [<ffffffff8151b2b3>] acpi_ev_address_space_dispatch+0x2ce/0x33a
[   16.453792]  [<ffffffff81507beb>] ? ec_transaction+0x4f/0x4f
[   16.454239]  [<ffffffff81522ef6>] acpi_ex_access_region+0x414/0x4ca
[   16.454719]  [<ffffffff810a81da>] ? __might_sleep+0x4a/0x80
[   16.455159]  [<ffffffff81523386>] acpi_ex_field_datum_io+0x178/0x3fa
[   16.455647]  [<ffffffff8152392c>] acpi_ex_extract_from_field+0x151/0x2bb
[   16.456157]  [<ffffffff81522592>] acpi_ex_read_data_from_field+0x380/0x3d0
[   16.456676]  [<ffffffff815279f2>] acpi_ex_resolve_node_to_value+0x326/0x443
[   16.457202]  [<ffffffff81527e83>] acpi_ex_resolve_to_value+0x374/0x40e
[   16.457700]  [<ffffffff81515151>] acpi_ds_evaluate_name_path+0xa3/0x143
[   16.458204]  [<ffffffff815157c0>] acpi_ds_exec_end_op+0xd1/0x6c6
[   16.458670]  [<ffffffff815365c5>] acpi_ps_parse_loop+0x7d7/0x873
[   16.459137]  [<ffffffff8153f1f3>] ? acpi_ut_trace+0x26/0x66
[   16.459579]  [<ffffffff8153786c>] acpi_ps_parse_aml+0x1ac/0x4a1
[   16.460041]  [<ffffffff81538526>] acpi_ps_execute_method+0x1f4/0x2b6
[   16.460529]  [<ffffffff8152f567>] acpi_ns_evaluate+0x2ee/0x435
[   16.460985]  [<ffffffff81534209>] acpi_evaluate_object+0x178/0x38e
[   16.461463]  [<ffffffff814fe210>] acpi_evaluate_integer+0x44/0xca
[   16.461934]  [<ffffffff810aba12>] ? check_preempt_curr+0x52/0x90
[   16.462403]  [<ffffffff8154533d>] ? acpi_button_notify+0x133/0x133
[   16.462880]  [<ffffffff81544b04>] acpi_lid_evaluate_state+0x1c/0x33
[   16.463401]  [<ffffffff815451f9>] acpi_lid_update_state+0x16/0x27
[   16.463875]  [<ffffffff8154535d>] acpi_lid_timeout+0x20/0x37
[   16.464320]  [<ffffffff810f0385>] call_timer_fn+0x35/0x130
[   16.464756]  [<ffffffff810f0962>] run_timer_softirq+0x222/0x4e0
[   16.465218]  [<ffffffff810f8c01>] ? ktime_get+0x41/0xb0
[   16.465640]  [<ffffffff81053256>] ? lapic_next_deadline+0x26/0x30
[   16.466112]  [<ffffffff8196af74>] __do_softirq+0x104/0x2ab
[   16.466550]  [<ffffffff810869c1>] irq_exit+0xf1/0x100
[   16.466959]  [<ffffffff8196ad72>] smp_apic_timer_interrupt+0x42/0x50
[   16.467446]  [<ffffffff81969f2c>] apic_timer_interrupt+0x8c/0xa0
[   16.467908]  <EOI> 
[   16.468089]  [<ffffffff817d3b12>] ? cpuidle_enter_state+0x122/0x2e0
[   16.468669]  [<ffffffff817d3d07>] cpuidle_enter+0x17/0x20
[   16.469099]  [<ffffffff810c6983>] call_cpuidle+0x23/0x40
[   16.469525]  [<ffffffff810c6bb4>] cpu_startup_entry+0x114/0x200
[   16.469988]  [<ffffffff81051d57>] start_secondary+0x107/0x130
[   16.470515] ACPI : button: The lid device is not compliant to SW_LID.
[   16.471013] ------------[ cut here ]------------
[   16.471397] WARNING: CPU: 2 PID: 0 at kernel/time/timer.c:1315 call_timer_fn+0x128/0x130
[   16.472117] timer: acpi_lid_timeout+0x0/0x37 preempt leak: 00000101 -> 00000000
[   16.472699] Modules linked in:
[   16.473046] CPU: 2 PID: 0 Comm: swapper/2 Tainted: G        W       4.9.0-rc8-00001-g4d0c35c #1
[   16.473713] Hardware name: LENOVO IdeaPad U410    /Lenovo          , BIOS 65CN15WW 06/05/2012
[   16.474368]  ffff880112a83de8 ffffffff81477139 ffff880112a83e38 0000000000000000
[   16.475191]  ffff880112a83e28 ffffffff810803db 0000052300000246 0000000000000001
[   16.476013]  ffff8800b4f0b190 0000000000000101 ffffffff8154533d ffff88010d4e5800
[   16.476835] Call Trace:
[   16.477082]  <IRQ> 
[   16.477262]  [<ffffffff81477139>] dump_stack+0x63/0x8a
[   16.477734]  [<ffffffff810803db>] __warn+0xcb/0xf0
[   16.478128]  [<ffffffff8154533d>] ? acpi_button_notify+0x133/0x133
[   16.478603]  [<ffffffff8108044f>] warn_slowpath_fmt+0x4f/0x60
[   16.479054]  [<ffffffff81544a59>] ? acpi_lid_tick+0x2f/0x32
[   16.479494]  [<ffffffff8154533d>] ? acpi_button_notify+0x133/0x133
[   16.479970]  [<ffffffff810f0478>] call_timer_fn+0x128/0x130
[   16.480410]  [<ffffffff810f0962>] run_timer_softirq+0x222/0x4e0
[   16.480871]  [<ffffffff810f8c01>] ? ktime_get+0x41/0xb0
[   16.481290]  [<ffffffff81053256>] ? lapic_next_deadline+0x26/0x30
[   16.481763]  [<ffffffff8196af74>] __do_softirq+0x104/0x2ab
[   16.482199]  [<ffffffff810869c1>] irq_exit+0xf1/0x100
[   16.482607]  [<ffffffff8196ad72>] smp_apic_timer_interrupt+0x42/0x50
[   16.483093]  [<ffffffff81969f2c>] apic_timer_interrupt+0x8c/0xa0
[   16.483555]  <EOI> 
[   16.483734]  [<ffffffff817d3b12>] ? cpuidle_enter_state+0x122/0x2e0
[   16.484313]  [<ffffffff817d3d07>] cpuidle_enter+0x17/0x20
[   16.484745]  [<ffffffff810c6983>] call_cpuidle+0x23/0x40
[   16.485169]  [<ffffffff810c6bb4>] cpu_startup_entry+0x114/0x200
[   16.485630]  [<ffffffff81051d57>] start_secondary+0x107/0x130
[   16.486081] ---[ end trace 1414925b5a440cdd ]---


To reproduce:

        git clone https://github.com/01org/lkp-tests.git
        cd lkp-tests
        bin/lkp install job.yaml  # job file is attached in this email
        bin/lkp run     job.yaml



Thanks,
Xiaolong

View attachment "config-4.9.0-rc8-00001-g4d0c35c" of type "text/plain" (154152 bytes)

View attachment "job-script" of type "text/plain" (7171 bytes)

Download attachment "dmesg.xz" of type "application/octet-stream" (43988 bytes)

View attachment "netperf" of type "text/plain" (4185 bytes)

View attachment "job.yaml" of type "text/plain" (4654 bytes)

View attachment "reproduce" of type "text/plain" (686 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ