linux-kernel - [timer] 5acb72bc56: WARNING:at_kernel/time/timer.c:#schedule

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>] [day] [month] [year] [list]
Date:   Wed, 29 Jan 2020 21:06:56 +0800
From:   kernel test robot <lkp@...el.com>
To:     Alexander Popov <alex.popov@...ux.com>
Cc:     0day robot <lkp@...el.com>, LKML <linux-kernel@...r.kernel.org>,
        lkp@...ts.01.org
Subject: [timer] 5acb72bc56: WARNING:at_kernel/time/timer.c:#schedule_timeout

FYI, we noticed the following commit (built with gcc-7):

commit: 5acb72bc5642be30e358f43eaa010d06a6ea29e7 ("timer: Warn about schedule_timeout() called for tasks in TASK_RUNNING state")
https://github.com/0day-ci/linux/commits/Alexander-Popov/timer-Warn-about-schedule_timeout-called-for-tasks-in-TASK_RUNNING-state/20200117-180155

in testcase: blktests
with following parameters:

	disk: 1SSD
	test: block-group1



on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):


+--------------------------------------------------+------------+------------+
|                                                  | a67ca893ae | 5acb72bc56 |
+--------------------------------------------------+------------+------------+
| boot_successes                                   | 9          | 3          |
| boot_failures                                    | 0          | 9          |
| WARNING:at_kernel/time/timer.c:#schedule_timeout | 0          | 9          |
| RIP:schedule_timeout                             | 0          | 9          |
+--------------------------------------------------+------------+------------+


If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp@...el.com>


[    9.394278] WARNING: CPU: 1 PID: 1 at kernel/time/timer.c:1892 schedule_timeout+0x254/0x340
[    9.395601] Modules linked in: ip_tables
[    9.396169] CPU: 1 PID: 1 Comm: systemd Not tainted 5.5.0-rc5-00071-g5acb72bc5642b #1
[    9.397240] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    9.398362] RIP: 0010:schedule_timeout+0x254/0x340
[    9.399021] Code: 08 00 41 89 c6 e9 a4 fe ff ff 80 3d 41 7a d4 00 00 0f 85 15 fe ff ff 48 c7 c7 28 99 f3 8c c6 05 2d 7a d4 00 01 e8 9c 81 5e ff <0f> 0b e9 fb fd ff ff 4d 89 ec e9 ec fe ff ff e8 38 b9 61 ff 48 c7
[    9.400914] RSP: 0018:ffffa8fc80013d38 EFLAGS: 00010282
[    9.401492] RAX: 0000000000000000 RBX: 000000000000752f RCX: 0000000000000000
[    9.402222] RDX: 0000000000000001 RSI: ffff96be3fd19b38 RDI: ffff96be3fd19b38
[    9.402956] RBP: ffff96bdd0f58000 R08: 0000000000000258 R09: 0000000000000030
[    9.403687] R10: 000000000000077b R11: ffffa8fc80013bd8 R12: 0000000000000000
[    9.404421] R13: ffff96bdd0f58208 R14: ffff96be3fd38a20 R15: 0000000000000001
[    9.405163] FS:  00007f1abe5ec680(0000) GS:ffff96be3fd00000(0000) knlGS:0000000000000000
[    9.406057] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    9.406672] CR2: 0000563498ad1294 CR3: 00000001d0d52000 CR4: 00000000000406e0
[    9.407404] Call Trace:
[    9.407764]  ? prepare_to_wait_event+0x99/0x190
[    9.408287]  tty_wait_until_sent+0x157/0x1a0
[    9.408790]  ? finish_wait+0x80/0x80
[    9.409238]  tty_port_close_start+0x11e/0x1b0
[    9.409798]  tty_port_close+0x27/0x60
[    9.410242]  tty_release+0x124/0x600
[    9.410680]  __fput+0xd1/0x270
[    9.411079]  task_work_run+0xa3/0xe0
[    9.411525]  exit_to_usermode_loop+0xeb/0xf0
[    9.412022]  do_syscall_64+0x1c6/0x1f0
[    9.412475]  entry_SYSCALL_64_after_hwframe+0x44/0xa9
[    9.413045] RIP: 0033:0x7f1ac02325b1
[    9.413517] Code: 2d aa 00 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b3 0f 1f 80 00 00 00 00 8b 05 9a ee 00 00 85 c0 75 16 b8 03 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 3f c3 66 0f 1f 44 00 00 53 89 fb 48 83 ec 10
[    9.415390] RSP: 002b:00007ffed608ea98 EFLAGS: 00000246 ORIG_RAX: 0000000000000003
[    9.416243] RAX: 0000000000000000 RBX: 0000000000000029 RCX: 00007f1ac02325b1
[    9.416975] RDX: 0000000000000005 RSI: 00007ffed608eaf0 RDI: 0000000000000029
[    9.417718] RBP: 00007f1abe5ec460 R08: 000000000000fce0 R09: 0000563498acc700
[    9.418452] R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000000
[    9.419186] R13: 0000000000000005 R14: 0000000000000000 R15: 0000000000000030
[    9.419924] ---[ end trace 029e41b457dc5ff5 ]---


To reproduce:

        # build kernel
	cd linux
	cp config-5.5.0-rc5-00071-g5acb72bc5642b .config
	make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage

        git clone https://github.com/intel/lkp-tests.git
        cd lkp-tests
        bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email



Thanks,
lkp


View attachment "config-5.5.0-rc5-00071-g5acb72bc5642b" of type "text/plain" (202329 bytes)

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

Download attachment "dmesg.xz" of type "application/x-xz" (93916 bytes)

View attachment "blktests" of type "text/plain" (1808 bytes)