[drm/i915] e44058ef12: WARNING:at_drivers/gpu/drm/i915/intel_drv.h:#gen6_read32[i915]
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: e44058ef1205cc0fc3306aed6285fd84040cf412 ("[PATCH 2/2] drm/i915: Do not get aux power for disconnected DP ports")
url: https://github.com/0day-ci/linux/commits/Jos-Roberto-de-Souza/drm-Do-not-...
base: git://anongit.freedesktop.org/drm-intel for-linux-next
in testcase: aim9
with following parameters:
testtime: 300s
test: mul_int
ucode: 0x20
cpufreq_governor: performance
test-description: Suite IX is the "AIM Independent Resource Benchmark:" the famous synthetic benchmark.
test-url: https://sourceforge.net/projects/aimbench/files/aim-suite9/
on test machine: 4 threads Intel(R) Core(TM) i3-3220 CPU @ 3.30GHz with 4G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-------------------------+------------+------------+
| | 44f0c4dca4 | e44058ef12 |
+-------------------------+------------+------------+
| boot_successes | 2 | 11 |
| boot_failures | 0 | 2 |
| End_of_test:RCU_HOTPLUG | 0 | 2 |
+-------------------------+------------+------------+
kern :warn : [ 70.099720] WARNING: CPU: 1 PID: 19 at drivers/gpu/drm/i915/intel_drv.h:1982 gen6_read32+0x1a4/0x1d0 [i915]
kern :warn : [ 70.099721] Modules linked in: snd_hda_codec_realtek snd_hda_codec_generic intel_rapl x86_pkg_temp_thermal intel_powerclamp wmi_bmof coretemp kvm_intel kvm irqbypass snd_hda_intel crct10dif_pclmul snd_hda_codec crc32_pclmul snd_hda_core snd_hwdep crc32c_intel snd_pcm ghash_clmulni_intel ahci cryptd libahci pcspkr snd_timer i915 snd soundcore libata drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm wmi video pcc_cpufreq ip_tables
kern :warn : [ 70.099743] CPU: 1 PID: 19 Comm: kworker/1:0 Not tainted 4.18.0-rc7-01572-ge44058e #1
kern :warn : [ 70.099744] Hardware name: Hewlett-Packard HP Pro 3340 MT/17A1, BIOS 8.07 01/24/2013
kern :warn : [ 70.099760] Workqueue: events output_poll_execute [drm_kms_helper]
kern :warn : [ 70.099785] RIP: 0010:gen6_read32+0x1a4/0x1d0 [i915]
kern :warn : [ 70.099785] Code: ff 48 8b 04 24 e9 f3 fe ff ff 80 3d 1f 13 11 00 00 0f 85 a3 fe ff ff 48 c7 c7 f0 0a 2b a0 c6 05 0b 13 11 00 01 e8 bc 60 e8 e0 <0f> 0b e9 89 fe ff ff b9 01 00 00 00 ba 01 00 00 00 89 ee 48 89 df
kern :warn : [ 70.099809] RSP: 0000:ffffc900006efc00 EFLAGS: 00010286
kern :warn : [ 70.099810] RAX: 0000000000000000 RBX: ffff88011a200000 RCX: 0000000000000006
kern :warn : [ 70.099811] RDX: 0000000000000007 RSI: 0000000000000002 RDI: ffff88011fa96950
kern :warn : [ 70.099812] RBP: 00000000000c4000 R08: 000000000000002a R09: ffffffff82d66740
kern :warn : [ 70.099813] R10: ffffc900006efc38 R11: 0000000000000000 R12: ffff88011a819000
kern :warn : [ 70.099814] R13: 0000000000000001 R14: 0000000000000002 R15: ffff88011b522000
kern :warn : [ 70.099815] FS: 0000000000000000(0000) GS:ffff88011fa80000(0000) knlGS:0000000000000000
kern :warn : [ 70.099816] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
kern :warn : [ 70.099817] CR2: 000055c4b40c7000 CR3: 000000011e40a003 CR4: 00000000001606e0
kern :warn : [ 70.099818] Call Trace:
kern :warn : [ 70.099847] cpt_digital_port_connected+0x4b/0x60 [i915]
kern :warn : [ 70.099870] intel_dp_detect+0xee/0x5b0 [i915]
kern :warn : [ 70.099877] drm_helper_probe_single_connector_modes+0xd3/0x6d0 [drm_kms_helper]
kern :warn : [ 70.099882] ? set_next_entity+0x96/0x1c0
kern :warn : [ 70.099888] drm_setup_crtcs+0x136/0xa80 [drm_kms_helper]
kern :warn : [ 70.099892] ? finish_wait+0x47/0x80
kern :warn : [ 70.099897] __drm_fb_helper_initial_config_and_unlock+0x3d/0x450 [drm_kms_helper]
kern :warn : [ 70.099900] ? _cond_resched+0x19/0x30
kern :warn : [ 70.099906] drm_kms_helper_hotplug_event+0x26/0x30 [drm_kms_helper]
kern :warn : [ 70.099911] output_poll_execute+0xa2/0x1a0 [drm_kms_helper]
kern :warn : [ 70.099918] process_one_work+0x19c/0x3b0
kern :warn : [ 70.331303] worker_thread+0x3c/0x3b0
kern :warn : [ 70.331307] ? process_one_work+0x3b0/0x3b0
kern :warn : [ 70.339253] kthread+0x11e/0x140
kern :warn : [ 70.342531] ? kthread_associate_blkcg+0xb0/0xb0
kern :warn : [ 70.347226] ret_from_fork+0x35/0x40
kern :warn : [ 70.350939] ---[ end trace 37126a8b5184f58a ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Rong Chen
2 years, 4 months
[block] e7fe535797: WARNING:at_lib/kobject.c:#kobject_put
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: e7fe5357977243d45c3924720b9ac9e218547de8 ("block: remove dead elevator code")
https://git.kernel.org/cgit/linux/kernel/git/axboe/linux-block.git mq-conversions
in testcase: mdadm-selftests
with following parameters:
disk: 1HDD
test_prefix: 18
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------+------------+------------+
| | 3b954b64a4 | e7fe535797 |
+---------------------------------------+------------+------------+
| boot_successes | 8 | 0 |
| boot_failures | 0 | 8 |
| WARNING:at_lib/kobject.c:#kobject_put | 0 | 8 |
| RIP:kobject_put | 0 | 8 |
+---------------------------------------+------------+------------+
[ 185.945851] WARNING: CPU: 1 PID: 226 at lib/kobject.c:707 kobject_put+0x37/0x40
[ 185.967988] Modules linked in: multipath loop raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq raid10 raid1 raid0 sr_mod cdrom sg ppdev ata_generic pata_acpi snd_pcm crc32c_intel snd_timer snd soundcore pcspkr serio_raw ata_piix libata i2c_piix4 parport_pc floppy parport ip_tables
[ 186.035784] CPU: 1 PID: 226 Comm: kworker/1:3 Not tainted 4.19.0-rc6-00196-ge7fe535 #1
[ 186.056359] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 186.076781] Workqueue: events __blk_release_queue
[ 186.088576] RIP: 0010:kobject_put+0x37/0x40
[ 186.099333] Code: 74 16 48 8d 7b 38 f0 ff 4b 38 0f 88 f4 39 01 00 75 1c 5b e9 3b fe ff ff 48 8b 37 48 89 fa 48 c7 c7 78 c1 39 82 e8 89 a1 6a ff <0f> 0b eb d4 5b c3 f3 c3 90 48 85 ff 74 13 53 48 8d 5f 18 48 89 df
[ 186.141300] RSP: 0000:ffffc90000aabe38 EFLAGS: 00010282
[ 186.154328] RAX: 0000000000000000 RBX: ffff88013fc00098 RCX: 0000000000000000
[ 186.170720] RDX: ffff88013fd1ef40 RSI: ffff88013fd16898 RDI: ffff88013fd16898
[ 186.187860] RBP: ffff8800a7b5acd8 R08: 0000000000000000 R09: 0000000000000294
[ 186.204226] R10: ffffea0001e37ec0 R11: ffffffff82f81fcd R12: ffffffff828abca0
[ 186.220532] R13: 0000000000000000 R14: ffff8800a7b5b588 R15: ffff8800a7b5b590
[ 186.236864] FS: 0000000000000000(0000) GS:ffff88013fd00000(0000) knlGS:0000000000000000
[ 186.256884] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 186.273342] CR2: 00007fff5295c200 CR3: 000000000260a006 CR4: 00000000000206e0
[ 186.292454] Call Trace:
[ 186.300707] blk_mq_sysfs_deinit+0x38/0x60
[ 186.313663] blk_mq_release+0x5e/0x70
[ 186.326271] __blk_release_queue+0x79/0x120
[ 186.338980] process_one_work+0x19c/0x3b0
[ 186.351711] worker_thread+0x3c/0x3b0
[ 186.362376] ? process_one_work+0x3b0/0x3b0
[ 186.374365] kthread+0x11e/0x140
[ 186.385058] ? kthread_associate_blkcg+0xb0/0xb0
[ 186.398860] ret_from_fork+0x35/0x40
[ 186.409258] ---[ end trace db5c64132e93355f ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
2 years, 4 months
8cb682da6b ("rcu: Affinity forward-progress test to avoid .."): BUG: unable to handle kernel NULL pointer dereference at 00000000
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/paulmck/linux-rcu.git dev.2018.10.09a
commit 8cb682da6b49f65c3acf4255d045430cec116c52
Author: Paul E. McKenney <paulmck(a)linux.ibm.com>
AuthorDate: Fri Sep 21 18:08:09 2018 -0700
Commit: Paul E. McKenney <paulmck(a)linux.ibm.com>
CommitDate: Wed Oct 3 17:36:12 2018 -0700
rcu: Affinity forward-progress test to avoid housekeeping CPUs
This commit affinities the forward-progress tests to avoid hogging a
housekeeping CPU on the theory that the offloaded callbacks will be
running on those housekeeping CPUs.
Signed-off-by: Paul E. McKenney <paulmck(a)linux.ibm.com>
61fe6c84c4 MAINTAINERS: Update from @linux.vnet.ibm.com to @linux.ibm.com
8cb682da6b rcu: Affinity forward-progress test to avoid housekeeping CPUs
+------------------------------------------+------------+------------+
| | 61fe6c84c4 | 8cb682da6b |
+------------------------------------------+------------+------------+
| boot_successes | 37 | 0 |
| boot_failures | 0 | 13 |
| BUG:unable_to_handle_kernel | 0 | 13 |
| Oops:#[##] | 0 | 13 |
| EIP:__bitmap_weight | 0 | 13 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 13 |
+------------------------------------------+------------+------------+
[ 1.414107] rcu-torture: torture_stutter task started
[ 1.415593] Kprobe smoke test: started
[ 1.487589] Kprobe smoke test: passed successfully
[ 1.488532] Key type blacklist registered
[ 1.492230] rcu-torture: rcu_torture_fwd_progress task started
[ 1.493190] BUG: unable to handle kernel NULL pointer dereference at 00000000
[ 1.494373] *pde = 00000000
[ 1.494857] Oops: 0000 [#1] SMP DEBUG_PAGEALLOC PTI
[ 1.495277] CPU: 0 PID: 47 Comm: rcu_torture_fwd Tainted: G T 4.19.0-rc1-00167-g8cb682d #2
[ 1.495277] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 1.495277] EIP: __bitmap_weight+0x44/0x61
[ 1.495277] Code: 90 01 c3 83 c1 01 39 f1 75 ef f6 c2 1f 0f 95 c0 0f b6 c8 83 04 8d 7c 57 13 48 01 84 c0 74 15 f7 da 89 d1 b8 ff ff ff ff d3 e8 <23> 04 b7 f3 0f b8 c0 90 01 c3 89 d8 5b 5e 5f 5d c3 85 d2 0f 95 c0
[ 1.495277] EAX: 00000001 EBX: 00000000 ECX: ffffffff EDX: ffffffff
[ 1.495277] ESI: 00000000 EDI: 00000000 EBP: 4df25ef0 ESP: 4df25ee4
[ 1.495277] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00210a03
[ 1.495277] CR0: 80050033 CR2: 00000000 CR3: 0853e000 CR4: 000406d0
[ 1.495277] Call Trace:
[ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf
[ 1.495277] ? rcu_bind_current_to_nocb+0x1d/0xaa
[ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf
[ 1.495277] ? rcu_torture_fwd_prog+0x3a/0x7d5
[ 1.495277] ? __schedule+0x3dc/0x119e
[ 1.495277] ? do_raw_spin_unlock+0xe0/0x14f
[ 1.495277] ? __kthread_parkme+0x7e/0xe8
[ 1.495277] ? __kthread_parkme+0xcf/0xe8
[ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf
[ 1.495277] ? kthread+0x190/0x192
[ 1.495277] ? rcu_busted_torture_deferred_free+0xf/0xf
[ 1.495277] ? __kthread_cancel_work+0x8a/0x8a
[ 1.495277] ? ret_from_fork+0x2e/0x38
[ 1.495277] Modules linked in:
[ 1.495277] CR2: 0000000000000000
[ 1.495277] ---[ end trace d71c97358a9de1b6 ]---
[ 1.495277] EIP: __bitmap_weight+0x44/0x61
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 6d1a2e5e66859bda816e5f5cf4a189768a512f15 5b394b2ddf0347bef56e50c69a58773c94343ff3 --
git bisect bad 6a0dd7b5ab9dae0fcbd5029a524893464813924d # 10:01 B 0 9 24 0 Merge 'linux-review/Oleksij-Rempel/mmc-add-new-Alcor-Micro-Cardreader-driver/20181014-230322' into devel-catchup-201810150742
git bisect bad 84176ccf06fdda3b89a5ca82349c36ef8ad392ff # 10:19 B 0 9 24 0 Merge 'arm-platforms/irq/generic_handle_domain_irq' into devel-catchup-201810150742
git bisect bad b18b42355739752c2530509d535b89aff1fd1c0e # 10:30 B 0 8 23 0 Merge 'linux-review/David-Howells/rxrpc-use-correct-kvec-num-when-sending-BUSY-response-packet/20181015-050347' into devel-catchup-201810150742
git bisect bad d2e181dfcb0e6484a981fc80dd0330d4d27d5e56 # 10:47 B 0 4 19 0 Merge 'linux-review/Heiner-Kallweit/r8169-remove-unneeded-call-to-netif_stop_queue-in-rtl8169_net_suspend/20181015-062947' into devel-catchup-201810150742
git bisect bad ca1a236af592d646dc1514e385f0e4808b67d75a # 11:15 B 0 11 26 0 Merge 'rcu/dev.2018.10.09a' into devel-catchup-201810150742
git bisect good a0ca9aa6219089fdf00601cbda0fe64a0efe165c # 11:43 G 11 0 0 0 0day base guard for 'devel-catchup-201810150742'
git bisect good 5998a75adbf4f85e63b06fa7723633cc84d7129b # 12:14 G 11 0 0 0 rcu: Switch last accelerate/advance to rcu_data structure
git bisect good b59a9b5bf95e3541e6fe7cf0c2bef18850a40f94 # 12:53 G 11 0 0 0 rcutorture: Import a copy of nolibc
git bisect bad 9dc4ef685b2750095261b6b3106313be39caad95 # 13:10 B 0 8 23 0 rcutorture: Print time since GP end upon forward-progress failure
git bisect bad 2022fbc3c7b192bbbf6419d5a43c8f635c727379 # 13:38 B 0 6 21 0 doc: rcu: Better clarify the rcu_segcblist ->len field
git bisect bad 676b3a727b3ebc267a0707f4c0b7bb9ca0f8f2ee # 14:05 B 0 8 23 0 rcu: Remove unused rcu_state externs
git bisect good 61fe6c84c4c0b8755ec8b129977e8cdeb8960087 # 15:01 G 10 0 0 0 MAINTAINERS: Update from @linux.vnet.ibm.com to @linux.ibm.com
git bisect bad 07a525319f3988ae2c28b131316522df183892de # 15:46 B 0 1 16 0 doc: Clarify RCU data-structure comment about rcu_tree fanout
git bisect bad 8cb682da6b49f65c3acf4255d045430cec116c52 # 16:23 B 0 11 26 0 rcu: Affinity forward-progress test to avoid housekeeping CPUs
# first bad commit: [8cb682da6b49f65c3acf4255d045430cec116c52] rcu: Affinity forward-progress test to avoid housekeeping CPUs
git bisect good 61fe6c84c4c0b8755ec8b129977e8cdeb8960087 # 16:32 G 31 0 0 0 MAINTAINERS: Update from @linux.vnet.ibm.com to @linux.ibm.com
# extra tests with debug options
git bisect bad 8cb682da6b49f65c3acf4255d045430cec116c52 # 16:58 B 0 11 26 0 rcu: Affinity forward-progress test to avoid housekeeping CPUs
# extra tests on HEAD of linux-devel/devel-catchup-201810150742
git bisect bad 6d1a2e5e66859bda816e5f5cf4a189768a512f15 # 16:58 B 0 19 37 0 0day head guard for 'devel-catchup-201810150742'
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 4 months
[sd] 7618616fcd: WARNING:at_drivers/scsi/scsi_lib.c:#scsi_device_quiesce
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 7618616fcdf8a5de9f3dc45fc6281ebd0663bd8a ("sd: Rely on the driver core for asynchronous probing")
https://github.com/bvanassche/linux for-next
in testcase: suspend_stress
with following parameters:
mode: mem
iterations: 10
on test machine: 4 threads Skylake with 4G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------+-----------+------------+
| | v4.19-rc6 | 7618616fcd |
+-----------------------------------------------------------------+-----------+------------+
| boot_successes | 383 | 2 |
| boot_failures | 9 | |
| WARNING:stack_recursion | 2 | |
| WARNING:at#for_ip_swapgs_restore_regs_and_return_to_usermode/0x | 2 | |
| WARNING:at_ip_fsnotify/0x | 1 | |
| BUG:kernel_in_stage | 1 | |
| End_of_test:RCU_HOTPLUG | 6 | |
+-----------------------------------------------------------------+-----------+------------+
kern :warn : [ 39.706677] WARNING: CPU: 0 PID: 507 at drivers/scsi/scsi_lib.c:3047 scsi_device_quiesce+0x2c/0xa0
kern :info : [ 39.707819] input input13: calling input_dev_suspend+0x0/0x60 @ 471, parent: card0
kern :warn : [ 39.709155] Modules linked in: sd_mod sg snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_rapl x86_pkg_temp_thermal
kern :info : [ 39.710344] input input13: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.710344] intel_powerclamp coretemp
kern :info : [ 39.711792] input input12: calling input_dev_suspend+0x0/0x60 @ 471, parent: card0
kern :warn : [ 39.712980] kvm_intel snd_hda_intel kvm irqbypass snd_hda_codec
kern :info : [ 39.713992] input input12: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.715209] crct10dif_pclmul snd_hda_core crc32_pclmul crc32c_intel snd_hwdep
kern :info : [ 39.716346] input input11: calling input_dev_suspend+0x0/0x60 @ 471, parent: card0
kern :warn : [ 39.717537] ghash_clmulni_intel snd_pcm pcbc aesni_intel crypto_simd
kern :info : [ 39.718738] input input11: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.719956] cryptd glue_helper pcspkr snd_timer sdhci_pci snd
kern :info : [ 39.721127] input input10: calling input_dev_suspend+0x0/0x60 @ 471, parent: card0
kern :warn : [ 39.722332] cqhci soundcore i915 sdhci ahci
kern :info : [ 39.723478] input input10: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.724690] libahci mmc_core drm_kms_helper syscopyarea sysfillrect sysimgblt
kern :info : [ 39.725752] input input9: calling input_dev_suspend+0x0/0x60 @ 471, parent: card0
kern :warn : [ 39.726963] libata fb_sys_fops ir_rc6_decoder intel_pch_thermal drm
kern :info : [ 39.728181] input input9: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.729406] rc_rc6_mce ite_cir rc_core video acpi_pad pcc_cpufreq
kern :info : [ 39.730566] input input8: calling input_dev_suspend+0x0/0x60 @ 471, parent: card0
kern :warn : [ 39.731772] ip_tables
kern :warn : [ 39.731774] CPU: 0 PID: 507 Comm: kworker/u8:8 Not tainted 4.19.0-rc6-00001-g7618616f #1
kern :warn : [ 39.731776] Hardware name: /NUC6i5SYB, BIOS SYSKLi35.86A.0060.2017.0414.1652 04/14/2017
kern :info : [ 39.732952] input input8: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.734189] Workqueue: events_unbound async_run_entry_fn
kern :info : [ 39.735167] input input7: calling input_dev_suspend+0x0/0x60 @ 471, parent: LNXVIDEO:00
kern :info : [ 39.735175] snd_hda_codec_hdmi hdaudioC0D2: calling pm_runtime_force_suspend+0x0/0xe0 @ 511, parent: 0000:00:1f.3
kern :info : [ 39.735178] snd_hda_codec_realtek hdaudioC0D0: calling pm_runtime_force_suspend+0x0/0xe0 @ 7, parent: 0000:00:1f.3
kern :info : [ 39.735180] snd_hda_codec_hdmi hdaudioC0D2: pm_runtime_force_suspend+0x0/0xe0 returned 0 after 0 usecs
kern :warn : [ 39.736518] RIP: 0010:scsi_device_quiesce+0x2c/0xa0
kern :warn : [ 39.736519] Code: 44 00 00 41 55 41 54 55 53 48 89 fb 48 8b 87 c8 07 00 00 48 8b 6f 08 48 85 c0 74 10 65 48 8b 14 25 40 5c 01 00 48 39 d0 74 02 <0f> 0b 48 89 ef 4c 8d ab a0 07 00 00 e8 a3 5a e0 ff 48 89 ef e8 cb
kern :info : [ 39.737809] input input7: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.739040] RSP: 0018:ffffc900010b7d88 EFLAGS: 00010206
kern :warn : [ 39.739041] RAX: ffff8801380d4d80 RBX: ffff8801353f7800 RCX: 0000000000000018
kern :warn : [ 39.739043] RDX: ffff880137ff26c0 RSI: ffffffff81659960 RDI: ffff8801353f7800
kern :info : [ 39.740192] coretemp coretemp.0: calling platform_pm_suspend+0x0/0x50 @ 471, parent: platform
kern :warn : [ 39.741454] RBP: ffff880130558000 R08: 0000000000000000 R09: 0000000000aaaaaa
kern :warn : [ 39.741455] R10: 0000000000000010 R11: 00000000ffffffff R12: ffff8801353f7800
kern :warn : [ 39.741456] R13: ffffffffa03cd2a0 R14: 0000000000000002 R15: ffffffff82342cab
kern :warn : [ 39.741457] FS: 0000000000000000(0000) GS:ffff88017e800000(0000) knlGS:0000000000000000
kern :info : [ 39.742879] coretemp coretemp.0: platform_pm_suspend+0x0/0x50 returned 0 after 0 usecs
kern :warn : [ 39.744305] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
kern :warn : [ 39.744306] CR2: 0000000001a4a409 CR3: 000000000260a006 CR4: 00000000003606f0
kern :warn : [ 39.744307] Call Trace:
kern :info : [ 39.745698] leds mmc0::: calling led_suspend+0x0/0x30 @ 471, parent: 0000:00:1e.6
kern :warn : [ 39.746885] ? scsi_print_result+0x250/0x250
kern :warn : [ 39.746888] scsi_bus_suspend_common+0x49/0xb0
kern :info : [ 39.746985] snd_hda_codec_realtek hdaudioC0D0: pm_runtime_force_suspend+0x0/0xe0 returned 0 after 11526 usecs
kern :info : [ 39.748829] leds mmc0::: led_suspend+0x0/0x30 returned 0 after 0 usecs
kern :warn : [ 39.750168] ? ktime_get+0x3e/0xa0
kern :warn : [ 39.750170] ? scsi_bus_freeze+0x20/0x20
kern :info : [ 39.751425] input input6: calling input_dev_suspend+0x0/0x60 @ 471, parent: pcspkr
kern :warn : [ 39.752764] dpm_run_callback+0x4e/0x180
kern :warn : [ 39.752766] __device_suspend+0xf1/0x460
kern :info : [ 39.754112] input input6: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.755538] async_suspend+0x1a/0x90
kern :warn : [ 39.755541] async_run_entry_fn+0x39/0x160
kern :info : [ 39.756916] input input5: calling input_dev_suspend+0x0/0x60 @ 471, parent: rc0
kern :warn : [ 39.758275] process_one_work+0x19c/0x3b0
kern :warn : [ 39.758277] worker_thread+0x3c/0x3b0
kern :info : [ 39.759686] input input5: input_dev_suspend+0x0/0x60 returned 0 after 0 usecs
kern :warn : [ 39.761138] ? process_one_work+0x3b0/0x3b0
kern :warn : [ 39.761140] kthread+0x11e/0x140
kern :info : [ 39.762622] input input4: calling input_dev_suspend+0x0/0x60 @ 471, parent: 0003:14DD:1005.0001
kern :info : [ 39.762633] usb 1-7: calling usb_dev_suspend+0x0/0x10 @ 519, parent: usb1
kern :info : [ 39.762636] usb 1-7: usb_dev_suspend+0x0/0x10 returned 0 after 1 usecs
kern :warn : [ 39.763984] ? kthread_associate_blkcg+0xb0/0xb0
kern :warn : [ 39.763987] ret_from_fork+0x35/0x40
kern :warn : [ 39.763989] ---[ end trace 584e64f738d4cc70 ]---
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Rong Chen
2 years, 4 months
Re: [LKP] [fsnotify] 60f7ed8c7c: will-it-scale.per_thread_ops -5.9% regression
by Jan Kara
On Sun 30-09-18 12:16:45, Amir Goldstein wrote:
> On Sun, Sep 30, 2018 at 12:00 PM Amir Goldstein <amir73il(a)gmail.com> wrote:
> >
> > On Sun, Sep 30, 2018 at 9:50 AM kernel test robot <rong.a.chen(a)intel.com> wrote:
> > >
> > > Greeting,
> > >
> > > FYI, we noticed a -5.9% regression of will-it-scale.per_thread_ops due to commit:
> > >
> > >
> > > commit: 60f7ed8c7c4d06aeda448c6da74621552ee739aa ("fsnotify: send path type events to group with super block marks")
> > > https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
> > >
> > > in testcase: will-it-scale
> > > on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 64G memory
> > > with following parameters:
> > >
> > > nr_task: 16
> > > mode: thread
> > > test: unlink2
> > > cpufreq_governor: performance
> > >
> > > test-description: Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two.
> > > test-url: https://github.com/antonblanchard/will-it-scale
> > >
> > >
> > >
> > > Details are as below:
> > > -------------------------------------------------------------------------------------------------->
> > >
> > >
> > > To reproduce:
> > >
> > > git clone https://github.com/intel/lkp-tests.git
> > > cd lkp-tests
> > > bin/lkp install job.yaml # job file is attached in this email
> > > bin/lkp run job.yaml
> > >
> > > =========================================================================================
> > > compiler/cpufreq_governor/kconfig/mode/nr_task/rootfs/tbox_group/test/testcase:
> > > gcc-7/performance/x86_64-rhel-7.2/thread/16/debian-x86_64-2018-04-03.cgz/lkp-bdw-ep3d/unlink2/will-it-scale
> > >
> > > commit:
> > > 1e6cb72399 ("fsnotify: add super block object type")
> > > 60f7ed8c7c ("fsnotify: send path type events to group with super block marks")
> > >
> >
> > I have to admit this looks strange.
> > All this commit does is dereference mnt->mnt.mnt_sb and then
> > sb->s_fsnotify_mask/sb->s_fsnotify_marks to find that they are zero.
> > AFAICT there should be no extra contention added by this commit and it's
> > hard to believe that parallel unlink workload would suffer from this change.
> >
> > I will try to install lkp-tests to verify this on my own system, but
> > until proven
> > otherwise I will regard this as false positive.
> >
>
> Actually, is it possible to ask for a private test with the following patch
> to optimize out an unneeded srcu_derefence().
> This optimization (assuming it is correct) could in fact improve scaling
> compared to upstream, because there are already 2 calls to
> fsnotify_first_mark in the code. The blamed commit just adds a 3rd one.
I'd be surprised if your patch actually changed anything. On x86,
srcu_dereference(foo) is just READ_ONCE(foo) and that is just a syntactic
suggar around foo to make sure compiler really fetches the value from
memory to a register only once... But it's probably worth a try.
> I am assuming that() in the test there is an fsnotify mount mark (maybe setup
> by systemd) otherwise, the optimization in line 351 would have not reached
> the extra fsnotify_first_mark() call.
> Can you confirm or disprove the assumption that an fanotify mount mark
> is present during the test?
This would be good to know.
Honza
> ----
> diff --git a/fs/notify/fsnotify.c b/fs/notify/fsnotify.c
> index 422fbc6dffde..8d45d82e09ff 100644
> --- a/fs/notify/fsnotify.c
> +++ b/fs/notify/fsnotify.c
> @@ -246,6 +246,9 @@ static struct fsnotify_mark
> *fsnotify_first_mark(struct fsnotify_mark_connector
> struct fsnotify_mark_connector *conn;
> struct hlist_node *node = NULL;
>
> + if (!*connp)
> + return NULL;
> +
> conn = srcu_dereference(*connp, &fsnotify_mark_srcu);
> if (conn)
> node = srcu_dereference(conn->list.first, &fsnotify_mark_srcu);
--
Jan Kara <jack(a)suse.com>
SUSE Labs, CR
2 years, 4 months
[x86/mm/tlb] 5462bc3a9a: unixbench.score 7.0% improvement
by kernel test robot
Greeting,
FYI, we noticed a 7.0% improvement of unixbench.score due to commit:
commit: 5462bc3a9a3c38328bbbd276d51164c7cf21d6a8 ("x86/mm/tlb: Always use lazy TLB mode")
https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git x86/mm
in testcase: unixbench
on test machine: 8 threads Ivy Bridge with 16G memory
with following parameters:
runtime: 300s
nr_task: 1
test: context1
ucode: 0x20
cpufreq_governor: performance
test-description: UnixBench is the original BYTE UNIX benchmark suite aims to test performance of Unix-like system.
test-url: https://github.com/kdlucas/byte-unixbench
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
=========================================================================================
compiler/cpufreq_governor/kconfig/nr_task/rootfs/runtime/tbox_group/test/testcase/ucode:
gcc-7/performance/x86_64-rhel-7.2/1/debian-x86_64-2018-04-03.cgz/300s/lkp-ivb-d01/context1/unixbench/0x20
commit:
a31acd3ee8 ("x86/mm: Page size aware flush_tlb_mm_range()")
5462bc3a9a ("x86/mm/tlb: Always use lazy TLB mode")
a31acd3ee8f7dbc0 5462bc3a9a3c38328bbbd276d5
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
1:4 -25% :4 dmesg.RIP:copy_page_to_iter
:4 100% 4:4 dmesg.RIP:cpuidle_enter_state
:4 25% 1:4 kmsg.ba52ac8>]usb_hcd_irq
1:4 -25% :4 kmsg.e4afb4>]usb_hcd_irq
:4 25% 1:4 kmsg.e5d84e9>]usb_hcd_irq
1:4 -25% :4 kmsg.eaf7194>]usb_hcd_irq
1:4 -25% :4 kmsg.f4ac>]usb_hcd_irq
:4 25% 1:4 kmsg.usb_hcd_irq
%stddev %change %stddev
\ | \
386.50 +7.0% 413.58 unixbench.score
410.13 -0.9% 406.25 unixbench.time.elapsed_time
410.13 -0.9% 406.25 unixbench.time.elapsed_time.max
55.00 +3.2% 56.75 unixbench.time.percent_of_cpu_this_job_got
207.51 +2.2% 212.17 unixbench.time.system_time
46114091 +7.0% 49358271 unixbench.time.voluntary_context_switches
62958045 +6.2% 66876363 unixbench.workload
22199 +1.9% 22621 interrupts.CAL:Function_call_interrupts
0.22 ± 43% -40.2% 0.13 ± 12% turbostat.CPU%c6
451150 +7.5% 484981 vmstat.system.cs
3399624 ± 12% -17.0% 2823082 ± 5% cpuidle.POLL.time
1497754 ± 2% +18.2% 1770379 cpuidle.POLL.usage
3826 ± 7% +18.1% 4518 ± 7% slabinfo.anon_vma.active_objs
3875 ± 6% +16.6% 4520 ± 7% slabinfo.anon_vma.num_objs
1280 ± 11% -14.6% 1093 ± 9% slabinfo.skbuff_head_cache.active_objs
147541 ± 7% +17.0% 172576 ± 9% sched_debug.cfs_rq:/.load.avg
89.04 ± 11% +24.6% 110.95 ± 7% sched_debug.cfs_rq:/.runnable_load_avg.avg
155.33 ± 15% +20.5% 187.16 ± 12% sched_debug.cfs_rq:/.runnable_load_avg.stddev
136488 ± 10% +19.9% 163599 ± 7% sched_debug.cfs_rq:/.runnable_weight.avg
139887 ± 7% +18.0% 165093 ± 3% sched_debug.cpu.load.avg
3820087 ± 9% +20.8% 4613034 ± 8% sched_debug.cpu.nr_switches.stddev
3818882 ± 9% +20.8% 4611438 ± 8% sched_debug.cpu.sched_count.stddev
1909422 ± 9% +20.8% 2305711 ± 8% sched_debug.cpu.sched_goidle.stddev
1909878 ± 9% +20.8% 2306344 ± 8% sched_debug.cpu.ttwu_count.stddev
2.633e+11 ± 25% +29.7% 3.415e+11 ± 12% perf-stat.branch-instructions
1.865e+08 +7.0% 1.995e+08 perf-stat.context-switches
1.41 -5.9% 1.32 perf-stat.cpi
1.46 ± 3% -0.5 1.00 ± 3% perf-stat.dTLB-load-miss-rate%
3.225e+11 ± 25% +30.0% 4.192e+11 ± 12% perf-stat.dTLB-loads
0.15 ± 7% -0.1 0.08 ± 3% perf-stat.dTLB-store-miss-rate%
2.001e+11 ± 25% +30.1% 2.604e+11 ± 12% perf-stat.dTLB-stores
77.66 -15.9 61.81 perf-stat.iTLB-load-miss-rate%
2.038e+09 ± 25% -45.2% 1.118e+09 ± 14% perf-stat.iTLB-load-misses
1.213e+12 ± 25% +29.4% 1.569e+12 ± 12% perf-stat.instructions
595.17 +136.3% 1406 perf-stat.instructions-per-iTLB-miss
0.71 +6.3% 0.76 perf-stat.ipc
10.90 ± 10% -4.5 6.41 ± 45% perf-profile.calltrace.cycles-pp.pipe_read.__vfs_read.vfs_read.ksys_read.do_syscall_64
11.03 ± 10% -4.4 6.60 ± 45% perf-profile.calltrace.cycles-pp.__vfs_read.vfs_read.ksys_read.do_syscall_64.entry_SYSCALL_64_after_hwframe
7.76 ± 9% -3.2 4.54 ± 43% perf-profile.calltrace.cycles-pp.pipe_wait.pipe_read.__vfs_read.vfs_read.ksys_read
6.68 ± 9% -3.1 3.60 ± 44% perf-profile.calltrace.cycles-pp.schedule.pipe_wait.pipe_read.__vfs_read.vfs_read
6.49 ± 9% -3.0 3.48 ± 44% perf-profile.calltrace.cycles-pp.__sched_text_start.schedule.pipe_wait.pipe_read.__vfs_read
4.80 ± 5% -2.5 2.29 ± 48% perf-profile.calltrace.cycles-pp.schedule_idle.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64
4.68 ± 4% -2.5 2.20 ± 49% perf-profile.calltrace.cycles-pp.__sched_text_start.schedule_idle.do_idle.cpu_startup_entry.start_secondary
1.01 ± 22% -0.6 0.36 ±102% perf-profile.calltrace.cycles-pp.copy_page_to_iter.pipe_read.__vfs_read.vfs_read.ksys_read
11.81 ± 11% -5.5 6.27 ± 45% perf-profile.children.cycles-pp.__sched_text_start
12.77 ± 10% -4.6 8.13 ± 44% perf-profile.children.cycles-pp.ksys_read
12.33 ± 10% -4.6 7.74 ± 44% perf-profile.children.cycles-pp.vfs_read
10.92 ± 10% -4.5 6.45 ± 44% perf-profile.children.cycles-pp.pipe_read
11.05 ± 10% -4.4 6.66 ± 44% perf-profile.children.cycles-pp.__vfs_read
7.79 ± 9% -3.1 4.68 ± 44% perf-profile.children.cycles-pp.pipe_wait
6.69 ± 9% -3.0 3.69 ± 44% perf-profile.children.cycles-pp.schedule
2.68 ± 6% -2.6 0.08 ± 66% perf-profile.children.cycles-pp.switch_mm_irqs_off
5.12 ± 14% -2.5 2.64 ± 46% perf-profile.children.cycles-pp.schedule_idle
2.25 ± 8% -0.6 1.60 ± 34% perf-profile.children.cycles-pp.tick_nohz_next_event
1.08 ± 10% -0.6 0.51 ± 49% perf-profile.children.cycles-pp.copy_page_to_iter
0.48 ± 32% -0.3 0.18 ± 71% perf-profile.children.cycles-pp.touch_atime
1.07 ± 7% -0.3 0.79 ± 35% perf-profile.children.cycles-pp.__next_timer_interrupt
0.42 ± 28% -0.2 0.25 ± 32% perf-profile.children.cycles-pp.___perf_sw_event
0.68 ± 7% -0.2 0.50 ± 33% perf-profile.children.cycles-pp.find_next_bit
0.28 ± 17% -0.2 0.12 ± 57% perf-profile.children.cycles-pp.account_entity_enqueue
0.49 ± 9% -0.1 0.35 ± 32% perf-profile.children.cycles-pp.__update_load_avg_cfs_rq
0.32 ± 12% -0.1 0.19 ± 39% perf-profile.children.cycles-pp.__update_load_avg_se
0.20 ± 19% -0.1 0.06 ± 70% perf-profile.children.cycles-pp.pm_qos_request
0.18 ± 20% -0.1 0.05 ±124% perf-profile.children.cycles-pp.anon_pipe_buf_release
0.21 ± 19% -0.1 0.11 ± 70% perf-profile.children.cycles-pp.rcu_needs_cpu
0.14 ± 20% -0.1 0.04 ±104% perf-profile.children.cycles-pp.tick_check_broadcast_expired
0.18 ± 57% -0.1 0.10 ± 17% perf-profile.children.cycles-pp.clockevents_program_event
0.12 ± 39% -0.1 0.03 ±102% perf-profile.children.cycles-pp.irq_work_needs_cpu
0.15 ± 18% -0.1 0.09 ± 40% perf-profile.children.cycles-pp.put_prev_entity
0.08 ± 40% -0.1 0.03 ±100% perf-profile.children.cycles-pp.run_timer_softirq
1.28 ± 5% -1.2 0.07 ± 62% perf-profile.self.cycles-pp.switch_mm_irqs_off
0.53 ± 17% -0.4 0.17 ± 39% perf-profile.self.cycles-pp.copy_page_to_iter
0.24 ± 42% -0.2 0.04 ±101% perf-profile.self.cycles-pp.atime_needs_update
0.47 ± 8% -0.1 0.34 ± 32% perf-profile.self.cycles-pp.__update_load_avg_cfs_rq
0.32 ± 13% -0.1 0.18 ± 38% perf-profile.self.cycles-pp.__update_load_avg_se
0.18 ± 20% -0.1 0.05 ±124% perf-profile.self.cycles-pp.anon_pipe_buf_release
0.18 ± 22% -0.1 0.06 ± 70% perf-profile.self.cycles-pp.pm_qos_request
0.24 ± 12% -0.1 0.12 ± 78% perf-profile.self.cycles-pp.__calc_delta
0.20 ± 17% -0.1 0.11 ± 68% perf-profile.self.cycles-pp.rcu_needs_cpu
0.14 ± 19% -0.1 0.04 ±104% perf-profile.self.cycles-pp.tick_check_broadcast_expired
0.11 ± 41% -0.1 0.03 ±100% perf-profile.self.cycles-pp.irq_work_needs_cpu
0.09 ± 20% -0.1 0.04 ±103% perf-profile.self.cycles-pp.current_time
unixbench.time.voluntary_context_switches
6e+07 +-+-----------------------------------------------------------------+
| |
5e+07 O-OO O O OO O OO O OO O O OO O OO O OO O O O O O O OO O O |
|.++.+.+ +.+.++.+.++.+.+.++.+.++.+.++.+.+.+O.+.O +.+ +.O.O+.+.++.|
| : : : : : : |
4e+07 +-+ : : : : : : |
| : : : : : : |
3e+07 +-+ : : : : : : |
| : : : : : : |
2e+07 +-+ :: :: :: |
| :: :: :: |
| :: :: :: |
1e+07 +-+ : : : |
| : : : |
0 +-+-----------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
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,
Rong Chen
2 years, 4 months
[igb] 0bb7d3174c: BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 0bb7d3174c2d02358cf3072a04cffb9c07a10f04 ("igb: reduce CPU0 latency when updating statistics")
https://git.kernel.org/cgit/linux/kernel/git/jkirsher/next-queue.git dev-queue
in testcase: ltp
with following parameters:
test: net.rpc
test-description: The LTP testsuite contains a collection of tools for testing the Linux kernel and related features.
test-url: http://linux-test-project.github.io/
on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 64G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------+------------+------------+
| | 21145e703c | 0bb7d3174c |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 3 | 4 |
| boot_failures | 0 | 12 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 12 |
+-----------------------------------------------------------------------------+------------+------------+
[ 112.280332] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:254
[ 112.293170] in_atomic(): 1, irqs_disabled(): 0, pid: 3274, name: rpc.rstatd
[ 112.301910] CPU: 19 PID: 3274 Comm: rpc.rstatd Not tainted 4.19.0-rc6-02017-g0bb7d31 #1
[ 112.311803] Hardware name: Intel Corporation S2600KPR/S2600KPR, BIOS SE5C610.86B.01.01.0019.101220160604 10/12/2016
[ 112.324442] Call Trace:
[ 112.328158] dump_stack+0x5c/0x7b
[ 112.332827] ___might_sleep+0xf1/0x110
[ 112.337983] mutex_lock+0x1c/0x40
[ 112.342653] igb_get_stats64+0x27/0x80
[ 112.347777] dev_get_stats+0xa1/0xc0
[ 112.352687] dev_seq_printf_stats+0x32/0xe0
[ 112.358260] dev_seq_show+0x10/0x30
[ 112.363030] seq_read+0x28b/0x3b0
[ 112.367590] proc_reg_read+0x39/0x60
[ 112.372439] __vfs_read+0x36/0x190
[ 112.377084] vfs_read+0x9b/0x140
[ 112.381545] ksys_read+0x52/0xc0
[ 112.385965] do_syscall_64+0x5b/0x180
[ 112.390863] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 112.397313] RIP: 0033:0x7efc2e0416d0
[ 112.402093] Code: b6 fe ff ff 48 8d 3d 17 be 08 00 48 83 ec 08 e8 06 db 01 00 66 0f 1f 44 00 00 83 3d 39 30 2c 00 00 75 10 b8 00 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 de 9b 01 00 48 89 04 24
[ 112.424653] RSP: 002b:00007fffd2df8eb8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[ 112.433890] RAX: ffffffffffffffda RBX: 00005649bd7fce20 RCX: 00007efc2e0416d0
[ 112.442665] RDX: 0000000000000400 RSI: 00005649bd801c00 RDI: 0000000000000003
[ 112.451444] RBP: 0000000000000d68 R08: 0000000000000003 R09: 0000000000000410
[ 112.460225] R10: 00007efc2e51c700 R11: 0000000000000246 R12: 00007efc2e2fc440
[ 112.469005] R13: 00007efc2e2fb900 R14: 00000000000003ff R15: 00005649bd7fce20
[ 112.562858] LTP: starting rusers (rusers01)
[ 113.259364] 2018-10-14 17:54:55 ln -sf /usr/bin/genisoimage /usr/bin/mkisofs
[ 113.259367]
[ 113.270637] 2018-10-14 17:54:55 ./runltp -f net.rpc
[ 113.270638]
[ 113.279659] INFO: creating /lkp/benchmarks/ltp/ltp/output directory
[ 113.279661]
[ 113.290150] INFO: creating /lkp/benchmarks/ltp/ltp/results directory
[ 113.290151]
[ 113.300408] Checking for required user/group ids
[ 113.300409]
[ 113.308116]
[ 113.310837] 'nobody' user id and group found.
[ 113.310839]
[ 113.318750] 'bin' user id and group found.
[ 113.318751]
[ 113.326440] 'daemon' user id and group found.
[ 113.326441]
[ 113.334121] Users group found.
[ 113.334122]
[ 113.340331] Sys group found.
[ 113.340332]
[ 113.346490] Required users/groups exist.
[ 113.346492]
[ 113.554344] If some fields are empty or look unusual you may have an old version.
[ 113.554345]
[ 113.566373] Compare to the current minimal requirements in Documentation/Changes.
[ 113.566375]
[ 113.577186]
[ 113.579537] /etc/os-release
[ 113.579539]
[ 113.585849] PRETTY_NAME="Debian GNU/Linux 9 (stretch)"
[ 113.585850]
[ 113.594494] NAME="Debian GNU/Linux"
[ 113.594495]
[ 113.601054] VERSION_ID="9"
[ 113.601056]
[ 113.606837] VERSION="9 (stretch)"
[ 113.606839]
[ 113.613128] ID=debian
[ 113.613129]
[ 113.618668] HOME_URL="https://www.debian.org/"
[ 113.618669]
[ 113.626838] SUPPORT_URL="https://www.debian.org/support"
[ 113.626840]
[ 113.635884] BUG_REPORT_URL="https://bugs.debian.org/"
[ 113.635886]
[ 113.643893]
[ 113.646032] uname:
[ 113.646033]
[ 114.727245] Linux lkp-bdw-ep3d 4.19.0-rc6-02017-g0bb7d31 #1 SMP Mon Oct 15 00:40:52 CST 2018 x86_64 GNU/Linux
[ 114.727247]
[ 114.740653]
[ 114.742911] /proc/cmdline
[ 114.742913]
[ 124.736200] ip=::::lkp-bdw-ep3d::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/lkp-bdw-ep3d/ltp-net.rpc-debian-x86_64-2018-04-03.cgz-0bb7d3174c2d02358cf3072a04cffb9c07a10f04-20181015-22794-qzlwz-8.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.2 branch=linux-devel/devel-hourly-2018101213 commit=0bb7d3174c2d02358cf3072a04cffb9c07a10f04 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.2/gcc-7/0bb7d3174c2d02358cf3072a04cffb9c07a10f04/vmlinuz-4.19.0-rc6-02017-g0bb7d31 max_uptime=3600 RESULT_ROOT=/result/ltp/net.rpc/lkp-bdw-ep3d/debian-x86_64-2018-04-03.cgz/x86_64-rhel-7.2/gcc-7/0bb7d3174c2d02358cf3072a04cffb9c07a10f04/f6dd3af2f13629f20a0a0b912c09c4f261ddf3fd/8 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_log
[ 124.828253]
[ 124.875970]
[ 127.641306] Gnu C gcc (Debian 6.3.0-18+deb9u1) 6.3.0 20170516
[ 127.641308]
[ 127.652367] Gnu make 4.1
[ 127.652369]
[ 127.659699] util-linux linux 2.29.2
[ 127.659700]
[ 128.393402] mount linux 2.29.2 (libmount 2.29.2: selinux, btrfs, assert, debug)
[ 128.393403]
[ 128.406136] modutils 23
[ 128.406137]
[ 128.413231] e2fsprogs 1.43.4
[ 128.413233]
[ 128.420725] Linux C Library > libc.2.24
[ 128.420726]
[ 128.428597] Dynamic linker (ldd) 2.24
[ 128.428598]
[ 128.435800] Procps 3.3.12
[ 128.435803]
[ 128.443272] Net-tools 2.10-alpha
[ 128.443274]
[ 128.451271] iproute2 iproute2-ss161212
[ 128.451272]
[ 128.459933] iputils iputils-s20161105
[ 128.459935]
[ 128.468418] Kbd 84:
[ 128.468421]
[ 128.475534] Sh-utils 8.26
[ 128.475536]
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
lkp
2 years, 4 months
ed8481a61e [ 9.611710] BUG: non-zero pgtables_bytes on freeing mm: -32
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/commits/Martin-Schwidefsky/s390-mm-fix-m...
commit ed8481a61e3584904d92de9be03af5c39671259d
Author: Martin Schwidefsky <schwidefsky(a)de.ibm.com>
AuthorDate: Fri Oct 12 17:08:33 2018 +0200
Commit: 0day robot <lkp(a)intel.com>
CommitDate: Sun Oct 14 14:47:41 2018 +0800
s390: runtime warning about pgtables_bytes
On Thu, 11 Oct 2018 15:02:11 +0200
Martin Schwidefsky <schwidefsky(a)de.ibm.com> wrote:
> On Thu, 11 Oct 2018 18:04:12 +0800
> Li Wang <liwang(a)redhat.com> wrote:
>
> > When running s390 system with LTP/cve-2017-17052.c[1], the following BUG is
> > came out repeatedly.
> > I remember this warning start from kernel-4.16.0 and now it still exist in
> > kernel-4.19-rc7.
> > Can anyone take a look?
> >
> > [ 2678.991496] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.001543] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.002453] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.003256] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.013689] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.024647] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.064408] BUG: non-zero pgtables_bytes on freeing mm: 16384
> > [ 2679.133963] BUG: non-zero pgtables_bytes on freeing mm: 16384
> >
> > [1]:
> > https://github.com/linux-test-project/ltp/blob/master/testcases/cve/cve-2...
>
> Confirmed, I see this bug with cvs-2017-17052 on my LPAR as well.
> I'll look into it.
Ok, I think I understand the problem now. This is the patch I am testing
right now. It seems to fix the issue, but I had to change common mm
code for it.
--
From 9e3bc2e96930206ef1ece377e45224c51aca1799 Mon Sep 17 00:00:00 2001
From: Martin Schwidefsky <schwidefsky(a)de.ibm.com>
Date: Fri, 12 Oct 2018 16:32:29 +0200
Subject: [RFC][PATCH] s390/mm: fix mis-accounting of pgtable_bytes
In case a fork or a clone system fails in copy_process and the error
handling does the mmput() at the bad_fork_cleanup_mm label, the following
warning messages will appear on the console:
BUG: non-zero pgtables_bytes on freeing mm: 16384
The reason for that is the tricks we play with mm_inc_nr_puds() and
mm_inc_nr_pmds() in init_new_context().
A normal 64-bit process has 3 levels of page table, the p4d level and
the pud level are folded. On process termination the free_pud_range()
function in mm/memory.c will subtract 16KB from pgtable_bytes with a
mm_dec_nr_puds() call, but there actually is not really a pud table.
The s390 version of pud_free_tlb() recognized this an does nothing,
the region-3 table will be freed with the pgd_free() call later on.
But the mm_dec_nr_puds() is done unconditionally, to counter act this
the init_new_context() function has an extra mm_inc_nr_puds() call.
Now with a failed fork or clone the free_pgtables() function is not
called, there is no mm_dec_nr_puds() but the mm_inc_nr_puds() has
been done which leads to the incorrect pgtable_bytes of 16384.
Nothing is broken by this, but the warning is annoying.
To get rid of the warning drop the mm_inc_nr_pmds() & mm_inc_nr_puds()
calls from init_new_context(), introduce the mm_pmd_folded(),
pmd_pud_folded() and pmd_p4d_folded() helper, and add if-statements
to the functions mm_[inc|dec]_nr_[pmds|puds].
Signed-off-by: Martin Schwidefsky <schwidefsky(a)de.ibm.com>
5eaf436e0e s390/vmalloc: fix VMALLOC_START calculation
ed8481a61e s390: runtime warning about pgtables_bytes
+-------------------------------------------+------------+------------+
| | 5eaf436e0e | ed8481a61e |
+-------------------------------------------+------------+------------+
| boot_successes | 34 | 0 |
| boot_failures | 1 | 15 |
| Mem-Info | 1 | |
| BUG:non-zero_pgtables_bytes_on_freeing_mm | 0 | 15 |
+-------------------------------------------+------------+------------+
[ 9.602751] debug: unmapping init [mem 0xb3023000-0xb3138fff]
[ 9.603967] Write protecting the kernel text: 18776k
[ 9.604625] Write protecting the kernel read-only data: 8144k
[ 9.605086] Run /init as init process
[ 9.610329] process 126 (init) attempted a POSIX timer syscall while CONFIG_POSIX_TIMERS is not set
[ 9.611710] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.614852] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.616449] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.617783] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.621240] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.622627] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.625760] BUG: non-zero pgtables_bytes on freeing mm: -40
/etc/rcS.d/S00fbsetup: line 3: /sbin/modprobe: No such file or directory
[ 9.631741] BUG: non-zero pgtables_bytes on freeing mm: -48
[ 9.632757] BUG: non-zero pgtables_bytes on freeing mm: -56
[ 9.635839] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.637711] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.639399] BUG: non-zero pgtables_bytes on freeing mm: -40
Please wait: booting...
[ 9.640994] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.643733] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.645309] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.647544] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.649089] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.650136] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.654325] BUG: non-zero pgtables_bytes on freeing mm: -40
Starting udev
[ 9.658011] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.659625] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.661226] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.662742] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.664379] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.665821] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.667431] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.668851] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.670836] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.681314] BUG: non-zero pgtables_bytes on freeing mm: -40
/etc/rcS.d/S03udev: line 72: /proc/sys/kernel/hotplug: No such file or directory
[ 9.683604] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.690893] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.694281] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.695833] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.697484] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.765864] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.769527] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.781404] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.793382] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.794491] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.796269] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.797266] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.801346] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.807608] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.809042] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.810006] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.811145] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.812106] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.813069] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.814079] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.815050] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.816163] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.817531] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.821361] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.822326] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.823409] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.824645] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.826338] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.828094] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.831040] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.832080] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.835138] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.836537] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.837827] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.839154] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.840469] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.841384] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.842287] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.843408] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.847540] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.849077] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.850809] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.851870] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.854382] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.856476] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.857434] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.858410] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.859345] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.860261] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.861246] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.863488] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.869385] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.870379] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.871302] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.872276] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.873243] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.874223] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.878031] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.879162] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.880357] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.881269] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.886276] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.890609] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.891570] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.893394] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.894343] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.895243] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.896157] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.897116] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.900154] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.901097] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.902200] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.905603] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.906855] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.908757] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.911209] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.914122] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.918520] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.919503] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.920504] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.921406] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.922936] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.923959] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.927299] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.928224] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.930121] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.933921] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.934812] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.936548] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.937463] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.938577] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.939461] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.943869] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.969802] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.971785] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.973362] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.984724] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.986229] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.987273] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.988227] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.991213] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.992692] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.994343] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 9.997193] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.998147] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 9.999106] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.000500] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.001552] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.002608] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.003566] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.004535] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.005488] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.006584] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.007554] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.008451] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.009405] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.010365] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.012042] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.013540] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.014330] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.015295] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.020846] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.021647] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.025110] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.026018] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.027128] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.028045] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.029336] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.030262] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.031244] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.032203] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.033106] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.035840] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.041366] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.044862] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.053249] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.056995] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.058421] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.059905] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.062036] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.070355] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.073411] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.075371] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.077001] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.079643] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.080671] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.091551] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.093883] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.097317] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.101374] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.102888] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.109162] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.110768] BUG: non-zero pgtables_bytes on freeing mm: -40
[ 10.113073] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.114745] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.117298] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.120637] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.123983] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.127325] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.130638] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.134009] BUG: non-zero pgtables_bytes on freeing mm: -32
[ 10.137318] BUG: non-zero pgtables_bytes on freeing mm: -32
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 5eca99c09dd33c172f4110663737cef26d0458e4 0238df646e6224016a45505d2c111a24669ebe21 --
git bisect bad d1cea4998b25f08e6a49a2c4da576c6cbd1296c0 # 17:00 B 0 8 22 0 Merge 'linux-review/Nishad-Kamdar/staging-mt7621-mmc-Use-BIT-macro-instead-of-explicit-shifting-in-dbg-h/20181014-073338' into devel-catchup-201810141522
git bisect bad 1783ccb99cb28aba7606a17f0686b3ecbd47dea9 # 17:09 B 0 6 20 0 Merge 'linux-review/Vasily-Khoruzhick/ASoC-sun4i-i2s-don-t-try-to-start-up-or-shut-down-DAI-if-it-s-active/20181014-095159' into devel-catchup-201810141522
git bisect bad 6f23edb781e35519c7a6bcb5fff591e8003b3053 # 17:21 B 0 11 25 0 Merge 'linux-review/Linus-Walleij/usb-host-add-DT-bindings-for-faraday-fotg2/20181014-114411' into devel-catchup-201810141522
git bisect bad a24b69f11e017e8dfb1069ea76b13a5d8aed97cb # 17:31 B 0 1 17 2 Merge 'linux-review/Andrea-Arcangeli/mm-thp-fix-MADV_DONTNEED-vs-migrate_misplaced_transhuge_page-race-condition/20181014-143004' into devel-catchup-201810141522
git bisect good 9c9946d5ea1fda1efc883a4be4339c9c999e4b0c # 17:46 G 11 0 0 0 0day base guard for 'devel-catchup-201810141522'
git bisect bad 7d2fd7d3663a2409705047748d3103f7b1a8ac3e # 18:00 B 0 8 23 1 Merge 'linux-review/Martin-Schwidefsky/s390-mm-fix-mis-accounting-of-pgtable_bytes/20181014-144736' into devel-catchup-201810141522
git bisect good 49698745e53c417370ac5cfe8b849bb65d62f129 # 18:12 G 11 0 0 0 s390: move ipl block and cmd line handling to early boot phase
git bisect good e006222b57508d58b55d36c6ae6663f5729dad2b # 18:24 G 11 0 1 1 s390/mm: optimize debugfs ptdump kasan zero page walking
git bisect good 0534bde7de19a2e66c2b2bf05fcfd00a7cc849fa # 18:36 G 11 0 2 2 s390/pkey: Define protected key blob format
git bisect good 52a34b34d4ff9a61bc6da9740541d8f08a40438c # 18:49 G 11 0 1 1 s390/crypto: Enhance paes cipher to accept variable length key material
git bisect good c72251ad879056d096d39db21c08cb52e481eb2d # 19:00 G 10 0 1 1 s390/mem_detect: add missing include
git bisect bad ed8481a61e3584904d92de9be03af5c39671259d # 19:08 B 0 11 25 0 s390: runtime warning about pgtables_bytes
git bisect good 5eaf436e0e5b7ce05ef9371e82f87319bc9a5173 # 19:18 G 11 0 1 1 s390/vmalloc: fix VMALLOC_START calculation
# first bad commit: [ed8481a61e3584904d92de9be03af5c39671259d] s390: runtime warning about pgtables_bytes
git bisect good 5eaf436e0e5b7ce05ef9371e82f87319bc9a5173 # 19:22 G 32 0 0 1 s390/vmalloc: fix VMALLOC_START calculation
# extra tests on HEAD of linux-devel/devel-catchup-201810141522
git bisect bad 5eca99c09dd33c172f4110663737cef26d0458e4 # 19:22 B 0 15 32 0 0day head guard for 'devel-catchup-201810141522'
# extra tests on tree/branch linux-review/Martin-Schwidefsky/s390-mm-fix-mis-accounting-of-pgtable_bytes/20181014-144736
git bisect bad ed8481a61e3584904d92de9be03af5c39671259d # 19:24 B 0 15 29 0 s390: runtime warning about pgtables_bytes
# extra tests with first bad commit reverted
git bisect good 2b6c00a05fca76811f0ad912ee0607ea45fba190 # 19:36 G 11 0 0 0 Revert "s390: runtime warning about pgtables_bytes"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 4 months
[MD] d595567dc4: mdadm-selftests.02lineargrow.fail
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: d595567dc4f0c1d90685ec1e2e296e2cad2643ac ("MD: fix invalid stored role for a disk")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: mdadm-selftests
with following parameters:
disk: 1HDD
test_prefix: 02
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
Welcome to fdisk (util-linux 2.29.2).
Changes will remain in memory only, until you decide to write them.
Be careful before using the write command.
Device does not contain a recognized partition table.
Created a new DOS disklabel with disk identifier 0xd50ae9f0.
Command (m for help): Partition type
p primary (0 primary, 0 extended, 4 free)
e extended (container for logical partitions)
Select (default p): Partition number (1-4, default 1): First sector (2048-536870911, default 2048): Last sector, +sectors or +size{K,M,G,T,P} (2048-536870911, default 536870911):
Created a new partition 1 of type 'Linux' and of size 5 GiB.
Command (m for help): The partition table has been altered.
Calling ioctl() to re-read partition table.
Syncing disks.
2018-10-12 11:05:55 mkdir -p /var/tmp
2018-10-12 11:05:55 mke2fs -t ext3 -b 1024 -J size=1 -q /dev/vda1
2018-10-12 11:06:00 mount -t ext3 /dev/vda1 /var/tmp
sed -e 's/{DEFAULT_METADATA}/1.2/g' \
-e 's,{MAP_PATH},/run/mdadm/map,g' mdadm.8.in > mdadm.8
/usr/bin/install -D -m 644 mdadm.8 /usr/share/man/man8/mdadm.8
/usr/bin/install -D -m 644 mdmon.8 /usr/share/man/man8/mdmon.8
/usr/bin/install -D -m 644 md.4 /usr/share/man/man4/md.4
/usr/bin/install -D -m 644 mdadm.conf.5 /usr/share/man/man5/mdadm.conf.5
/usr/bin/install -D -m 644 udev-md-raid-arrays.rules /lib/udev/rules.d/63-md-raid-arrays.rules
/usr/bin/install -D -m 644 udev-md-raid-assembly.rules /lib/udev/rules.d/64-md-raid-assembly.rules
/usr/bin/install -D -m 755 mdadm /sbin/mdadm
/usr/bin/install -D -m 755 mdmon /sbin/mdmon
Testing on linux-4.19.0-rc5-00182-gd595567 kernel
tests/02lineargrow... FAILED - see /var/tmp/log for details
Testing on linux-4.19.0-rc5-00182-gd595567 kernel
tests/02r1add... succeeded
Testing on linux-4.19.0-rc5-00182-gd595567 kernel
tests/02r1grow... succeeded
Testing on linux-4.19.0-rc5-00182-gd595567 kernel
tests/02r5grow... succeeded
Testing on linux-4.19.0-rc5-00182-gd595567 kernel
tests/02r6grow... succeeded
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
Rong Chen
2 years, 4 months
