March 2016 - LKP - Ml01.01.Org

[lkp] [namei] fda89e6574: kernel BUG at fs/namei.c:679!

by kernel test robot

FYI, we noticed the below changes on https://git.kernel.org/pub/scm/linux/kernel/git/viro/vfs.git work.lookups commit fda89e65743179d09e55bc6c265d06fa5efa8803 ("namei: untanlge lookup_fast()") +------------------------------------------+------------+------------+ | | 6c51e513a3 | fda89e6574 | +------------------------------------------+------------+------------+ | boot_successes | 0 | 1 | | boot_failures | 1 | 4 | | invoked_oom-killer:gfp_mask=0x | 1 | 1 | | Mem-Info | 1 | 1 | | Out_of_memory:Kill_process | 1 | 1 | | kernel_BUG_at_fs/namei.c | 0 | 3 | | invalid_opcode:#[##]SMP | 0 | 3 | | RIP:unlazy_walk | 0 | 3 | | Kernel_panic-not_syncing:Fatal_exception | 0 | 3 | | backtrace:SYSC_newfstatat | 0 | 3 | | backtrace:SyS_newfstatat | 0 | 3 | +------------------------------------------+------------+------------+ [ 19.082726] gre: GRE over IPv4 demultiplexor driver [ 19.084132] PPTP driver version 0.8.5 [ 19.622035] ------------[ cut here ]------------ [ 19.623051] kernel BUG at fs/namei.c:679! [ 19.624142] invalid opcode: 0000 [#1] SMP [ 19.625196] Modules linked in: pptp gre nfnetlink scsi_transport_iscsi pppoe inet_diag crypto_user sctp vmw_vsock_vmci_transport vsock vmw_vmci ieee802154_socket ieee802154 pppox ppp_generic slhc atm rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sr_mod cdrom ata_generic pata_acpi snd_pcm ppdev snd_timer snd soundcore pcspkr serio_raw ata_piix parport_pc parport floppy acpi_cpufreq libata i2c_piix4 [ 19.634478] CPU: 2 PID: 471 Comm: trinity-main Not tainted 4.5.0-rc4-00017-gfda89e6 #1 [ 19.636222] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 19.638068] task: ffff88007e49a440 ti: ffff88007ecfc000 task.ti: ffff88007ecfc000 [ 19.639728] RIP: 0010:[<ffffffff811fc7c1>] [<ffffffff811fc7c1>] unlazy_walk+0x151/0x170 [ 19.641705] RSP: 0018:ffff88007ecffc30 EFLAGS: 00010246 [ 19.643337] RAX: 0000000000001000 RBX: ffff88007ecffd40 RCX: ffff88007ecffcc4 [ 19.644713] RDX: 0000000000000002 RSI: ffff8800a486a240 RDI: ffff88007ecffd40 [ 19.646082] RBP: ffff88007ecffc58 R08: 0000000000000001 R09: 0000000000200000 [ 19.647451] R10: ffffffffffffffff R11: 0000000b5ae0aec3 R12: ffff8800a486a240 [ 19.648820] R13: ffff8800a486a480 R14: ffff88007ecffcc8 R15: ffff88013a53aaa0 [ 19.650323] FS: 00007f1ba2185700(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000 [ 19.652545] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 19.654211] CR2: 000000000239b000 CR3: 000000007f654000 CR4: 00000000000006e0 [ 19.656138] Stack: [ 19.657186] ffff88007ecffd40 ffff8800a486a240 ffff88007ecffcd0 ffff88007ecffcc8 [ 19.660359] ffff88013a53aaa0 ffff88007ecffcb0 ffffffff811fc937 ffff88007ecffcc4 [ 19.663448] ffff88007ecffcc4 ffff88007ecffcc4 0000000280808080 ffff88007ecffd40 [ 19.666064] Call Trace: [ 19.666835] [<ffffffff811fc937>] lookup_fast+0x157/0x330 [ 19.667898] [<ffffffff811fd3aa>] walk_component+0x3a/0x410 [ 19.668968] [<ffffffff811fe1fd>] path_lookupat+0x5d/0x110 [ 19.670047] [<ffffffff8120176e>] filename_lookup+0x9e/0x150 [ 19.671163] [<ffffffff8109d479>] ? __might_sleep+0x49/0x80 [ 19.672276] [<ffffffff812013b6>] ? getname_flags+0x56/0x1f0 [ 19.673370] [<ffffffff811ce84e>] ? kmem_cache_alloc+0x18e/0x1f0 [ 19.674545] [<ffffffff812013b6>] ? getname_flags+0x56/0x1f0 [ 19.675665] [<ffffffff812013d2>] ? getname_flags+0x72/0x1f0 [ 19.676755] [<ffffffff812018d6>] user_path_at_empty+0x36/0x40 [ 19.677859] [<ffffffff811f6a33>] vfs_fstatat+0x53/0xa0 [ 19.678902] [<ffffffff811f6f55>] SYSC_newfstatat+0x15/0x30 [ 19.679998] [<ffffffff811f716e>] SyS_newfstatat+0xe/0x10 [ 19.681087] [<ffffffff818daf6e>] entry_SYSCALL_64_fastpath+0x12/0x6d [ 19.682253] Code: 00 41 8b 7e 40 49 8d 76 20 e8 6c fe ff ff 84 c0 75 ba 48 89 df 65 ff 0d a6 f5 e0 7e e8 69 cb 00 00 b8 f6 ff ff ff e9 53 ff ff ff <0f> 0b 48 89 df 65 ff 0d 8b f5 e0 7e e8 4e cb 00 00 e9 29 ff ff [ 19.690108] RIP [<ffffffff811fc7c1>] unlazy_walk+0x151/0x170 [ 19.691269] RSP <ffff88007ecffc30> [ 19.692149] ---[ end trace 563d316f4eb96ab1 ]--- [ 19.693134] Kernel panic - not syncing: Fatal exception 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 Thanks, Kernel Test Robot

4 years, 2 months

3
3
0 / 0

[lkp] [[media] sound/usb] aebb2b89bf: BUG: unable to handle kernel NULL pointer dereference at 0000000c

by kernel test robot

FYI, we noticed the below changes on git://linuxtv.org/media_tree.git master commit aebb2b89bff0fdeb4e7ddd73dcbccde1a0f27554 ("[media] sound/usb: Use Media Controller API to share media resources") [ 13.108846] UBSAN: Undefined behaviour in sound/usb/card.c:565:2 [ 13.110984] member access within null pointer of type 'const struct snd_usb_audio_quirk' [ 13.113150] CPU: 0 PID: 15 Comm: kworker/0:1 Not tainted 4.5.0-rc3-00373-gaebb2b8 #1 [ 13.115204] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 13.121461] Workqueue: usb_hub_wq hub_event [ 13.122606] d04ee6c0 d32c1860 c9512a06 d32c1870 c95d47ec cbc57c64 00000000 d32c1894 [ 13.124897] c95d4d81 cadec8c5 cadec925 cbb16d18 29c049bb 00000282 00000000 00000001 [ 13.127183] d32c192c ca72af8f 00000001 00000031 cae7b86f c0030d08 d042050c cbc5534c [ 13.129468] Call Trace: [ 13.130135] [<c9512a06>] dump_stack+0x16/0x20 [ 13.131305] [<c95d47ec>] ubsan_epilogue+0xc/0x40 [ 13.132575] [<c95d4d81>] __ubsan_handle_type_mismatch+0x171/0x200 [ 13.134244] [<ca72af8f>] usb_audio_probe+0x210f/0x21e0 [ 13.135677] [<c9ca533a>] usb_probe_interface+0x4fa/0xad0 [ 13.137083] [<c9ca4e40>] ? usb_probe_device+0x190/0x190 [ 13.138441] [<c9b42c79>] driver_probe_device+0x829/0xde0 [ 13.139834] [<c9b4368b>] __device_attach_driver+0x15b/0x340 [ 13.141302] [<c9b3cec2>] bus_for_each_drv+0xa2/0x1d0 [ 13.142610] [<c9b41e63>] __device_attach+0x193/0x460 [ 13.143914] [<c9b43530>] ? __driver_attach+0x300/0x300 [ 13.145258] [<c9b438dd>] device_initial_probe+0xd/0x10 [ 13.146600] [<c9b3d8f0>] bus_probe_device+0x190/0x2b0 [ 13.147956] [<c9b39204>] device_add+0x10a4/0x1dc0 [ 13.149128] [<c9c77994>] ? usb_unlocked_enable_lpm+0x64/0xe0 [ 13.150655] [<c9c779a9>] ? usb_unlocked_enable_lpm+0x79/0xe0 [ 13.152215] [<c9c9efc6>] usb_set_configuration+0xe86/0x19a0 [ 13.153758] [<c9cc3409>] generic_probe+0xc9/0x240 [ 13.155066] [<c8fba35f>] ? do_raw_spin_unlock+0x16f/0x310 [ 13.156564] [<c9ca4d34>] usb_probe_device+0x84/0x190 [ 13.157940] [<c9ca4cb0>] ? usb_autoresume_device+0x130/0x130 [ 13.159499] [<c9b42c79>] driver_probe_device+0x829/0xde0 [ 13.160901] [<c8fba35f>] ? do_raw_spin_unlock+0x16f/0x310 [ 13.162298] [<c9b4368b>] __device_attach_driver+0x15b/0x340 [ 13.163747] [<c9b3cec2>] bus_for_each_drv+0xa2/0x1d0 [ 13.165019] [<c9b41e63>] __device_attach+0x193/0x460 [ 13.166311] [<c9b43530>] ? __driver_attach+0x300/0x300 [ 13.167678] [<c9b438dd>] device_initial_probe+0xd/0x10 [ 13.169037] [<c9b3d8f0>] bus_probe_device+0x190/0x2b0 [ 13.170347] [<c9b39204>] device_add+0x10a4/0x1dc0 [ 13.171600] [<c9c75178>] usb_new_device+0x508/0x15a0 [ 13.172847] [<c9c7f5c1>] hub_port_connect+0xde1/0x25d0 [ 13.174233] [<c9c82159>] port_event+0x769/0x14a0 [ 13.175522] [<c9c831d7>] hub_event+0x347/0x950 [ 13.176780] [<c8f3c75b>] process_one_work+0x5ab/0x1600 [ 13.178197] [<c8f3c6b9>] ? process_one_work+0x509/0x1600 [ 13.179669] [<c8f3d840>] ? worker_thread+0x90/0x1bb0 [ 13.181051] [<c8f3de97>] worker_thread+0x6e7/0x1bb0 [ 13.182409] [<c8f52c65>] kthread+0x1a5/0x2a0 [ 13.183620] [<c8f3d7b0>] ? process_one_work+0x1600/0x1600 [ 13.185078] [<c8f72ff1>] ? schedule_tail+0x11/0x100 [ 13.186390] [<ca921130>] ret_from_kernel_thread+0x20/0x40 [ 13.187834] [<c8f52ac0>] ? __kthread_parkme+0x140/0x140 [ 13.189226] ================================================================================ [ 13.191589] BUG: unable to handle kernel NULL pointer dereference at 0000000c [ 13.193597] IP: [<ca729e96>] usb_audio_probe+0x1016/0x21e0 [ 13.195130] *pdpt = 00000000104eb001 *pde = 0000000000000000 [ 13.196771] Oops: 0000 [#1] DEBUG_PAGEALLOC [ 13.198013] CPU: 0 PID: 15 Comm: kworker/0:1 Not tainted 4.5.0-rc3-00373-gaebb2b8 #1 [ 13.200098] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 13.202487] Workqueue: usb_hub_wq hub_event [ 13.203623] task: d32be000 ti: d32c0000 task.ti: d32c0000 [ 13.205034] EIP: 0060:[<ca729e96>] EFLAGS: 00010282 CPU: 0 [ 13.206468] EIP is at usb_audio_probe+0x1016/0x21e0 [ 13.207752] EAX: 00000000 EBX: 00000000 ECX: 00000000 EDX: 0047f3db [ 13.209348] ESI: 00000001 EDI: d04ee6c0 EBP: d32c192c ESP: d32c189c [ 13.211039] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068 [ 13.212493] CR0: 8005003b CR2: 0000000c CR3: 104de040 CR4: 000006b0 [ 13.214192] Stack: [ 13.214766] 00000001 00000031 cae7b86f c0030d08 d042050c cbc5534c 00000000 ffffffff [ 13.217150] 00000001 cbb19220 cbe45044 00016110 d32c1904 00000212 d04de0a0 d0420570 [ 13.219512] cbe450d0 00000000 d04d0ca8 00000000 331a44e4 00000009 00000000 cbe44eb4 [ 13.221805] Call Trace: [ 13.222469] [<c9ca533a>] usb_probe_interface+0x4fa/0xad0 [ 13.223894] [<c9ca4e40>] ? usb_probe_device+0x190/0x190 [ 13.226821] [<c9b42c79>] driver_probe_device+0x829/0xde0 [ 13.228302] [<c9b4368b>] __device_attach_driver+0x15b/0x340 [ 13.229854] [<c9b3cec2>] bus_for_each_drv+0xa2/0x1d0 [ 13.231232] [<c9b41e63>] __device_attach+0x193/0x460 [ 13.232612] [<c9b43530>] ? __driver_attach+0x300/0x300 [ 13.234033] [<c9b438dd>] device_initial_probe+0xd/0x10 [ 13.235411] [<c9b3d8f0>] bus_probe_device+0x190/0x2b0 [ 13.236767] [<c9b39204>] device_add+0x10a4/0x1dc0 [ 13.238023] [<c9c77994>] ? usb_unlocked_enable_lpm+0x64/0xe0 [ 13.239515] [<c9c779a9>] ? usb_unlocked_enable_lpm+0x79/0xe0 [ 13.241063] [<c9c9efc6>] usb_set_configuration+0xe86/0x19a0 [ 13.242604] [<c9cc3409>] generic_probe+0xc9/0x240 [ 13.243912] [<c8fba35f>] ? do_raw_spin_unlock+0x16f/0x310 [ 13.245400] [<c9ca4d34>] usb_probe_device+0x84/0x190 [ 13.246781] [<c9ca4cb0>] ? usb_autoresume_device+0x130/0x130 [ 13.248337] [<c9b42c79>] driver_probe_device+0x829/0xde0 [ 13.249814] [<c8fba35f>] ? do_raw_spin_unlock+0x16f/0x310 [ 13.251266] [<c9b4368b>] __device_attach_driver+0x15b/0x340 [ 13.252784] [<c9b3cec2>] bus_for_each_drv+0xa2/0x1d0 [ 13.254168] [<c9b41e63>] __device_attach+0x193/0x460 [ 13.255546] [<c9b43530>] ? __driver_attach+0x300/0x300 [ 13.256948] [<c9b438dd>] device_initial_probe+0xd/0x10 [ 13.258323] [<c9b3d8f0>] bus_probe_device+0x190/0x2b0 [ 13.259682] [<c9b39204>] device_add+0x10a4/0x1dc0 [ 13.260945] [<c9c75178>] usb_new_device+0x508/0x15a0 [ 13.262271] [<c9c7f5c1>] hub_port_connect+0xde1/0x25d0 [ 13.263639] [<c9c82159>] port_event+0x769/0x14a0 [ 13.264914] [<c9c831d7>] hub_event+0x347/0x950 [ 13.266153] [<c8f3c75b>] process_one_work+0x5ab/0x1600 [ 13.267580] [<c8f3c6b9>] ? process_one_work+0x509/0x1600 [ 13.269047] [<c8f3d840>] ? worker_thread+0x90/0x1bb0 [ 13.270426] [<c8f3de97>] worker_thread+0x6e7/0x1bb0 [ 13.271781] [<c8f52c65>] kthread+0x1a5/0x2a0 [ 13.272977] [<c8f3d7b0>] ? process_one_work+0x1600/0x1600 [ 13.274454] [<c8f72ff1>] ? schedule_tail+0x11/0x100 [ 13.275761] [<ca921130>] ret_from_kernel_thread+0x20/0x40 [ 13.277198] [<c8f52ac0>] ? __kthread_parkme+0x140/0x140 [ 13.278590] Code: c4 01 00 00 00 88 45 c8 31 d2 b8 c0 66 b1 cb e8 91 ad ea fe e9 84 f8 ff ff 8d 74 26 00 8b 45 bc 85 c0 0f 84 f0 10 00 00 8b 45 bc <8a> 58 0c 80 fb 01 0f 87 b1 10 00 00 83 e3 01 8d 0c 9d 00 00 00 [ 13.286051] EIP: [<ca729e96>] usb_audio_probe+0x1016/0x21e0 SS:ESP 0068:d32c189c [ 13.288125] CR2: 000000000000000c [ 13.289052] ---[ end trace 01c578e7e96409e3 ]--- [ 13.290314] Kernel panic - not syncing: Fatal exception Thanks, Kernel Test Robot

4 years, 2 months

1
0
0 / 0

Please add git tree

by Matthew Wilcox

Please add git://git.infradead.org/users/willy/linux-dax.git to the list of repos to test. The current branch I want tested is dax-pud-2015-03-05 but there will be other branches to test in the future. Thank you!

4 years, 2 months

3
5
0 / 0

[lkp] [tracing] 633f6f58af: INFO: suspicious RCU usage

by kernel test robot

FYI, we noticed the dmesgs warning on https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master commit 633f6f58af445022e38417599a4789b5fc510b71 ("tracing: Remove duplicate checks for online CPUs") +-------------------------------------+------------+------------+ | | d39cdd2036 | 633f6f58af | +-------------------------------------+------------+------------+ | INFO:suspicious_RCU_usage | 0 | 11 | | RCU_used_illegally_from_offline_CPU | 0 | 11 | | backtrace:cpu_startup_entry | 0 | 11 | +-------------------------------------+------------+------------+ [ 7.181678] numa_remove_cpu cpu 0 node 0: mask now 1 [ 7.183715] [ 7.183948] =============================== [ 7.184546] [ INFO: suspicious RCU usage. ] [ 7.185140] 4.5.0-rc7-00002-g633f6f5 #1 Not tainted [ 7.185831] ------------------------------- [ 7.186425] include/trace/events/tlb.h:56 suspicious rcu_dereference_check() usage! [ 7.187755] [ 7.187755] other info that might help us debug this: [ 7.187755] [ 7.188874] [ 7.188874] RCU used illegally from offline CPU! [ 7.188874] rcu_scheduler_active = 1, debug_locks = 0 [ 7.190426] no locks held by swapper/0/0. [ 7.190995] [ 7.190995] stack backtrace: [ 7.191614] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.5.0-rc7-00002-g633f6f5 #1 [ 7.192659] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 [ 7.193891] 0000000000000000 ffffffff81e03e20 ffffffff8139477c ffffffff81e0f500 [ 7.195004] 0000000000000001 ffffffff81e03e50 ffffffff810e29cb ffff8800107a0b00 [ 7.196116] ffffffff81e76a40 0000000000000000 ffff8800107a0e98 ffffffff81e03e68 [ 7.197229] Call Trace: [ 7.197592] [<ffffffff8139477c>] dump_stack+0x81/0xb6 [ 7.198313] [<ffffffff810e29cb>] lockdep_rcu_suspicious+0xf7/0x100 [ 7.199193] [<ffffffff810c64b5>] trace_tlb_flush+0x96/0xf1 [ 7.200130] [<ffffffff810c65d0>] switch_mm+0x73/0x11d [ 7.200957] [<ffffffff810cb92e>] idle_task_exit+0x67/0x74 [ 7.201731] [<ffffffff81036888>] play_dead_common+0xe/0x42 [ 7.202516] [<ffffffff810368cd>] native_play_dead+0x11/0x104 [ 7.203318] [<ffffffff8100f0db>] arch_cpu_idle_dead+0xf/0x11 [ 7.204127] [<ffffffff810dbd27>] cpu_startup_entry+0xdd/0x30a [ 7.204951] [<ffffffff816d3d93>] rest_init+0x13a/0x140 [ 7.205694] [<ffffffff821a6f91>] start_kernel+0x4b1/0x4be [ 7.206470] [<ffffffff821a6120>] ? early_idt_handler_array+0x120/0x120 [ 7.207395] [<ffffffff821a6315>] x86_64_start_reservations+0x2a/0x2c [ 7.208297] [<ffffffff821a6444>] x86_64_start_kernel+0x12d/0x13a [ 7.238450] CPU 0 is now offline [ 7.240139] debug: unmapping init [mem 0xffffffff81fcf000-0xffffffff82297fff] [ 7.242450] Write protecting the kernel read-only data: 14336k Thanks, Xiaolong Ye

4 years, 2 months

1
0
0 / 0

[lkp] [RAID5] 6ab2a4b806: 91.9% fsmark.app_overhead

by kernel test robot

FYI, we noticed the below changes on https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master commit 6ab2a4b806ae21b6c3e47c5ff1285ec06d505325 ("RAID5: revert e9e4c377e2f563 to fix a livelock") ========================================================================================= compiler/cpufreq_governor/disk/filesize/fs/iterations/kconfig/md/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase: gcc-4.9/performance/8BRD_12G/4M/xfs/1x/x86_64-rhel/RAID5/64t/debian-x86_64-2015-02-07.cgz/fsyncBeforeClose/lkp-hsx02/60G/fsmark commit: 27a353c026a879a1001e5eac4bda75b16262c44a 6ab2a4b806ae21b6c3e47c5ff1285ec06d505325 27a353c026a879a1 6ab2a4b806ae21b6c3e47c5ff1 ---------------- -------------------------- %stddev %change %stddev \ | \ 592411 ± 2% +91.9% 1136931 ± 11% fsmark.app_overhead 98.37 ± 1% -21.9% 76.80 ± 2% fsmark.files_per_sec 188.95 ± 0% +59.9% 302.16 ± 3% fsmark.time.elapsed_time 188.95 ± 0% +59.9% 302.16 ± 3% fsmark.time.elapsed_time.max 20813 ± 4% +290.1% 81196 ± 4% fsmark.time.minor_page_faults 165.00 ± 2% +553.8% 1078 ± 7% fsmark.time.percent_of_cpu_this_job_got 312.06 ± 3% +947.5% 3269 ± 10% fsmark.time.system_time 10132990 ± 2% +465.7% 57325950 ± 7% fsmark.time.voluntary_context_switches 171460 ± 2% -24.4% 129549 ± 6% meminfo.Writeback 830948 ± 85% +339.1% 3649060 ± 89% numa-numastat.node3.numa_foreign 2814 ± 1% -10.4% 2523 ± 0% slabinfo.kmalloc-4096.active_objs 2870 ± 1% -12.1% 2523 ± 0% slabinfo.kmalloc-4096.num_objs 285.82 ± 0% +39.4% 398.30 ± 2% uptime.boot 40413 ± 0% +33.4% 53898 ± 2% uptime.idle 1.92 ± 1% +345.0% 8.53 ± 6% turbostat.%Busy 55.25 ± 1% +347.1% 247.00 ± 6% turbostat.Avg_MHz 356.24 ± 0% +5.5% 375.77 ± 0% turbostat.PkgWatt 7994 ± 3% +26.5% 10115 ± 5% softirqs.NET_RX 308842 ± 48% +222.4% 995686 ± 12% softirqs.RCU 227016 ± 26% +477.0% 1309870 ± 16% softirqs.SCHED 743051 ± 31% +195.6% 2196488 ± 8% softirqs.TIMER 329656 ± 0% -37.0% 207541 ± 3% vmstat.io.bo 1.75 ± 24% +500.0% 10.50 ± 4% vmstat.procs.r 108684 ± 1% +249.1% 379378 ± 3% vmstat.system.cs 3836 ± 2% +257.4% 13711 ± 5% vmstat.system.in 188.95 ± 0% +59.9% 302.16 ± 3% time.elapsed_time 188.95 ± 0% +59.9% 302.16 ± 3% time.elapsed_time.max 20813 ± 4% +290.1% 81196 ± 4% time.minor_page_faults 165.00 ± 2% +553.8% 1078 ± 7% time.percent_of_cpu_this_job_got 312.06 ± 3% +947.5% 3269 ± 10% time.system_time 10132990 ± 2% +465.7% 57325950 ± 7% time.voluntary_context_switches 2497 ±142% -87.3% 316.75 ± 15% numa-meminfo.node1.Inactive(anon) 2591 ±137% -84.5% 401.50 ± 11% numa-meminfo.node1.Shmem 16695 ± 1% -16.9% 13875 ± 9% numa-meminfo.node1.Unevictable 16915 ± 1% -17.4% 13969 ± 8% numa-meminfo.node2.Unevictable 5698 ± 22% +90.1% 10831 ± 24% numa-meminfo.node3.Active(anon) 5660 ± 22% +89.2% 10707 ± 24% numa-meminfo.node3.AnonPages 550.25 ± 22% +1135.0% 6795 ± 50% numa-meminfo.node3.Inactive(anon) 626.25 ± 19% +1002.1% 6902 ± 50% numa-meminfo.node3.Shmem 383.75 ± 3% +11.7% 428.75 ± 2% numa-vmstat.node1.nr_alloc_batch 623.75 ±142% -87.4% 78.75 ± 16% numa-vmstat.node1.nr_inactive_anon 647.25 ±137% -84.6% 100.00 ± 11% numa-vmstat.node1.nr_shmem 4173 ± 1% -16.9% 3468 ± 9% numa-vmstat.node1.nr_unevictable 4228 ± 1% -17.4% 3492 ± 8% numa-vmstat.node2.nr_unevictable 1424 ± 22% +90.1% 2708 ± 24% numa-vmstat.node3.nr_active_anon 1415 ± 22% +89.2% 2677 ± 24% numa-vmstat.node3.nr_anon_pages 137.00 ± 22% +1139.8% 1698 ± 50% numa-vmstat.node3.nr_inactive_anon 156.00 ± 19% +1005.8% 1725 ± 50% numa-vmstat.node3.nr_shmem 36.67 ±130% +52543.9% 19302 ±139% proc-vmstat.kswapd_high_wmark_hit_quickly 3.75 ± 11% +6480.0% 246.75 ±101% proc-vmstat.nr_pages_scanned 42860 ± 2% -24.4% 32415 ± 6% proc-vmstat.nr_writeback 10144 ± 8% +593.7% 70377 ± 4% proc-vmstat.numa_hint_faults 5990 ± 14% +608.3% 42426 ± 4% proc-vmstat.numa_hint_faults_local 1310 ± 14% +365.9% 6105 ± 11% proc-vmstat.numa_pages_migrated 13530 ± 6% +452.3% 74730 ± 4% proc-vmstat.numa_pte_updates 4444 ± 9% +476.1% 25606 ±101% proc-vmstat.pageoutrun 523627 ± 1% +67.6% 877482 ± 3% proc-vmstat.pgfault 1310 ± 14% +365.9% 6105 ± 11% proc-vmstat.pgmigrate_success 16384 ± 4% +72.5% 28257 ± 15% proc-vmstat.slabs_scanned 2452516 ± 62% +165.0% 6499322 ±123% latency_stats.avg.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_alloc_read_agfl.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_ialloc_ag_alloc.xfs_dialloc.xfs_ialloc 619509 ±123% -100.0% 0.00 ± -1% latency_stats.avg.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_btree_read_buf_block.xfs_btree_lookup_get_block.xfs_btree_lookup.xfs_alloc_lookup_eq.xfs_alloc_fixup_trees.xfs_alloc_ag_vextent_size 668825 ±167% -74.7% 169025 ± 94% latency_stats.avg.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_btree_read_buf_block.xfs_btree_lookup_get_block.xfs_btree_lookup.xfs_inobt_insert.xfs_ialloc_ag_alloc.xfs_dialloc 0.00 ± -1% +Inf% 10533842 ±100% latency_stats.avg.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_block_read.xfs_dir2_block_addname.xfs_dir_createname.xfs_create.xfs_generic_create 1434223 ± 33% +205.1% 4375501 ± 39% latency_stats.avg.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_imap_to_bp.xfs_iread.xfs_iget.xfs_ialloc.xfs_dir_ialloc.xfs_create 1134 ±173% +1.6e+05% 1818032 ±153% latency_stats.avg.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_read_agf.xfs_alloc_read_agf.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_ialloc_ag_alloc.xfs_dialloc 35260 ± 6% +280.2% 134055 ± 12% latency_stats.hits.raid5_get_active_stripe.[raid456].raid5_make_request.[raid456].md_make_request.generic_make_request.submit_bio._xfs_buf_ioapply.xfs_buf_submit.xlog_bdstrat.xlog_sync.xlog_state_release_iclog._xfs_log_force_lsn.xfs_file_fsync 9385649 ± 3% +505.1% 56794939 ± 7% latency_stats.hits.raid5_get_active_stripe.[raid456].raid5_make_request.[raid456].md_make_request.generic_make_request.submit_bio.xfs_submit_ioend_bio.xfs_submit_ioend.xfs_vm_writepage.__writepage.write_cache_pages.generic_writepages.xfs_vm_writepages 3807550 ± 25% +155.1% 9711263 ± 73% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_alloc_read_agfl.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_bmap_btalloc.xfs_bmap_alloc.xfs_bmapi_write 2688649 ± 59% +187.4% 7727816 ±117% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_alloc_read_agfl.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_ialloc_ag_alloc.xfs_dialloc.xfs_ialloc 673991 ±108% -100.0% 0.00 ± -1% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_btree_read_buf_block.xfs_btree_lookup_get_block.xfs_btree_lookup.xfs_alloc_lookup_eq.xfs_alloc_fixup_trees.xfs_alloc_ag_vextent_size 668825 ±167% -74.6% 169683 ± 94% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_btree_read_buf_block.xfs_btree_lookup_get_block.xfs_btree_lookup.xfs_inobt_insert.xfs_ialloc_ag_alloc.xfs_dialloc 0.00 ± -1% +Inf% 14261726 ± 73% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_block_read.xfs_dir2_block_addname.xfs_dir_createname.xfs_create.xfs_generic_create 1260860 ± 73% +328.3% 5400244 ±169% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_leaf_read.xfs_dir2_leaf_addname.xfs_dir_createname.xfs_create.xfs_generic_create 5058489 ± 54% +148.5% 12570469 ± 76% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_leaf_read.xfs_dir2_leaf_lookup_int.xfs_dir2_leaf_lookup.xfs_dir_lookup.xfs_lookup 1134 ±173% +4.8e+05% 5434961 ±154% latency_stats.max.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_read_agf.xfs_alloc_read_agf.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_ialloc_ag_alloc.xfs_dialloc 6967127 ± 59% +82.8% 12733733 ±127% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_alloc_read_agfl.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_ialloc_ag_alloc.xfs_dialloc.xfs_ialloc 11217182 ± 27% +199.8% 33628397 ± 72% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_alloc_read_agfl.xfs_alloc_fix_freelist.xfs_free_extent.xfs_trans_free_extent.xfs_bmap_finish.xfs_itruncate_extents 674368 ±108% -100.0% 0.00 ± -1% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_btree_read_buf_block.xfs_btree_lookup_get_block.xfs_btree_lookup.xfs_alloc_lookup_eq.xfs_alloc_fixup_trees.xfs_alloc_ag_vextent_size 668825 ±167% -50.1% 333621 ± 97% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_btree_read_buf_block.xfs_btree_lookup_get_block.xfs_btree_lookup.xfs_inobt_insert.xfs_ialloc_ag_alloc.xfs_dialloc 0.00 ± -1% +Inf% 51551059 ± 88% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_block_read.xfs_dir2_block_addname.xfs_dir_createname.xfs_create.xfs_generic_create 87014211 ± 98% +420.2% 4.527e+08 ± 36% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_block_read.xfs_dir2_block_lookup_int.xfs_dir2_block_lookup.xfs_dir_lookup.xfs_lookup 1318029 ± 69% +573.2% 8872791 ±169% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_da_read_buf.xfs_dir3_leaf_read.xfs_dir2_leaf_addname.xfs_dir_createname.xfs_create.xfs_generic_create 60188444 ± 39% +368.4% 2.819e+08 ± 29% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_imap_to_bp.xfs_iread.xfs_iget.xfs_ialloc.xfs_dir_ialloc.xfs_create 81840534 ± 50% +295.9% 3.24e+08 ± 29% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_read_agf.xfs_alloc_read_agf.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_bmap_btalloc.xfs_bmap_alloc 1134 ±173% +4.8e+05% 5435006 ±154% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_read_agf.xfs_alloc_read_agf.xfs_alloc_fix_freelist.xfs_alloc_vextent.xfs_ialloc_ag_alloc.xfs_dialloc 1.69e+08 ± 42% +381.8% 8.144e+08 ± 23% latency_stats.sum.down.xfs_buf_lock._xfs_buf_find.xfs_buf_get_map.xfs_buf_read_map.xfs_trans_read_buf_map.xfs_read_agi.xfs_ialloc_read_agi.xfs_dialloc.xfs_ialloc.xfs_dir_ialloc.xfs_create 411.75 ±115% +3043.3% 12942 ± 21% latency_stats.sum.wait_on_page_bit.__migration_entry_wait.migration_entry_wait.do_swap_page.handle_mm_fault.__do_page_fault.do_page_fault.page_fault 70443 ± 38% +284.3% 270746 ± 59% latency_stats.sum.xlog_cil_force_lsn._xfs_log_force_lsn.xfs_file_fsync.vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath lkp-hsx02: Brickland Haswell-EX Memory: 128G uptime.boot 450 ++--------------------------------------------------------------------+ O O O O O O O O O O | 400 ++ O O O O O O O O O O O | 350 ++ | | | 300 *+.*..*...*..*..*..*..* *..*..*..*..*...*..*..*..*..*..*...*..*..* 250 ++ : : | | : : | 200 ++ : : | 150 ++ : : | | : : | 100 ++ : : | 50 ++ : : | | : | 0 ++-----------------------*--------------------------------------------+ uptime.idle 60000 ++-----------------------O------------------------------------------+ O O O O O O O O O O O O O | 50000 ++ O O O O O O O | | | | .*.. .*.. .*.. | 40000 *+.*..*..*. *...*..* *..*..*..*. *. *...*..*..*..*..*..* | : : | 30000 ++ : : | | : : | 20000 ++ : : | | : : | | : : | 10000 ++ : : | | : | 0 ++-----------------------*------------------------------------------+ turbostat.Avg_MHz 300 ++--------------------------------------------------------------------+ O O O O | 250 ++ O | | O O O O O O O O O O O O O O | | O O | 200 ++ | | | 150 ++ | | | 100 ++ | | | *..*..*...*..*.. .*...*..*..*..*.. .*...*..*..* 50 ++ *..*..*.. .*..*..*..*. *. | | .. | 0 ++-----------------------*--------------------------------------------+ turbostat._Busy 10 ++---------------------------------------------------------------------+ 9 O+ O O O | | O O O O O O | 8 ++ O O O O O O O O O O | 7 ++ O | | | 6 ++ | 5 ++ | 4 ++ | | | 3 ++ | 2 *+.*..*...*..*.. .*... *.. .*..*..*..*...*..*..*..*...*..*..* | *. *.. + *...*. | 1 ++ + | 0 ++------------------------*--------------------------------------------+ turbostat.PkgWatt 400 ++--------------------------------------------------------------------+ O O O O O O O O O O O O O O O O O O O O O | 350 *+.*..*...*..*..*..*..* *..*..*..*..*...*..*..*..*..*..*...*..*..* 300 ++ : : | | : : | 250 ++ : : | | : : | 200 ++ : : | | : : | 150 ++ : : | 100 ++ : : | | : : | 50 ++ :: | | : | 0 ++-----------------------*--------------------------------------------+ fsmark.app_overhead 1.4e+06 ++----------------------------O--------------------------O--------+ | | 1.2e+06 ++ O O O O | O O O O O | 1e+06 ++ O O O O O O O O | | O O | 800000 ++ | | | 600000 *+.*..*..*..*..*..*..* *..*..*..*..*..*..*..*..*..*..*..*..*..* | : : | 400000 ++ : : | | : : | 200000 ++ : : | | :: | 0 ++----------------------*-----------------------------------------+ fsmark.time.system_time 4500 ++-------------------------------------------------------------------+ | O | 4000 O+ O O | 3500 ++ O | | O O O O O O O | 3000 ++ O O O O O O O | 2500 ++ O O | | | 2000 ++ | 1500 ++ | | | 1000 ++ | 500 ++ | *..*..*..*...*..*..*..*.. .*..*...*..*..*..*..*..*..*..*...*..*..*..* 0 ++-----------------------*-------------------------------------------+ fsmark.time.percent_of_cpu_this_job_got 1400 ++-------------------------------------------------------------------+ | | 1200 O+ O O O | | O O O O O O | 1000 ++ O O O O O O O O O | | O O | 800 ++ | | | 600 ++ | | | 400 ++ | | | 200 *+.*..*..*...*.. .*.. .*..*..*..*..*..*...*..*..*..* | *. *.. .*..*...*..*. | 0 ++-----------------------*-------------------------------------------+ fsmark.time.elapsed_time 350 ++-----------------------O--------------------------------------------+ O O O O | 300 ++ O O O O O O O O O O O O O O O O | | | 250 ++ | | | 200 *+.*..*...*..*.. ..*.. .*.. .*...*..*..| | *..*..* *..*..*..*..*. *. *..*. * 150 ++ : : | | : : | 100 ++ : : | | : : | 50 ++ : : | | :: | 0 ++-----------------------*--------------------------------------------+ fsmark.time.elapsed_time.max 350 ++-----------------------O--------------------------------------------+ O O O O | 300 ++ O O O O O O O O O O O O O O O O | | | 250 ++ | | | 200 *+.*..*...*..*.. ..*.. .*.. .*...*..*..| | *..*..* *..*..*..*..*. *. *..*. * 150 ++ : : | | : : | 100 ++ : : | | : : | 50 ++ : : | | :: | 0 ++-----------------------*--------------------------------------------+ fsmark.time.minor_page_faults 100000 ++----------------------O-----O------------------------------------+ 90000 ++ O | O O O O O O O O | 80000 ++ O O O O O O | 70000 ++ O O O | | O | 60000 ++ | 50000 ++ | 40000 ++ | | | 30000 ++ | 20000 *+.*..*..*..*..*..*..* *..*...*..*..*..*..*..*..*..*..*..*..*..* | + + | 10000 ++ + + | 0 ++----------------------*------------------------------------------+ fsmark.time.voluntary_context_switches 8e+07 ++------------------------------------------------------------------+ | O | 7e+07 O+ O O | 6e+07 ++ O O O O O | | O O O O O O O O | 5e+07 ++ O O O O | | | 4e+07 ++ | | | 3e+07 ++ | 2e+07 ++ | | | 1e+07 *+.*..*..*..*..*...*..*.. *..*..*..*..*..*..*..*...*..*..*..*..*..* | .. | 0 ++-----------------------*------------------------------------------+ time.system_time 4500 ++-------------------------------------------------------------------+ | O | 4000 O+ O O | 3500 ++ O | | O O O O O O O | 3000 ++ O O O O O O O | 2500 ++ O O | | | 2000 ++ | 1500 ++ | | | 1000 ++ | 500 ++ | *..*..*..*...*..*..*..*.. .*..*...*..*..*..*..*..*..*..*...*..*..*..* 0 ++-----------------------*-------------------------------------------+ time.percent_of_cpu_this_job_got 1400 ++-------------------------------------------------------------------+ | | 1200 O+ O O O | | O O O O O O | 1000 ++ O O O O O O O O O | | O O | 800 ++ | | | 600 ++ | | | 400 ++ | | | 200 *+.*..*..*...*.. .*.. .*..*..*..*..*..*...*..*..*..* | *. *.. .*..*...*..*. | 0 ++-----------------------*-------------------------------------------+ time.elapsed_time 350 ++-----------------------O--------------------------------------------+ O O O O | 300 ++ O O O O O O O O O O O O O O O O | | | 250 ++ | | | 200 *+.*..*...*..*.. ..*.. .*.. .*...*..*..| | *..*..* *..*..*..*..*. *. *..*. * 150 ++ : : | | : : | 100 ++ : : | | : : | 50 ++ : : | | :: | 0 ++-----------------------*--------------------------------------------+ time.elapsed_time.max 350 ++-----------------------O--------------------------------------------+ O O O O | 300 ++ O O O O O O O O O O O O O O O O | | | 250 ++ | | | 200 *+.*..*...*..*.. ..*.. .*.. .*...*..*..| | *..*..* *..*..*..*..*. *. *..*. * 150 ++ : : | | : : | 100 ++ : : | | : : | 50 ++ : : | | :: | 0 ++-----------------------*--------------------------------------------+ time.minor_page_faults 100000 ++----------------------O-----O------------------------------------+ 90000 ++ O | O O O O O O O O | 80000 ++ O O O O O O | 70000 ++ O O O | | O | 60000 ++ | 50000 ++ | 40000 ++ | | | 30000 ++ | 20000 *+.*..*..*..*..*..*..* *..*...*..*..*..*..*..*..*..*..*..*..*..* | + + | 10000 ++ + + | 0 ++----------------------*------------------------------------------+ time.voluntary_context_switches 8e+07 ++------------------------------------------------------------------+ | O | 7e+07 O+ O O | 6e+07 ++ O O O O O | | O O O O O O O O | 5e+07 ++ O O O O | | | 4e+07 ++ | | | 3e+07 ++ | 2e+07 ++ | | | 1e+07 *+.*..*..*..*..*...*..*.. *..*..*..*..*..*..*..*...*..*..*..*..*..* | .. | 0 ++-----------------------*------------------------------------------+ [*] bisect-good sample [O] bisect-bad sample 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, Xiaolong Ye

4 years, 2 months

1
0
0 / 0

[lkp] [f2fs] 24b8491251: -7.1% fsmark.app_overhead

by kernel test robot

FYI, we noticed the below changes on https://git.kernel.org/pub/scm/linux/kernel/git/jaegeuk/f2fs dev-test commit 24b8491251cde66879e74092167cc0f27a1f11ce ("f2fs: preallocate blocks for buffered aio writes") ========================================================================================= compiler/cpufreq_governor/disk/filesize/fs/iterations/kconfig/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase: gcc-4.9/performance/1BRD_48G/4M/f2fs/1x/x86_64-rhel/64t/debian-x86_64-2015-02-07.cgz/NoSync/lkp-hsx04/40G/fsmark commit: b439b103a6c9eb3417f34b4a609d4e00b4c59aca 24b8491251cde66879e74092167cc0f27a1f11ce b439b103a6c9eb34 24b8491251cde66879e7409216 ---------------- -------------------------- %stddev %change %stddev \ | \ 612289 ± 1% -7.1% 568876 ± 1% fsmark.app_overhead 1495 ± 2% +25.1% 1871 ± 2% fsmark.files_per_sec 3422 ± 2% +41.0% 4827 ± 4% fsmark.time.percent_of_cpu_this_job_got 1188385 ± 1% -68.3% 377081 ± 9% fsmark.time.voluntary_context_switches 264601 ± 26% -38.8% 161926 ± 43% softirqs.SCHED 4802 ± 5% +84.1% 8841 ± 12% proc-vmstat.pgactivate 54154 ± 2% -11.3% 48039 ± 4% proc-vmstat.pgfault 43810 ± 2% -9.3% 39722 ± 2% proc-vmstat.pgfree 19.00 ± 20% +50.0% 28.50 ± 8% vmstat.procs.r 208274 ± 6% -66.3% 70257 ± 8% vmstat.system.cs 24846 ± 7% +18.3% 29381 ± 6% vmstat.system.in 4254 ± 1% +11.5% 4745 ± 4% numa-meminfo.node1.KernelStack 8893 ± 20% -33.7% 5896 ± 14% numa-meminfo.node3.Active(anon) 28208 ± 7% +21.6% 34295 ± 6% numa-meminfo.node3.Active(file) 8828 ± 20% -34.7% 5762 ± 13% numa-meminfo.node3.AnonPages 654.25 ± 9% -17.9% 537.00 ± 18% numa-vmstat.node0.nr_alloc_batch 2217 ± 20% -33.7% 1470 ± 14% numa-vmstat.node3.nr_active_anon 7050 ± 7% +21.6% 8573 ± 6% numa-vmstat.node3.nr_active_file 2208 ± 19% -34.7% 1441 ± 13% numa-vmstat.node3.nr_anon_pages 6.97 ± 2% -19.9% 5.59 ± 2% time.elapsed_time 6.97 ± 2% -19.9% 5.59 ± 2% time.elapsed_time.max 3422 ± 2% +41.0% 4827 ± 4% time.percent_of_cpu_this_job_got 0.46 ± 1% -12.6% 0.40 ± 4% time.user_time 1188385 ± 1% -68.3% 377081 ± 9% time.voluntary_context_switches 21.32 ± 3% +36.3% 29.05 ± 5% turbostat.%Busy 616.25 ± 3% +36.4% 840.75 ± 5% turbostat.Avg_MHz 26.38 ± 5% -23.9% 20.06 ± 10% turbostat.CPU%c1 2.77 ± 20% -59.9% 1.11 ± 12% turbostat.CPU%c3 309.69 ± 2% +4.5% 323.48 ± 0% turbostat.PkgWatt 15951637 ± 16% +127.1% 36232857 ± 28% cpuidle.C1-HSW.time 93867067 ± 12% -37.6% 58584188 ± 20% cpuidle.C1E-HSW.time 337610 ± 3% -70.0% 101408 ± 7% cpuidle.C1E-HSW.usage 37574978 ± 18% -71.3% 10766602 ± 7% cpuidle.C3-HSW.time 140480 ± 14% -75.2% 34776 ± 14% cpuidle.C3-HSW.usage 7.511e+08 ± 1% -24.4% 5.681e+08 ± 1% cpuidle.C6-HSW.time 790092 ± 3% -78.7% 168250 ± 15% cpuidle.C6-HSW.usage 0.00 ± -1% +Inf% 346080 ± 9% latency_stats.hits.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write 36235 ± 4% -100.0% 0.00 ± -1% latency_stats.hits.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs] 1110160 ± 2% -100.0% 0.00 ± -1% latency_stats.hits.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_node_page.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 10727 ± 2% -100.0% 0.00 ± -1% latency_stats.hits.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 104220 ± 12% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 88684 ± 2% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 62708911 ± 13% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write 6532927 ± 7% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs] 0.00 ± -1% +Inf% 860616 ± 16% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_node_page.[f2fs].f2fs_convert_inline_inode.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write 1.663e+08 ± 0% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_node_page.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 434819 ± 14% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 436260 ± 2% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 375976 ± 14% latency_stats.sum.call_rwsem_down_write_failed.set_node_addr.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 827019 ± 5% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_write_failed.set_node_addr.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write ========================================================================================= compiler/cpufreq_governor/disk/filesize/fs/iterations/kconfig/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase: gcc-4.9/performance/1BRD_48G/4M/f2fs/1x/x86_64-rhel/64t/debian-x86_64-2015-02-07.cgz/NoSync/lkp-hsw-ep2/40G/fsmark commit: b439b103a6c9eb3417f34b4a609d4e00b4c59aca 24b8491251cde66879e74092167cc0f27a1f11ce b439b103a6c9eb34 24b8491251cde66879e7409216 ---------------- -------------------------- %stddev %change %stddev \ | \ 625.40 ± 1% +6.7% 667.52 ± 0% fsmark.files_per_sec 2702 ± 7% -36.6% 1714 ± 12% fsmark.time.involuntary_context_switches 770.50 ± 8% +52.2% 1172 ± 12% fsmark.time.percent_of_cpu_this_job_got 133.18 ± 7% +40.7% 187.40 ± 11% fsmark.time.system_time 686680 ± 3% -67.3% 224529 ± 18% fsmark.time.voluntary_context_switches 80197 ± 13% +19.3% 95683 ± 6% numa-meminfo.node1.Active 106550 ± 19% -38.6% 65433 ± 5% softirqs.SCHED 37713 ± 0% +17.5% 44314 ± 0% proc-vmstat.nr_active_file 5074 ± 4% +30.5% 6620 ± 20% proc-vmstat.pgactivate 178348 ± 1% +14.9% 204838 ± 0% meminfo.Active 150432 ± 0% +17.9% 177429 ± 0% meminfo.Active(file) 4898 ± 21% -12.5% 4284 ± 18% meminfo.AnonHugePages 15.63 ± 7% -42.0% 9.06 ± 6% turbostat.CPU%c1 3.34 ± 15% -22.2% 2.60 ± 6% turbostat.CPU%c3 10.18 ± 45% +89.7% 19.30 ± 14% turbostat.Pkg%pc2 1161365 ± 1% +2.6% 1192137 ± 0% vmstat.io.bo 3.25 ± 59% +138.5% 7.75 ± 10% vmstat.procs.r 65985 ± 5% -60.5% 26040 ± 12% vmstat.system.cs 8603 ± 3% +41.5% 12173 ± 11% vmstat.system.in 2702 ± 7% -36.6% 1714 ± 12% time.involuntary_context_switches 770.50 ± 8% +52.2% 1172 ± 12% time.percent_of_cpu_this_job_got 133.18 ± 7% +40.7% 187.40 ± 11% time.system_time 0.36 ± 6% -17.1% 0.30 ± 4% time.user_time 686680 ± 3% -67.3% 224529 ± 18% time.voluntary_context_switches 42510356 ± 16% -56.7% 18410803 ± 34% cpuidle.C1E-HSW.time 187168 ± 5% -63.9% 67587 ± 5% cpuidle.C1E-HSW.usage 34590282 ± 12% -42.9% 19767899 ± 11% cpuidle.C3-HSW.time 88675 ± 13% -73.0% 23973 ± 33% cpuidle.C3-HSW.usage 1.039e+09 ± 3% -8.8% 9.474e+08 ± 3% cpuidle.C6-HSW.time 380449 ± 12% -70.5% 112252 ± 17% cpuidle.C6-HSW.usage 42507793 ± 13% -66.1% 14427275 ± 19% cpuidle.POLL.time 7918 ± 50% -49.1% 4032 ± 31% cpuidle.POLL.usage 0.00 ± -1% +Inf% 194173 ± 20% latency_stats.hits.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write 27391 ± 4% -100.0% 0.00 ± -1% latency_stats.hits.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs] 621245 ± 4% -100.0% 0.00 ± -1% latency_stats.hits.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_node_page.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 42514 ± 44% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 71010 ± 9% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 29050748 ± 40% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write 5471586 ± 7% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs] 0.00 ± -1% +Inf% 379285 ± 50% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_node_page.[f2fs].f2fs_convert_inline_inode.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write 1.014e+08 ± 0% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_node_page.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 160093 ± 47% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 280946 ± 3% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write 0.00 ± -1% +Inf% 168921 ± 36% latency_stats.sum.call_rwsem_down_write_failed.set_node_addr.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_map_blocks.[f2fs].f2fs_preallocate_blocks.[f2fs].f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath 609595 ± 5% -100.0% 0.00 ± -1% latency_stats.sum.call_rwsem_down_write_failed.set_node_addr.[f2fs].new_node_page.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write lkp-hsx04: Brickland Haswell-EX Memory: 512G lkp-hsw-ep2: Brickland Haswell-EP Memory: 128G fsmark.time.voluntary_context_switches 1.6e+06 ++----------------------------------------------------------------+ | | 1.4e+06 *+.*.*.. .*..*.*..*.*..*.*..*.*..*..* | | * + .*. .*. .* 1.2e+06 ++ *..*.*. *..*.*..*.*. *. | 1e+06 ++ | | | 800000 ++ | | | 600000 ++ | 400000 ++ O O | | O O O O O O O | 200000 O+ O O O O O O O O O O O O | | O | 0 ++----------------------------------------------------------------+ time.voluntary_context_switches 1.6e+06 ++----------------------------------------------------------------+ | | 1.4e+06 *+.*.*.. .*..*.*..*.*..*.*..*.*..*..* | | * + .*. .*. .* 1.2e+06 ++ *..*.*. *..*.*..*.*. *. | 1e+06 ++ | | | 800000 ++ | | | 600000 ++ | 400000 ++ O O | | O O O O O O O | 200000 O+ O O O O O O O O O O O O | | O | 0 ++----------------------------------------------------------------+ [*] bisect-good sample [O] bisect-bad sample 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, Xiaolong Ye

4 years, 2 months

1
0
0 / 0

[cpu/hotplug] 931ef16330: INFO: task swapper/0:1 blocked for more than 120 seconds.

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master commit 931ef163309ee955611f287dc65248b39a65fc9d Author: Thomas Gleixner <tglx(a)linutronix.de> AuthorDate: Fri Feb 26 18:43:36 2016 +0000 Commit: Thomas Gleixner <tglx(a)linutronix.de> CommitDate: Tue Mar 1 20:36:56 2016 +0100 cpu/hotplug: Unpark smpboot threads from the state machine Handle the smpboot threads in the state machine. Signed-off-by: Thomas Gleixner <tglx(a)linutronix.de> Cc: linux-arch(a)vger.kernel.org Cc: Rik van Riel <riel(a)redhat.com> Cc: Rafael Wysocki <rafael.j.wysocki(a)intel.com> Cc: "Srivatsa S. Bhat" <srivatsa(a)mit.edu> Cc: Peter Zijlstra <peterz(a)infradead.org> Cc: Arjan van de Ven <arjan(a)linux.intel.com> Cc: Sebastian Siewior <bigeasy(a)linutronix.de> Cc: Rusty Russell <rusty(a)rustcorp.com.au> Cc: Steven Rostedt <rostedt(a)goodmis.org> Cc: Oleg Nesterov <oleg(a)redhat.com> Cc: Tejun Heo <tj(a)kernel.org> Cc: Andrew Morton <akpm(a)linux-foundation.org> Cc: Paul McKenney <paulmck(a)linux.vnet.ibm.com> Cc: Linus Torvalds <torvalds(a)linux-foundation.org> Cc: Paul Turner <pjt(a)google.com> Link: http://lkml.kernel.org/r/20160226182341.295777684@linutronix.de Signed-off-by: Thomas Gleixner <tglx(a)linutronix.de> +--------------------------------------------------+------------+------------+------------+ | | 949338e351 | 931ef16330 | 65908fe64c | +--------------------------------------------------+------------+------------+------------+ | boot_successes | 910 | 156 | 7 | | boot_failures | 0 | 154 | 12 | | INFO:task_blocked_for_more_than#seconds | 0 | 154 | 10 | | RIP:native_safe_halt | 0 | 154 | 10 | | RIP:flat_send_IPI_mask | 0 | 154 | | | Kernel_panic-not_syncing:hung_task:blocked_tasks | 0 | 154 | 10 | | backtrace:debug_hotplug_cpu | 0 | 154 | 10 | | backtrace:kernel_init_freeable | 0 | 154 | 10 | | backtrace:cpu_startup_entry | 0 | 154 | 10 | | backtrace:watchdog | 0 | 154 | 10 | | RIP:__default_send_IPI_dest_field | 0 | 0 | 10 | | IP-Config:Auto-configuration_of_network_failed | 0 | 0 | 2 | +--------------------------------------------------+------------+------------+------------+ [ 3.668043] Key type encrypted registered [ 3.670448] rtc-test rtc-test.0: setting system clock to 2016-03-10 09:59:54 UTC (1457603994) [ 3.671603] Unregister pv shared memory for cpu 0 [ 240.448449] INFO: task swapper/0:1 blocked for more than 120 seconds. [ 240.449093] Not tainted 4.5.0-rc6-00012-g931ef16 #1 [ 240.449576] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 240.450280] swapper/0 D ffff88001012bb48 12392 1 0 0x00000000 [ 240.450949] ffff88001012bb48 ffff880010fd2e18 ffff880010198000 ffff880010124000 [ 240.451666] ffff88001012c000 ffff88001012bd10 ffff88001012bd18 0000000000000002 [ 240.452376] ffff880010124000 ffff88001012bb60 ffffffff8141779b 7fffffffffffffff [ 240.453091] Call Trace: [ 240.453324] [<ffffffff8141779b>] schedule+0x2b/0x43 [ 240.453780] [<ffffffff8141b298>] schedule_timeout+0x35/0x184 [ 240.454297] [<ffffffff810a4ecd>] ? mark_held_locks+0x5e/0x74 [ 240.454817] [<ffffffff8141bc6a>] ? _raw_spin_unlock_irq+0x27/0x4b [ 240.455371] [<ffffffff810a5054>] ? trace_hardirqs_on_caller+0x171/0x18d [ 240.455979] [<ffffffff814180a5>] wait_for_common+0x12f/0x175 [ 240.456497] [<ffffffff814180a5>] ? wait_for_common+0x12f/0x175 [ 240.457036] [<ffffffff81088bf6>] ? wake_up_q+0x51/0x51 [ 240.457508] [<ffffffff81418103>] wait_for_completion+0x18/0x1a [ 240.458044] [<ffffffff810b55cc>] __wait_rcu_gp+0xb9/0xce [ 240.458541] [<ffffffff810663fd>] takedown_cpu+0x40/0x10b [ 240.459033] [<ffffffff810badd3>] ? __call_rcu+0x267/0x267 [ 240.459626] [<ffffffff810bae12>] ? call_rcu_bh+0x14/0x14 [ 240.460117] [<ffffffff810b5504>] ? rcu_panic+0x12/0x12 [ 240.460587] [<ffffffff81417fb0>] ? wait_for_common+0x3a/0x175 [ 240.461116] [<ffffffff810b5504>] ? rcu_panic+0x12/0x12 [ 240.461584] [<ffffffff8109ddff>] ? complete+0x18/0x43 [ 240.462057] [<ffffffff81066270>] cpuhp_invoke_callback+0xb/0x10 [ 240.462651] [<ffffffff81410b8d>] _cpu_down+0xad/0x11e [ 240.463205] [<ffffffff81066b2b>] cpu_down+0x25/0x32 [ 240.463661] [<ffffffff8100a12b>] _debug_hotplug_cpu+0x44/0xa9 [ 240.464186] [<ffffffff81cab065>] ? topology_init+0xba/0xba [ 240.464692] [<ffffffff81cab072>] debug_hotplug_cpu+0xd/0x11 [ 240.465203] [<ffffffff8100049b>] do_one_initcall+0x18a/0x19f [ 240.465724] [<ffffffff81080600>] ? parse_args+0x112/0x2bc [ 240.466219] [<ffffffff81ca605e>] kernel_init_freeable+0x1e4/0x267 [ 240.466777] [<ffffffff814104db>] ? rest_init+0x132/0x132 [ 240.467263] [<ffffffff814104e4>] kernel_init+0x9/0xd9 [ 240.467732] [<ffffffff8141c95f>] ret_from_fork+0x3f/0x70 [ 240.468217] [<ffffffff814104db>] ? rest_init+0x132/0x132 [ 240.468716] 4 locks held by swapper/0/1: [ 240.469071] #0: (device_hotplug_lock){+.+.+.}, at: [<ffffffff812cbee6>] lock_device_hotplug+0x12/0x14 [ 240.469983] #1: (cpu_add_remove_lock){+.+.+.}, at: [<ffffffff81065eae>] cpu_maps_update_begin+0x12/0x14 [ 240.470900] #2: (cpu_hotplug.lock){++++++}, at: [<ffffffff81066a1d>] cpu_hotplug_begin+0x0/0xb4 [ 240.471755] #3: (cpu_hotplug.lock#2){+.+.+.}, at: [<ffffffff81066a8d>] cpu_hotplug_begin+0x70/0xb4 [ 240.472647] Sending NMI to all CPUs: [ 240.474028] NMI backtrace for cpu 0 [ 240.474350] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.5.0-rc6-00012-g931ef16 #1 [ 240.475023] task: ffffffff81a0f500 ti: ffffffff81a00000 task.ti: ffffffff81a00000 [ 240.475690] RIP: 0010:[<ffffffff810322fe>] [<ffffffff810322fe>] native_safe_halt+0x6/0x8 [ 240.476413] RSP: 0000:ffffffff81a03ec8 EFLAGS: 00000202 [ 240.476893] RAX: ffffffff81a0f500 RBX: ffffffff81a00000 RCX: 0000000000000002 [ 240.477526] RDX: ffffffff81a0f500 RSI: 0000000000000001 RDI: ffffffff81a0f500 [ 240.478165] RBP: ffffffff81a03ec8 R08: 00000000be162c32 R09: 0000000000000006 [ 240.478810] R10: ffffffff81a03e58 R11: 0000000000006000 R12: 0000000000000000 [ 240.479449] R13: ffffffff81a00000 R14: ffffffff81a00000 R15: 0000000000000000 [ 240.480088] FS: 0000000000000000(0000) GS:ffff880010e00000(0000) knlGS:0000000000000000 [ 240.480809] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 240.481326] CR2: 0000000000000000 CR3: 0000000001a0a000 CR4: 00000000000006b0 [ 240.481964] Stack: [ 240.482153] ffffffff81a03ed8 ffffffff8100c939 ffffffff81a03ee8 ffffffff8100cecb [ 240.482817] ffffffff81a03ef8 ffffffff8109dfa4 ffffffff81a03f40 ffffffff8109e14e [ 240.483476] ffffffff81a00000 689ad0fedaf7f2b1 0000000000000002 0000000000000000 [ 240.484137] Call Trace: [ 240.484363] [<ffffffff8100c939>] default_idle+0x13/0x1c [ 240.484846] [<ffffffff8100cecb>] arch_cpu_idle+0xa/0xc [ 240.485314] [<ffffffff8109dfa4>] default_idle_call+0x33/0x35 [ 240.485834] [<ffffffff8109e14e>] cpu_startup_entry+0x1a8/0x268 [ 240.486363] [<ffffffff814104d5>] rest_init+0x12c/0x132 [ 240.486836] [<ffffffff81ca5e6d>] start_kernel+0x420/0x42d [ 240.487328] [<ffffffff81ca5120>] ? early_idt_handler_array+0x120/0x120 [ 240.487923] [<ffffffff81ca52c5>] x86_64_start_reservations+0x2a/0x2c [ 240.488506] [<ffffffff81ca53ad>] x86_64_start_kernel+0xe6/0xf3 [ 240.489045] 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 c9 49 89 d0 8b 07 48 89 e5 [ 240.490729] NMI backtrace for cpu 1 [ 240.491047] CPU: 1 PID: 22 Comm: khungtaskd Not tainted 4.5.0-rc6-00012-g931ef16 #1 [ 240.491733] task: ffff88001024a000 ti: ffff88001024c000 task.ti: ffff88001024c000 [ 240.492397] RIP: 0010:[<ffffffff8102de65>] [<ffffffff8102de65>] flat_send_IPI_mask+0x72/0xa4 [ 240.493163] RSP: 0000:ffff88001024fd70 EFLAGS: 00000046 [ 240.493643] RAX: 0000000003000000 RBX: 0000000000000c00 RCX: 0000000000000002 [ 240.494288] RDX: 0000000000000c00 RSI: 0000000000000002 RDI: 0000000000000300 [ 240.494927] RBP: ffff88001024fd90 R08: 0000000000000001 R09: 0000000000000000 [ 240.495566] R10: ffffffff82477b40 R11: ffffffff82a5776d R12: 0000000000000282 [ 240.496203] R13: 0000000000000003 R14: 0000000000000002 R15: 0000000000000002 [ 240.496842] FS: 0000000000000000(0000) GS:ffff880011000000(0000) knlGS:0000000000000000 [ 240.497561] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 240.498078] CR2: 00000000ffffffff CR3: 0000000001a0a000 CR4: 00000000000006a0 [ 240.498724] Stack: [ 240.498912] 00000000001cea00 0000000000000001 ffffffff8102b70c 0000000000000040 [ 240.499566] ffff88001024fda0 ffffffff8102b722 ffff88001024fde8 ffffffff811e9f91 [ 240.500230] ffffffff81109200 ffff880010124880 ffff880010124000 ffff880010124000 [ 240.500893] Call Trace: [ 240.501119] [<ffffffff8102b70c>] ? irq_force_complete_move+0x9f/0x9f [ 240.501700] [<ffffffff8102b722>] nmi_raise_cpu_backtrace+0x16/0x18 [ 240.502259] [<ffffffff811e9f91>] nmi_trigger_all_cpu_backtrace+0x150/0x298 [ 240.502896] [<ffffffff81109200>] ? print_lock+0x66/0x69 [ 240.503377] [<ffffffff8102b763>] arch_trigger_all_cpu_backtrace+0x14/0x16 [ 240.503995] [<ffffffff810f10ee>] watchdog+0x372/0x3eb [ 240.504454] [<ffffffff810f0d7c>] ? reset_hung_task_detector+0x10/0x10 [ 240.505041] [<ffffffff81080cfc>] kthread+0x105/0x10d [ 240.505493] [<ffffffff810a2c08>] ? put_lock_stats+0xe/0x20 [ 240.506056] [<ffffffff81080bf7>] ? kthread_create_on_node+0x1de/0x1de [ 240.506642] [<ffffffff8141c95f>] ret_from_fork+0x3f/0x70 [ 240.507126] [<ffffffff81080bf7>] ? kthread_create_on_node+0x1de/0x1de [ 240.507713] Code: c4 10 74 04 f3 90 eb f0 44 89 e8 c1 e0 18 89 04 25 10 d3 5f ff 44 89 f2 09 da 80 cf 04 41 83 fe 02 0f 44 d3 89 14 25 00 d3 5f ff <41> f7 c4 00 02 00 00 75 11 4c 89 e7 57 9d 0f 1f 44 00 00 e8 03 [ 240.509389] Kernel panic - not syncing: hung_task: blocked tasks [ 240.509941] CPU: 1 PID: 22 Comm: khungtaskd Not tainted 4.5.0-rc6-00012-g931ef16 #1 [ 240.510621] 0000000000000000 ffff88001024fd78 ffffffff811e6d0c ffffffff817fccc3 [ 240.511337] ffff88001024fe08 ffff88001024fdf8 ffffffff811088e6 0000000000000008 [ 240.512069] ffff88001024fe08 ffff88001024fda0 ffff88001024fde8 0000000000000040 [ 240.512792] Call Trace: [ 240.513021] [<ffffffff811e6d0c>] dump_stack+0x85/0xbe [ 240.513485] [<ffffffff811088e6>] panic+0xd2/0x219 [ 240.513926] [<ffffffff810f10fa>] watchdog+0x37e/0x3eb [ 240.514389] [<ffffffff810f0d7c>] ? reset_hung_task_detector+0x10/0x10 [ 240.514979] [<ffffffff81080cfc>] kthread+0x105/0x10d [ 240.515433] [<ffffffff810a2c08>] ? put_lock_stats+0xe/0x20 [ 240.515999] [<ffffffff81080bf7>] ? kthread_create_on_node+0x1de/0x1de [ 240.516585] [<ffffffff8141c95f>] ret_from_fork+0x3f/0x70 [ 240.517084] [<ffffffff81080bf7>] ? kthread_create_on_node+0x1de/0x1de [ 240.518097] Kernel Offset: disabled Elapsed time: 250 git bisect start 65908fe64cee059d076412ab35bbfd5b96e079ac f6cede5b49e822ebc41a099fe41ab4989f64e2cb -- git bisect bad ec891af2ed69fa68097f0c690afcbb25ab35d905 # 11:12 20- 8 Merge 'perf/perf/core' into devel-spot-201603100933 git bisect bad a02a12533c9c796ce3c619da68d44cc9a9eb15c6 # 11:23 35- 6 Merge 'rockchip/v4.7-clk/next' into devel-spot-201603100933 git bisect bad 873cef7c4ae802fea40245c9a0903fefae5d6ee6 # 12:01 37- 32 Merge 'linux-review/Vladimir-Zapolskiy/staging-wilc1000-fix-check-of-kthread_run-return-value/20160310-071643' into devel-spot-201603100933 git bisect bad fe4e5e0f2ecb8e49435658f215decd9562b67424 # 12:13 55- 21 Merge 'scottwood/next' into devel-spot-201603100933 git bisect bad fc69c77394965ad9eb5dcec37f530c791e5e4380 # 12:28 72- 13 Merge 'x86-pkeys/pkeys-v028' into devel-spot-201603100933 git bisect good c27685af47501916808216febda4aea7cb2813f6 # 12:38 94+ 0 0day base guard for 'devel-spot-201603100933' git bisect good e074128287d1b7abd1ad6abaf5af5c4033255339 # 12:49 89+ 0 Merge 'gfs2/master' into devel-spot-201603100933 git bisect good 2ec41dfa089bb8fb9987bc94ba3a6881879a63ca # 12:55 95+ 0 Merge branch 'perf/core' git bisect bad 3576d6cca5a8d095293116db5990205e9a543d2b # 13:12 43- 4 manual merge of core/rcu git bisect good 02afeaae9843733a39cd9b11053748b2d1dc5ae7 # 13:39 142+ 0 x86/boot: Fix early command-line parsing when matching at end git bisect good 2c756feb18d9ec258dbb3a3d11c47e28820690d7 # 14:04 142+ 0 time: Add history to cross timestamp interface supporting slower devices git bisect good f9677e0f83080bb4186865868c359e72e1fac1ea # 14:23 141+ 0 x86/tsc: Always Running Timer (ART) correlated clocksource git bisect bad 2e1a3483ce74d197876e58281925dd4e378a7f28 # 14:51 101- 1 cpu/hotplug: Split out the state walk into functions git bisect good 4baa0afc6719cbf36a1e08551484a641926b3fd1 # 15:03 309+ 0 cpu/hotplug: Convert the hotplugged cpu work to a state machine git bisect good 5b7aa87e0482be768486e0c2277aa4122487eb9d # 17:47 305+ 0 cpu/hotplug: Implement setup/removal interface git bisect bad 931ef163309ee955611f287dc65248b39a65fc9d # 18:02 70- 12 cpu/hotplug: Unpark smpboot threads from the state machine git bisect good 949338e35131c551f7bf54f48a2e3a227af6721b # 18:19 309+ 0 cpu/hotplug: Move scheduler cpu_online notifier to hotplug core # first bad commit: [931ef163309ee955611f287dc65248b39a65fc9d] cpu/hotplug: Unpark smpboot threads from the state machine git bisect good 949338e35131c551f7bf54f48a2e3a227af6721b # 18:30 910+ 0 cpu/hotplug: Move scheduler cpu_online notifier to hotplug core # extra tests with DEBUG_INFO git bisect bad 931ef163309ee955611f287dc65248b39a65fc9d # 21:13 70- 335 cpu/hotplug: Unpark smpboot threads from the state machine # extra tests on HEAD of linux-devel/devel-spot-201603100933 git bisect bad 65908fe64cee059d076412ab35bbfd5b96e079ac # 21:14 0- 12 0day head guard for 'devel-spot-201603100933' # extra tests on tree/branch linux-next/master git bisect bad 8540bdafb49b6c8e54b8c2003f91be89712df76a # 21:33 35- 1 Add linux-next specific files for 20160310 # extra tests on tree/branch linus/master git bisect good 8e0f93cda48ed054e1216bab5c60017e1a5fc1e8 # 22:06 910+ 0 Merge tag 'spi-fix-v4.5-rc7' of git://git.kernel.org/pub/scm/linux/kernel/git/broonie/spi # extra tests on tree/branch linux-next/master git bisect bad 8540bdafb49b6c8e54b8c2003f91be89712df76a # 22:06 0- 440 Add linux-next specific files for 20160310 This script may reproduce the error. ---------------------------------------------------------------------------- #!/bin/bash kernel=$1 initrd=quantal-core-x86_64.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

4 years, 2 months

1
0
0 / 0

[lkp] [drm/i915] ed4a6a7ca8: WARNING: CPU: 0 PID: 650 at drivers/gpu/drm/i915/intel_drv.h:1470 gen6_write32+0x131/0x1b0 [i915]()

by kernel test robot

FYI, we noticed the below changes on https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master commit ed4a6a7ca853253f9b86f3005d76345482a71283 ("drm/i915: Add two-stage ILK-style watermark programming (v11)") <5>[ 17.105319] Key type id_resolver registered <5>[ 17.105566] Key type id_legacy registered <4>[ 19.890693] ------------[ cut here ]------------ <4>[ 19.890985] WARNING: CPU: 0 PID: 650 at drivers/gpu/drm/i915/intel_drv.h:1470 gen6_write32+0x131/0x1b0 [i915]() <4>[ 19.891606] Device suspended during HW access <4>[ 19.891843] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sd_mod snd_hda_codec_hdmi x86_pkg_temp_thermal coretemp kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel ahci aesni_intel snd_hda_codec_realtek libahci snd_hda_codec_generic ppdev lrw firewire_ohci gf128mul glue_helper ablk_helper cryptd serio_raw pcspkr libata firewire_core crc_itu_t snd_hda_intel snd_hda_codec snd_hda_core snd_hwdep i915 snd_pcm snd_timer video drm_kms_helper syscopyarea snd sysfillrect sysimgblt shpchp soundcore fb_sys_fops drm parport_pc parport nuvoton_cir rc_core <4>[ 19.895094] CPU: 0 PID: 650 Comm: pm_rpm Not tainted 4.5.0-rc3-00396-ged4a6a7 #1 <4>[ 19.895476] Hardware name: /DH67GD, BIOS BLH6710H.86A.0132.2011.1007.1505 10/07/2011 <4>[ 19.895947] 0000000000000000 ffff8800bceeb938 ffffffff8141e3aa ffff8800bceeb980 <4>[ 19.896364] ffffffffa026c660 ffff8800bceeb970 ffffffff81078ec6 0000000000070001 <4>[ 19.896777] 0000000000045110 ffff8800bdae0000 0000000000000000 0000000016304d06 <4>[ 19.897194] Call Trace: <4>[ 19.897336] [<ffffffff8141e3aa>] dump_stack+0x63/0x89 <4>[ 19.897606] [<ffffffff81078ec6>] warn_slowpath_common+0x86/0xc0 <4>[ 19.897917] [<ffffffff81078f4c>] warn_slowpath_fmt+0x4c/0x50 <4>[ 19.898216] [<ffffffff810a216d>] ? ttwu_do_activate+0x5d/0x70 <4>[ 19.898592] [<ffffffffa01dd661>] gen6_write32+0x131/0x1b0 [i915] <4>[ 19.898918] [<ffffffffa0190e3d>] _ilk_disable_lp_wm+0x9d/0xe0 [i915] <4>[ 19.899263] [<ffffffffa0196584>] ilk_program_watermarks+0x4f4/0x9b0 [i915] <4>[ 19.899633] [<ffffffffa0196b53>] ilk_optimize_watermarks+0x113/0x130 [i915] <4>[ 19.900019] [<ffffffffa0201663>] intel_atomic_commit+0x8b3/0xd60 [i915] <4>[ 19.900384] [<ffffffffa009a43d>] ? drm_atomic_set_crtc_for_connector+0x3d/0xf0 [drm] <4>[ 19.900803] [<ffffffffa009aea7>] drm_atomic_commit+0x37/0x60 [drm] <4>[ 19.901132] [<ffffffffa013f637>] drm_atomic_helper_set_config+0x77/0xb0 [drm_kms_helper] <4>[ 19.901560] [<ffffffffa008a7f2>] drm_mode_set_config_internal+0x62/0x100 [drm] <4>[ 19.901950] [<ffffffffa008edd9>] drm_mode_setcrtc+0x189/0x4f0 [drm] <4>[ 19.902285] [<ffffffffa0080a62>] drm_ioctl+0x142/0x590 [drm] <4>[ 19.902595] [<ffffffffa008ec50>] ? drm_mode_setplane+0x1b0/0x1b0 [drm] <4>[ 19.902943] [<ffffffff81204eef>] do_vfs_ioctl+0x8f/0x670 <4>[ 19.903224] [<ffffffff813acba3>] ? security_file_ioctl+0x43/0x60 <4>[ 19.903543] [<ffffffff81205549>] SyS_ioctl+0x79/0x90 <4>[ 19.903807] [<ffffffff818dad2e>] entry_SYSCALL_64_fastpath+0x12/0x6d <4>[ 19.904156] ---[ end trace 2fe52dffb2d62414 ]--- <4>[ 19.904156] ---[ end trace 2fe52dffb2d62414 ]--- 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 Thanks, Xiaolong Ye

4 years, 2 months

1
0
0 / 0

[tty/serial/8250] d294b233a2: WARNING: CPU: 0 PID: 1 at drivers/tty/tty_io.c:1548 tty_init_dev()

by kernel test robot

Greetings, 0day kernel testing robot got the below dmesg and the first bad commit is https://github.com/0day-ci/linux yegorslists-googlemail-com/tty-serial-8250-use-mctrl_gpio-helpers/20160309-190741 commit d294b233a2bdc7abdb5dfa856fc00dc67e96d57a Author: Yegor Yefremov <yegorslists(a)googlemail.com> AuthorDate: Wed Mar 9 12:03:14 2016 +0100 Commit: 0day robot <fengguang.wu(a)intel.com> CommitDate: Wed Mar 9 19:07:42 2016 +0800 tty/serial/8250: use mctrl_gpio helpers This patch permits the usage fo GPIOs to control the CTS/RTS/DTR/DSR/DCD/RI signals. Signed-off-by: Yegor Yefremov <yegorslists(a)googlemail.com> +-------------------------------------------------------+------------+------------+------------+ | | a95fc9c8e5 | d294b233a2 | d294b233a2 | +-------------------------------------------------------+------------+------------+------------+ | boot_successes | 84 | 0 | 0 | | boot_failures | 3 | 13 | 13 | | BUG:unable_to_handle_kernel | 3 | 13 | 13 | | Oops | 3 | 13 | 13 | | EIP_is_at_perf_prepare_sample | 3 | | | | Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 2 | | | | backtrace:iterate_dir | 1 | | | | backtrace:SyS_getdents64 | 1 | | | | Kernel_panic-not_syncing:Fatal_exception | 1 | 13 | 13 | | WARNING:at_drivers/tty/tty_io.c:#tty_init_dev() | 0 | 13 | 13 | | EIP_is_at_tty_init_dev | 0 | 13 | 13 | | backtrace:tty_init_dev | 0 | 13 | 13 | | backtrace:warn_slowpath_fmt | 0 | 13 | 13 | | backtrace:do_sys_open | 0 | 13 | 13 | | backtrace:SyS_open | 0 | 13 | 13 | | backtrace:kernel_init_freeable | 0 | 13 | 13 | +-------------------------------------------------------+------------+------------+------------+ [ 8.304454] page_owner is disabled [ 8.307923] rtc_cmos 00:00: setting system clock to 2016-03-09 19:56:39 UTC (1457553399) [ 8.311883] ------------[ cut here ]------------ [ 8.312286] WARNING: CPU: 0 PID: 1 at drivers/tty/tty_io.c:1548 tty_init_dev+0x154/0x290() [ 8.313091] tty_init_dev: ttyS driver does not set tty->port. This will crash the kernel later. Fix the driver! [ 8.313937] Modules linked in: [ 8.314223] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.5.0-rc4-00223-gd294b23 #904 [ 8.314842] 00000246 00000246 40277d1c 4137c480 40277d44 41b4345c 0000060c 40277d34 [ 8.315576] 4106ff33 4146dcd4 4ad00c10 00000001 00000000 40277d4c 4106ff86 00000009 [ 8.316312] 40277d44 41b436a8 40277d60 40277d7c 4146dcd4 41b4345c 0000060c 41b436a8 [ 8.317048] Call Trace: [ 8.317259] [<4137c480>] dump_stack+0x8c/0xbc [ 8.317625] [<4106ff33>] warn_slowpath_common+0xa3/0xd0 [ 8.318064] [<4146dcd4>] ? tty_init_dev+0x154/0x290 [ 8.318468] [<4106ff86>] warn_slowpath_fmt+0x26/0x30 [ 8.318878] [<4146dcd4>] tty_init_dev+0x154/0x290 [ 8.319274] [<4146e5c6>] tty_open+0x7b6/0xa90 [ 8.319640] [<41221ed5>] chrdev_open+0x205/0x2d0 [ 8.320028] [<412177e4>] do_dentry_open+0x544/0x5e0 [ 8.320488] [<41221cd0>] ? cdev_put+0x40/0x40 [ 8.320852] [<4121949a>] vfs_open+0x8a/0x90 [ 8.321208] [<4123246d>] do_last+0xb7d/0x1130 [ 8.321571] [<4122a917>] ? inode_permission+0x67/0xc0 [ 8.321992] [<41232b11>] path_openat+0xf1/0x4c0 [ 8.322375] [<41235229>] do_filp_open+0x69/0x120 [ 8.322762] [<41791b42>] ? _raw_spin_unlock+0x52/0x70 [ 8.323187] [<41249d68>] ? __alloc_fd+0x218/0x2b0 [ 8.323580] [<41219c7d>] do_sys_open+0x35d/0x440 [ 8.323963] [<41219d78>] SyS_open+0x18/0x20 [ 8.324320] [<41e09f1f>] kernel_init_freeable+0x106/0x1aa [ 8.324769] [<41787469>] kernel_init+0x9/0x150 [ 8.325146] [<41792451>] ret_from_kernel_thread+0x21/0x40 [ 8.325589] [<41787460>] ? rest_init+0xb0/0xb0 [ 8.326675] ---[ end trace a0fa66e39705bc4d ]--- [ 8.327076] BUG: unable to handle kernel NULL pointer dereference at 00000098 git bisect start d294b233a2bdc7abdb5dfa856fc00dc67e96d57a a95fc9c8e576dc238ad849f65c67e4fd33c01d3b -- # first bad commit: [d294b233a2bdc7abdb5dfa856fc00dc67e96d57a] tty/serial/8250: use mctrl_gpio helpers git bisect good a95fc9c8e576dc238ad849f65c67e4fd33c01d3b # 20:02 66+ 3 serial: 8250: describe CONFIG_SERIAL_8250_RSA # extra tests with DEBUG_INFO git bisect bad d294b233a2bdc7abdb5dfa856fc00dc67e96d57a # 20:06 0- 66 tty/serial/8250: use mctrl_gpio helpers # extra tests on HEAD of linux-review/yegorslists-googlemail-com/tty-serial-8250-use-mctrl_gpio-helpers/20160309-190741 git bisect bad d294b233a2bdc7abdb5dfa856fc00dc67e96d57a # 20:06 0- 13 tty/serial/8250: use mctrl_gpio helpers # extra tests on tree/branch linux-review/yegorslists-googlemail-com/tty-serial-8250-use-mctrl_gpio-helpers/20160309-190741 git bisect bad d294b233a2bdc7abdb5dfa856fc00dc67e96d57a # 20:06 0- 13 tty/serial/8250: use mctrl_gpio helpers # extra tests with first bad commit reverted git bisect good d232311183c0c28045b797481f732154ee93411b # 20:21 66+ 2 Revert "tty/serial/8250: use mctrl_gpio helpers" # extra tests on tree/branch linus/master git bisect good 7f02bf6b5f5de90b7a331759b5364e41c0f39bf9 # 20:25 61+ 1 Merge tag 'sound-4.5' of git://git.kernel.org/pub/scm/linux/kernel/git/tiwai/sound # extra tests on tree/branch linux-next/master git bisect good 7811b4ffc31285e08ee7a6a96a94848c37a43e09 # 20:30 61+ 2 Add linux-next specific files for 20160309 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

4 years, 2 months

1
0
0 / 0

[lkp] [pnfs support servers with multiple layout types] 843920003d: kmsg.NFS:set_pnfs_layoutdriver:cl_exchange_flags

by kernel test robot

FYI, we noticed the below changes on https://github.com/0day-ci/linux Tigran-Mkrtchyan/pnfs-support-servers-with-multiple-layout-types/20160302-220000 commit 843920003d2bf4c4f12bad0382e67290e79818d2 ("pnfs support servers with multiple layout types") As below, the log "NFS: set_pnfs_layoutdriver: cl_exchange_flags 0x0" showed with your commit. [ 10.115109] firewire_ohci 0000:03:00.0: added OHCI v1.10 device as card 0, 4 IR + 8 IT contexts, quirks 0x11 [ 16.227438] netconsole: network logging started [ 16.239391] Key type dns_resolver registered [ 16.242616] NFS: Registering the id_resolver key type [ 16.242851] Key type id_resolver registered [ 16.243114] Key type id_legacy registered [ 16.285154] NFS: set_pnfs_layoutdriver: cl_exchange_flags 0x0 [ 16.287031] NFS: set_pnfs_layoutdriver: cl_exchange_flags 0x0 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 Thanks, Xiaolong, Ye

4 years, 2 months

1
0
0 / 0

2020

2019

2018

2017

2016

2015

2014

2013

LKP March 2016