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]
Date:   Fri, 30 Jun 2017 09:34:02 +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]  c4a9ff748c: BUG:scheduling_while_atomic


FYI, we noticed the following commit:

commit: c4a9ff748c1ca2fe81e012ac32e1daa70702778b ("ACPI: button: Add a workaround to fix an order issue for old userspace")
url: https://github.com/0day-ci/linux/commits/Lv-Zheng/ACPI-button-Fix-button-lid_init_state-method-mode/20170622-143854


in testcase: netperf
with following parameters:

	ip: ipv4
	runtime: 300s
	nr_threads: 1
	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.12-rc6 | c4a9ff748c |
+-----------------------------------------------------------------------------------------------------+-----------+------------+
| boot_successes                                                                                      | 9404      | 3          |
| boot_failures                                                                                       | 2560      | 9          |
| ACPI_Error:Field[CPB3]at#exceeds_Buffer[NULL]size#(bits)(#/dsopcode-#)                              | 365       |            |
| ACPI_Error:Method_parse/execution_failed[~_SB._OSC](Node#),AE_AML_BUFFER_LIMIT(#/psparse-#)         | 365       |            |
| cpu_clock_throttled                                                                                 | 362       |            |
| Kernel_panic-not_syncing:Fatal_hardware_error                                                       | 2         |            |
| Mem-Info                                                                                            | 560       |            |
| invoked_oom-killer:gfp_mask=0x                                                                      | 528       |            |
| Out_of_memory:Kill_process                                                                          | 80        |            |
| BUG:Bad_page_state_in_process                                                                       | 25        |            |
| BUG:Bad_rss-counter_state_mm:#idx:#val                                                              | 18        |            |
| BUG:Bad_page_map_in_process                                                                         | 7         |            |
| page_allocation_failure:order:#,mode:#(GFP_ATOMIC|__GFP_COMP|__GFP_NOTRACK),nodemask=(null)         | 3         |            |
| WARNING:at_lib/list_debug.c:#__list_add_valid                                                       | 2         |            |
| INFO:rcu_sched_detected_stalls_on_CPUs/tasks                                                        | 2         |            |
| BUG:Bad_page_state:#messages_suppressed                                                             | 1         |            |
| WARNING:at_drivers/gpu/drm/drm_irq.c:#drm_wait_one_vblank[drm]                                      | 19        |            |
| WARNING:at_lib/list_debug.c:#__list_del_entry_valid                                                 | 2         |            |
| general_protection_fault:#[##]                                                                      | 2         |            |
| Kernel_panic-not_syncing:Fatal_exception                                                            | 3         |            |
| page_allocation_failure:order:#,mode:#(GFP_ATOMIC|__GFP_COMP|__GFP_ZERO),nodemask=(null)            | 18        |            |
| WARNING:at_drivers/gpu/drm/drm_vblank.c:#drm_wait_one_vblank[drm]                                   | 5         |            |
| page_allocation_failure:order:#,mode:#(GFP_KERNEL|__GFP_NORETRY),nodemask=(null)                    | 11        |            |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/rwsem.c                         | 4         |            |
| BUG:non-zero_nr_ptes_on_freeing_mm                                                                  | 13        |            |
| ACPI_Error:Field[CPB3]at_bit_offset/length#exceeds_size_of_target_Buffer(#bits)(#/dsopcode-#)       | 116       |            |
| ACPI_Error:Method_parse/execution_failed~_SB._OSC,AE_AML_BUFFER_LIMIT(#/psparse-#)                  | 116       |            |
| ACPI_Error:Method_parse/execution_failed~_SB.PC00.IOTR._CRS,AE_AML_NO_RESOURCE_END_TAG(#/psparse-#) | 129       |            |
| ACPI_Error:Method_execution_failed~_SB.PC00.IOTR._CRS,AE_AML_NO_RESOURCE_END_TAG(#/uteval-#)        | 129       |            |
| Kernel_panic-not_syncing:Hard_LOCKUP                                                                | 103       |            |
| Kernel_panic-not_syncing:Attempted_to_kill_init!exitcode=                                           | 20        |            |
| ACPI_Error:[~_SB_.PCI0.XHC_.RHUB.HS11]Namespace_lookup_failure,AE_NOT_FOUND(#/dswload-#)            | 15        |            |
| ACPI_Error:#table_load_failures,#successful(#/tbxfload-#)                                           | 15        |            |
| WARNING:at_drivers/gpu/drm/i915/intel_display.c:#intel_modeset_init[i915]                           | 259       | 9          |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c                         | 3         | 1          |
| INFO:creating/lkp/benchmarks/ltp/output_directory                                                   | 351       |            |
| INFO:creating/lkp/benchmarks/ltp/results_directory                                                  | 351       |            |
| INFO:ltp-pan_reported_some_tests_FAIL                                                               | 88        |            |
| INFO:ltp-pan_reported_all_tests_PASS                                                                | 208       |            |
| page_allocation_failure:order:#,mode:#(GFP_HIGHUSER_MOVABLE|__GFP_ZERO),nodemask=(null)             | 2         |            |
| WARNING:at_kernel/events/core.c:#add_event_to_ctx                                                   | 5         |            |
| WARNING:at_kernel/events/core.c:#event_sched_out                                                    | 5         |            |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/irq/manage.c                            | 1         |            |
| calltrace:init_netconsole                                                                           | 1         |            |
| calltrace:SyS_finit_module                                                                          | 2         |            |
| calltrace:SyS_write                                                                                 | 1         |            |
| BUG:unable_to_handle_kernel                                                                         | 1         |            |
| Oops:#[##]                                                                                          | 1         |            |
| WARNING:at_fs/sysfs/dir.c:#sysfs_warn_dup                                                           | 1         |            |
| calltrace:parport_pc_init                                                                           | 1         |            |
| WARNING:at_lib/kobject.c:#kobject_add_internal                                                      | 1         |            |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes                                    | 447       |            |
| BUG:kernel_hang_in_test_stage                                                                       | 263       |            |
| BUG:soft_lockup-CPU##stuck_for#s                                                                    | 1         |            |
| Kernel_panic-not_syncing:softlockup:hung_tasks                                                      | 1         |            |
| BUG:kernel_reboot-without-warning_in_boot_stage                                                     | 1         |            |
| BUG:kernel_reboot-without-warning_in_test_stage                                                     | 12        |            |
| WARNING:at_kernel/workqueue.c:#process_one_work                                                     | 1         |            |
| BUG:scheduling_while_atomic                                                                         | 0         | 8          |
| WARNING:at_kernel/time/timer.c:#call_timer_fn                                                       | 0         | 8          |
+-----------------------------------------------------------------------------------------------------+-----------+------------+



[   14.828015] BUG: scheduling while atomic: swapper/0/0/0x00000102
[   14.828552] Modules linked in:
[   14.828846] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.12.0-rc6-00001-gc4a9ff74 #1
[   14.829454] Hardware name: LENOVO IdeaPad U410    /Lenovo          , BIOS 65CN15WW 06/05/2012
[   14.830113] Call Trace:
[   14.830363]  <IRQ>
[   14.831578]  dump_stack+0x63/0x86
[   14.831887]  __schedule_bug+0x54/0x70
[   14.832212]  __schedule+0x633/0x850
[   14.832530]  schedule+0x3d/0x90
[   14.832826]  schedule_timeout+0x174/0x310
[   14.833176]  ? call_timer_fn+0x150/0x150
[   14.833518]  ec_guard+0x1c6/0x1d0
[   14.833821]  ? ec_guard+0x1c6/0x1d0
[   14.834135]  ? remove_wait_queue+0x70/0x70
[   14.834487]  acpi_ec_transaction+0x181/0x3b0
[   14.834850]  acpi_ec_read+0x42/0x50
[   14.835166]  acpi_ec_space_handler+0xa5/0x240
[   14.835534]  ? __update_load_avg_se+0x15b/0x180
[   14.835955]  ? acpi_os_signal_semaphore+0x4d/0x90
[   14.836345]  ? acpi_ut_release_mutex+0x11c/0x127
[   14.836727]  acpi_ev_address_space_dispatch+0x2ce/0x33a
[   14.837147]  ? ec_transaction+0x50/0x50
[   14.837483]  acpi_ex_access_region+0x414/0x4ca
[   14.837858]  ? __might_sleep+0x4a/0x80
[   14.838187]  acpi_ex_field_datum_io+0x178/0x3fa
[   14.838564]  acpi_ex_extract_from_field+0x151/0x2bb
[   14.838964]  acpi_ex_read_data_from_field+0x380/0x3d0
[   14.839374]  acpi_ex_resolve_node_to_value+0x326/0x443
[   14.839790]  acpi_ex_resolve_to_value+0x374/0x40e
[   14.840179]  acpi_ds_evaluate_name_path+0xa3/0x143
[   14.840573]  acpi_ds_exec_end_op+0xd1/0x6c6
[   14.840930]  acpi_ps_parse_loop+0x819/0x8b5
[   14.841287]  ? acpi_ut_trace+0x26/0x66
[   14.841617]  acpi_ps_parse_aml+0x1ac/0x4a1
[   14.841968]  acpi_ps_execute_method+0x1f4/0x2b6
[   14.842349]  acpi_ns_evaluate+0x2ee/0x435
[   14.842696]  acpi_evaluate_object+0x178/0x38e
[   14.843065]  ? acpi_lid_update_state+0x50/0x50
[   14.843439]  acpi_evaluate_integer+0x3e/0xd0
[   14.843802]  acpi_lid_update_state+0x27/0x50
[   14.844164]  acpi_lid_timeout+0x1d/0x30
[   14.844501]  call_timer_fn+0x35/0x150
[   14.844828]  run_timer_softirq+0x1e8/0x460
[   14.845180]  ? ktime_get+0x41/0xa0
[   14.845490]  ? lapic_next_deadline+0x26/0x30
[   14.845851]  ? clockevents_program_event+0x7a/0xf0
[   14.846246]  __do_softirq+0x104/0x2ab
[   14.846572]  irq_exit+0xf1/0x100
[   14.846872]  smp_apic_timer_interrupt+0x3d/0x50
[   14.847249]  apic_timer_interrupt+0x93/0xa0
[   14.847608] RIP: 0010:cpuidle_enter_state+0x121/0x2d0
[   14.848013] RSP: 0018:ffffffff81e03df0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
[   14.848618] RAX: 0000000000000000 RBX: 0000000000000005 RCX: 000000000000001f
[   14.849151] RDX: 0000000373cf2cc5 RSI: ffff88011f2195d8 RDI: 0000000000000000
[   14.849682] RBP: ffffffff81e03e28 R08: 00000000000f41c9 R09: 0000000000000018
[   14.850214] R10: ffffffff81e03dc0 R11: 000000000007cf4d R12: ffff88011f225800
[   14.850747] R13: ffffffff81fa9c58 R14: 0000000373cf2cc5 R15: ffffffff81fa9c40
[   14.851281]  </IRQ>
[   14.851516]  cpuidle_enter+0x17/0x20
[   14.851837]  call_cpuidle+0x23/0x40
[   14.852152]  do_idle+0x189/0x1e0
[   14.852451]  cpu_startup_entry+0x1d/0x20
[   14.852791]  rest_init+0x85/0x90
[   14.853093]  start_kernel+0x3f8/0x405
[   14.853419]  ? early_idt_handler_array+0x120/0x120
[   14.853812]  x86_64_start_reservations+0x2f/0x31
[   14.854195]  x86_64_start_kernel+0x143/0x152
[   14.854557]  secondary_startup_64+0x9f/0x9f
[   14.854990] ACPI : button: The lid device is not compliant to SW_LID.
[   14.855491] timer: acpi_lid_timeout+0x0/0x30 preempt leak: 00000101 -> 00000000
[   14.856086] ------------[ cut here ]------------
[   14.856473] WARNING: CPU: 0 PID: 0 at kernel/time/timer.c:1275 call_timer_fn+0x13c/0x150
[   14.857188] Modules linked in:
[   14.857479] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W       4.12.0-rc6-00001-gc4a9ff74 #1
[   14.858158] Hardware name: LENOVO IdeaPad U410    /Lenovo          , BIOS 65CN15WW 06/05/2012
[   14.858818] task: ffffffff81e104c0 task.stack: ffffffff81e00000
[   14.859279] RIP: 0010:call_timer_fn+0x13c/0x150
[   14.859655] RSP: 0018:ffff88011f203e98 EFLAGS: 00010292
[   14.860073] RAX: 0000000000000043 RBX: 0000000000000001 RCX: ffffffff81e61488
[   14.860607] RDX: 0000000000000001 RSI: 0000000000000092 RDI: 0000000000000246
[   14.861138] RBP: ffff88011f203ec0 R08: 0000000000000000 R09: 000000000000035e
[   14.861672] R10: ffffea0004714b40 R11: 000000008237d701 R12: 0000000000000101
[   14.862203] R13: ffff8800c7535990 R14: ffffffff815734e0 R15: ffff880101cb6800
[   14.862735] FS:  0000000000000000(0000) GS:ffff88011f200000(0000) knlGS:0000000000000000
[   14.863371] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   14.863819] CR2: 00007fc0dcc49267 CR3: 000000011de09000 CR4: 00000000001406f0
[   14.864354] Call Trace:
[   14.864605]  <IRQ>
[   14.864831]  run_timer_softirq+0x1e8/0x460
[   14.865185]  ? ktime_get+0x41/0xa0
[   14.865493]  ? lapic_next_deadline+0x26/0x30
[   14.865854]  ? clockevents_program_event+0x7a/0xf0
[   14.866248]  __do_softirq+0x104/0x2ab
[   14.866573]  irq_exit+0xf1/0x100
[   14.866874]  smp_apic_timer_interrupt+0x3d/0x50
[   14.867252]  apic_timer_interrupt+0x93/0xa0
[   14.867609] RIP: 0010:cpuidle_enter_state+0x121/0x2d0
[   14.868017] RSP: 0018:ffffffff81e03df0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
[   14.868619] RAX: 0000000000000000 RBX: 0000000000000005 RCX: 000000000000001f
[   14.869151] RDX: 0000000373cf2cc5 RSI: ffff88011f2195d8 RDI: 0000000000000000
[   14.869682] RBP: ffffffff81e03e28 R08: 00000000000f41c9 R09: 0000000000000018
[   14.870216] R10: ffffffff81e03dc0 R11: 000000000007cf4d R12: ffff88011f225800
[   14.870747] R13: ffffffff81fa9c58 R14: 0000000373cf2cc5 R15: ffffffff81fa9c40
[   14.871278]  </IRQ>
[   14.871513]  cpuidle_enter+0x17/0x20
[   14.871831]  call_cpuidle+0x23/0x40
[   14.872143]  do_idle+0x189/0x1e0
[   14.872443]  cpu_startup_entry+0x1d/0x20
[   14.872783]  rest_init+0x85/0x90
[   14.873083]  start_kernel+0x3f8/0x405
[   14.873411]  ? early_idt_handler_array+0x120/0x120
[   14.873807]  x86_64_start_reservations+0x2f/0x31
[   14.874192]  x86_64_start_kernel+0x143/0x152
[   14.874554]  secondary_startup_64+0x9f/0x9f
[   14.874910] Code: 03 48 85 c0 75 eb 65 ff 0d e2 9f f1 7e e9 0c ff ff ff 44 89 e2 4c 89 f6 48 c7 c7 08 5d ca 81 c6 05 d1 24 e2 00 01 e8 81 66 0a 00 <0f> ff e9 16 ff ff ff 0f 1f 00 66 2e 0f 1f 84 00 00 00 00 00 0f 
[   14.876305] ---[ end trace dfc99d55faf65cc8 ]---


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.12.0-rc6-00001-gc4a9ff74" of type "text/plain" (159327 bytes)

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

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

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

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

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

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ