lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [thread-next>] [day] [month] [year] [list]
Message-ID: <20181218002028.GG23332@shao2-debian>
Date:   Tue, 18 Dec 2018 08:20:28 +0800
From:   kernel test robot <rong.a.chen@...el.com>
To:     Matthew Wilcox <willy@...radead.org>
Cc:     linux-kernel@...r.kernel.org, LKP <lkp@...org>
Subject: [LKP] 5404a7f1c2 [ 90.902541] watchdog: BUG: soft lockup - CPU#0
 stuck for 22s! [swapper:1]

Greetings,

0day kernel testing robot got the below dmesg and the first bad commit is

https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master

commit 5404a7f1c21cfda061712bedf2d06cc0f6c755e9
Author:     Matthew Wilcox <willy@...radead.org>
AuthorDate: Mon Nov 5 09:34:04 2018 -0500
Commit:     Matthew Wilcox <willy@...radead.org>
CommitDate: Fri Nov 16 16:38:45 2018 -0500

    XArray tests: Correct some 64-bit assumptions
    
    The test-suite caught these two mistakes when compiled for 32-bit.
    I had only been running the test-suite in 64-bit mode.
    
    Signed-off-by: Matthew Wilcox <willy@...radead.org>

44a4a66b61  XArray: Correct xa_store_range
5404a7f1c2  XArray tests: Correct some 64-bit assumptions
7566ec393f  Linux 4.20-rc7
d14b746c6c  Add linux-next specific files for 20181214
+---------------------------------------------------------+------------+------------+-----------+---------------+
|                                                         | 44a4a66b61 | 5404a7f1c2 | v4.20-rc7 | next-20181214 |
+---------------------------------------------------------+------------+------------+-----------+---------------+
| boot_successes                                          | 0          | 635        | 641       | 604           |
| boot_failures                                           | 1926       | 197        | 142       | 219           |
| WARNING:at_include/linux/xarray.h:#__check_store_range  | 1850       |            |           |               |
| EIP:__check_store_range                                 | 1850       | 2          |           |               |
| BUG:soft_lockup-CPU##stuck_for#s                        | 1926       | 176        | 116       | 186           |
| EIP:xas_store                                           | 926        | 22         | 17        | 29            |
| Kernel_panic-not_syncing:softlockup:hung_tasks          | 1926       | 176        | 116       | 186           |
| EIP:thread_lookup_test                                  | 2          | 0          | 0         | 3             |
| EIP:xas_free_nodes                                      | 262        | 2          | 3         | 2             |
| EIP:call_rcu                                            | 181        | 2          | 1         | 4             |
| EIP:xas_create                                          | 280        | 44         | 34        | 48            |
| EIP:find_next_bit                                       | 64         | 1          | 1         | 10            |
| EIP:xa_store_range                                      | 145        | 3          |           |               |
| EIP:xas_find                                            | 17         | 2          | 0         | 2             |
| EIP:xas_load                                            | 9          | 15         | 4         | 7             |
| EIP:xa_find                                             | 4          | 1          |           |               |
| EIP:kmem_cache_alloc                                    | 2          | 3          | 0         | 1             |
| EIP:xas_clear_mark                                      | 2          | 1          | 1         | 1             |
| EIP:__xas_next                                          | 3          | 1          |           |               |
| EIP:check_move_small                                    | 2          | 0          | 0         | 1             |
| EIP:xa_erase                                            | 1          | 1          | 0         | 1             |
| EIP:__xa_erase                                          | 2          | 1          | 0         | 1             |
| EIP:check_xa_mark_1                                     | 1          | 1          |           |               |
| EIP:xas_get_mark                                        | 3          |            |           |               |
| EIP:check_find_1                                        | 1          | 0          | 0         | 1             |
| EIP:xa_get_mark                                         | 6          |            |           |               |
| EIP:xas_find_marked                                     | 8          | 1          | 2         | 2             |
| EIP:__xas_prev                                          | 1          | 1          | 1         | 1             |
| EIP:xa_load                                             | 1          | 2          | 1         | 2             |
| EIP:xa_store_many_order                                 | 1          | 1          | 2         | 2             |
| EIP:xa_erase_index                                      | 1          | 1          | 1         |               |
| EIP:__xa_set_mark                                       | 1          |            |           |               |
| Mem-Info                                                | 0          | 21         | 26        | 33            |
| EIP:__slab_alloc                                        | 0          | 13         | 9         | 14            |
| EIP:xas_find_conflict                                   | 0          | 8          | 2         | 1             |
| EIP:do_raw_spin_lock                                    | 0          | 3          | 3         |               |
| EIP:xa_store                                            | 0          | 6          | 1         | 3             |
| EIP:xa_store_index                                      | 0          | 3          | 2         | 7             |
| EIP:xas_alloc                                           | 0          | 3          | 1         | 1             |
| EIP:_raw_spin_unlock                                    | 0          | 2          | 2         | 4             |
| EIP:__check_store_iter                                  | 0          | 2          | 7         | 8             |
| EIP:__xa_store                                          | 0          | 22         | 11        | 12            |
| EIP:check_move                                          | 0          | 1          |           |               |
| EIP:do_raw_spin_unlock                                  | 0          | 3          | 3         | 4             |
| EIP:check_store_iter                                    | 0          | 2          | 0         | 3             |
| invoked_oom-killer:gfp_mask=0x                          | 0          | 0          | 4         | 1             |
| Out_of_memory:Kill_process                              | 0          | 0          | 1         | 1             |
| Out_of_memory_and_no_killable_processes                 | 0          | 0          | 1         | 1             |
| Kernel_panic-not_syncing:System_is_deadlocked_on_memory | 0          | 0          | 1         | 1             |
| EIP:should_failslab                                     | 0          | 0          | 1         |               |
| EIP:xas_start                                           | 0          | 0          | 3         |               |
| EIP:__xas_nomem                                         | 0          | 0          | 3         | 3             |
| EIP:check_find_3                                        | 0          | 0          | 0         | 4             |
| EIP:_raw_spin_lock                                      | 0          | 0          | 0         | 1             |
| EIP:xas_init_marks                                      | 0          | 0          | 0         | 1             |
| EIP:threadfunc                                          | 0          | 0          | 0         | 2             |
+---------------------------------------------------------+------------+------------+-----------+---------------+

[   63.558945] test_printf: all 240 tests passed
[   63.571646] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 3230
[   63.861584] test_bitmap: all 1524 tests passed
[   63.883448] test_bitfield: tests passed
[   63.895517] test_uuid: all 18 tests passed
[   90.902541] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
[   90.902541] CPU: 0 PID: 1 Comm: swapper Not tainted 4.20.0-rc1-00012-g5404a7f #1
[   90.902541] EIP: do_raw_spin_lock+0x32/0x70
[   90.902541] Code: 89 c3 75 2c a1 00 e7 47 82 39 43 0c 74 32 8b 43 08 85 c0 74 43 c7 03 00 00 00 00 a1 00 e7 47 82 c7 43 08 00 00 00 00 89 43 0c <5b> c3 8d 74 26 00 ba 13 a0 30 82 e8 0e fe ff ff eb c8 8d 74 26 00
[   90.902541] EAX: 9f1df400 EBX: 824e45fc ECX: 00000000 EDX: 00026228
[   90.902541] ESI: 824e45fc EDI: 00000200 EBP: 00000009 ESP: 9f1e1ec4
[   90.902541] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068 EFLAGS: 00200286
[   90.902541] CR0: 80050033 CR2: 00000000 CR3: 026ab000 CR4: 000006d0
[   90.902541] Call Trace:
[   90.902541]  ? xa_erase+0x15/0x40
[   90.902541]  ? xa_erase_index+0x1a/0xa0
[   90.902541]  ? test_update_node+0x90/0x90
[   90.902541]  ? check_create_range_2+0x45/0x90
[   90.902541]  ? check_create_range_1+0x2d/0x80
[   90.902541]  ? check_create_range+0x5f/0xf0
[   90.902541]  ? check_xa_alloc+0x340/0x340
[   90.902541]  ? xarray_checks+0x5a/0xc0
[   90.902541]  ? test_uuid_failed+0x67/0x67
[   90.902541]  ? do_one_initcall+0x65/0x134
[   90.902541]  ? trace_event_define_fields_initcall_finish+0x3f/0x3f
[   90.902541]  ? kernel_init_freeable+0x14a/0x1c8
[   90.902541]  ? rest_init+0x90/0x90
[   90.902541]  ? kernel_init+0x8/0x100
[   90.902541]  ? _raw_spin_unlock_irq+0xa/0x20
[   90.902541]  ? schedule_tail_wrapper+0x6/0x8
[   90.902541]  ? ret_from_fork+0x19/0x24
[   90.902541] Kernel panic - not syncing: softlockup: hung tasks
[   90.902541] CPU: 0 PID: 1 Comm: swapper Tainted: G             L    4.20.0-rc1-00012-g5404a7f #1
[   90.902541] Call Trace:
[   90.902541]  <IRQ>
[   90.902541]  ? panic+0x99/0x1bb
[   90.902541]  ? watchdog_timer_fn+0x239/0x240
[   90.902541]  ? __hrtimer_run_queues+0xfa/0x250
[   90.902541]  ? stop_one_cpu_nowait_workfn+0x20/0x20
[   90.902541]  ? hrtimer_interrupt+0xfe/0x2c0
[   90.902541]  ? handle_untracked_irq+0xb0/0xb0
[   90.902541]  ? timer_interrupt+0xa/0x10
[   90.902541]  ? __handle_irq_event_percpu+0x39/0x190
[   90.902541]  ? handle_irq_event_percpu+0x36/0x70
[   90.902541]  ? handle_untracked_irq+0xb0/0xb0
[   90.902541]  ? handle_irq_event_percpu+0x29/0x70
[   90.902541]  ? handle_irq_event+0x31/0x60
[   90.902541]  ? handle_untracked_irq+0xb0/0xb0
[   90.902541]  ? handle_level_irq+0x63/0xd0
[   90.902541]  ? handle_irq+0x88/0xc0
[   90.902541]  </IRQ>
[   90.902541]  ? do_IRQ+0x3a/0xaf
[   90.902541]  ? common_interrupt+0xe5/0xec
[   90.902541]  ? do_raw_spin_lock+0x32/0x70
[   90.902541]  ? xa_erase+0x15/0x40
[   90.902541]  ? xa_erase_index+0x1a/0xa0
[   90.902541]  ? test_update_node+0x90/0x90
[   90.902541]  ? check_create_range_2+0x45/0x90
[   90.902541]  ? check_create_range_1+0x2d/0x80
[   90.902541]  ? check_create_range+0x5f/0xf0
[   90.902541]  ? check_xa_alloc+0x340/0x340
[   90.902541]  ? xarray_checks+0x5a/0xc0
[   90.902541]  ? test_uuid_failed+0x67/0x67
[   90.902541]  ? do_one_initcall+0x65/0x134
[   90.902541]  ? trace_event_define_fields_initcall_finish+0x3f/0x3f
[   90.902541]  ? kernel_init_freeable+0x14a/0x1c8
[   90.902541]  ? rest_init+0x90/0x90
[   90.902541]  ? kernel_init+0x8/0x100
[   90.902541]  ? _raw_spin_unlock_irq+0xa/0x20
[   90.902541]  ? schedule_tail_wrapper+0x6/0x8
[   90.902541]  ? ret_from_fork+0x19/0x24
[   90.902541] Kernel Offset: disabled


                                                          # HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start 40e020c129cfc991e8ab4736d2665351ffd1468d v4.19 --
git bisect good e9ebc2151f88600e726e51e5f7ca9c33ad53b35f  # 10:20  G    902     0  902 1212  Merge branch 'irq-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 857fa628bbe93017c72ddd0d5304962a2608db07  # 10:49  G    906     0  906 1220  Merge git://git.kernel.org/pub/scm/linux/kernel/git/davem/net
git bisect  bad a234c7371fd8a71faa000745ce6ffee05e1dc07a  # 10:49  B    182     4    0 126  Merge tag 'dmaengine-fix-4.20-rc5' of git://git.infradead.org/users/vkoul/slave-dma
git bisect  bad 570a37437cf24790d77fed6a59fdc9ac749e6b19  # 10:49  B    198     2    0 110  Merge tag 'drm-fixes-2018-11-30' of git://anongit.freedesktop.org/drm/drm
git bisect  bad 5b26f7180cdb3406404e6da78741e4e57f9a999e  # 10:57  B    372     1   52 193  Merge tag 'spi-fix-v4.20-rc4' of git://git.kernel.org/pub/scm/linux/kernel/git/broonie/spi
git bisect  bad 17c2f540863a6c0faa3f0ede3c785d9427bcaf80  # 11:33  B     82     1   40  40  Merge tag 'nfs-for-4.20-4' of git://git.linux-nfs.org/projects/trondmy/linux-nfs
git bisect good e195ca6cb6f21633e56322d5aa11ed59cdb22fb2  # 12:23  G    906     0  906 912  Merge branch 'for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/hid/hid
git bisect  bad e2125dac22f2c9c66c412cd8e049a7305af59f73  # 12:53  B     42     1    8   8  Merge tag 'xarray-4.20-rc4' of git://git.infradead.org/users/willy/linux-dax
git bisect good 804dfaf01bcc9daa4298c608ba9018abf616ec48  # 14:55  G    905     0  905 913  XArray: Fix Documentation
git bisect  bad c5bbd4515a05f8acb7e6ab6297044a529762cbf5  # 15:32  B    226     1   66  66  dax: Reinstate RCU protection of inode
git bisect  bad 5404a7f1c21cfda061712bedf2d06cc0f6c755e9  # 16:17  B    395     1  111 111  XArray tests: Correct some 64-bit assumptions
git bisect good 44a4a66b619a0a83a52e707ebcd80182207bd50e  # 17:25  G    905     0  905 911  XArray: Correct xa_store_range
# first bad commit: [5404a7f1c21cfda061712bedf2d06cc0f6c755e9] XArray tests: Correct some 64-bit assumptions
git bisect good 44a4a66b619a0a83a52e707ebcd80182207bd50e  # 17:49  G   1000     0  1000 1913  XArray: Correct xa_store_range
# extra tests with debug options
git bisect  bad 5404a7f1c21cfda061712bedf2d06cc0f6c755e9  # 18:31  B    349     1  160 160  XArray tests: Correct some 64-bit assumptions
# extra tests on HEAD of linux-devel/devel-hourly-2018121621
git bisect  bad 367686392a463e7c8bbc583454db06fd195d2d06  # 18:31  B    223     2    0 106  0day head guard for 'devel-hourly-2018121621'
# extra tests on tree/branch linus/master
git bisect  bad 7566ec393f4161572ba6f11ad5171fd5d59b0fbd  # 19:07  B    448     1  100 100  Linux 4.20-rc7
# extra tests with first bad commit reverted
git bisect good 03b0716281e85f398c4729fbbef9f0672d05d7c5  # 20:35  G    908     0  908 908  Revert "XArray tests: Correct some 64-bit assumptions"
# extra tests on tree/branch linux-next/master
git bisect good d14b746c6c1ca310f679ef13f661587454e2c588  # 21:37  G    903     0  308 308  Add linux-next specific files for 20181214

---
0-DAY kernel test infrastructure                Open Source Technology Center
https://lists.01.org/pipermail/lkp                          Intel Corporation

Download attachment "dmesg-yocto-vm-yocto-319:20181217162724:i386-randconfig-sb0-12170053:4.20.0-rc1-00012-g5404a7f:1.gz" of type "application/gzip" (10366 bytes)

Download attachment "dmesg-vm-snb-openwrt-i386-23:20181217164630:i386-randconfig-sb0-12170053:4.20.0-rc1-00011-g44a4a66:2.gz" of type "application/gzip" (11070 bytes)

View attachment "reproduce-yocto-vm-yocto-319:20181217162724:i386-randconfig-sb0-12170053:4.20.0-rc1-00012-g5404a7f:1" of type "text/plain" (920 bytes)

View attachment "config-4.20.0-rc1-00012-g5404a7f" of type "text/plain" (122018 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ