[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months
[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
6 years, 2 months