From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1753211AbcIAVLq (ORCPT ); Thu, 1 Sep 2016 17:11:46 -0400 Received: from mx0a-00082601.pphosted.com ([67.231.145.42]:56528 "EHLO mx0a-00082601.pphosted.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1752196AbcIAVLg (ORCPT ); Thu, 1 Sep 2016 17:11:36 -0400 Subject: Re: [writeback] 8bc4ad9498: INFO: suspicious RCU usage. ] To: kernel test robot References: <57c8012a.zlFYe5dba5wTaKD+%xiaolong.ye@intel.com> CC: , Jens Axboe , LKML , Tejun Heo From: Jens Axboe Message-ID: <25429877-af98-30b2-1980-6686a7b7b78f@fb.com> Date: Thu, 1 Sep 2016 14:13:34 -0600 User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:45.0) Gecko/20100101 Thunderbird/45.2.0 MIME-Version: 1.0 In-Reply-To: <57c8012a.zlFYe5dba5wTaKD+%xiaolong.ye@intel.com> Content-Type: text/plain; charset="windows-1252"; format=flowed Content-Transfer-Encoding: 7bit X-Originating-IP: [216.160.245.98] X-ClientProxiedBy: CO1PR16CA0004.namprd16.prod.outlook.com (10.166.27.14) To BN6PR15MB1185.namprd15.prod.outlook.com (10.172.205.139) X-MS-Office365-Filtering-Correlation-Id: afb83b0b-4740-4edd-a59f-08d3d2a47810 X-Microsoft-Exchange-Diagnostics: 1;BN6PR15MB1185;2:qO1jfsikJersxcuqBacZedkpbC9FyAECcOMVXDSjRgDt5hwxs3g1IaUiWTSegPxUU6CTFDKMUw+2toqK+JD5pnLHvw1CjWAK2CCyuvxGw3nuZANTGOy9JyqbwbeOdOi6/c7zthL7XiZ+yStP5DeS15GiFk3YlYjG3yU1DkurDdbyGdhvQKVzcYmUclPgTcVY;3:dkRCcisuRDcagEKhhav9ZI1joFrJqTlvIpXAYF6U8ykxx4hC0FVbVQedHb3p8Czbbk8EBxOBHu5Mt/R6kN8G5HYNJW6Yn2oDgoeGMoHXSv+UsGPQNl8QkCujqBa2/BTo;25:WB5qbJvMcJeTMVNCUa9/ZdlVhm1jDTBAqCpNU802ZuOTGSB6fy7OQ/N2aZaTlwT9F7OB3p7fcwTz1d0EGQAai/NRd5Idfz2Ee0BDDEAD8dV0OAd+qpAlDGZsOOHJ7QPTEc243iGlOMNz+N47kbszNxO+Rj706CUM7WugBoWw54ksTlPOkqlstVhAWXFlC8wyQ9B+lupVD8B5k46iA26QX49o0Szvn9r5/Xk+PBqvOwx+uDADrCaeiJ2J6hXIDUGJOncE6mZ0fHvc2cfs92doxqjUAmgVToBbR8Sn97wWgS7JMCsNgOa9hga5tQqheljhiTQUOyyJVwFH1kK7mO0qpghk7DAvDjDZ1N9pckAghtKZGLOrl402v/a4LRlofOeDK88zksvmdIiNNGiUi/L4pCojXj9uBDURXpf4G8vm51o= X-Microsoft-Antispam: UriScan:;BCL:0;PCL:0;RULEID:;SRVR:BN6PR15MB1185; X-Microsoft-Exchange-Diagnostics: 1;BN6PR15MB1185;31:vnUhVnsc/I5blcC8msW3Hw57JykkgNBHFs5iKLUFw1g2oJDrwlxqbxaYns1/dQdIE5WaH3KWhxHTKoB+hx08qJxdth5Y/Rr+fYAt2DUC2CsmneGhQmmIr1u7LTF1rsOOQDWgI/hqhdj+vIqCaPfaIL0wZUY6HcpSkzUTBGzGpAUFVf/rdwIIkBxvtaiGSSai+XbZCWpjaBHARJznclsmnQpF13X38kD283jeqR8ynag=;20:Goz7EZUcaXRwxJHS937E+cspzM+1lwasWyx3/Ihr5l181vn7oM7Zx79t1Rlh7WCvzkrDfCqAjfg3WcGdMcdvQcL7PK4JWDN6aa41qU8Gs4nBGgS9g63QRaZcMYPl5ZJ8A05kS+CUQd15Nk4W7Ct7Inl7UXa8I3+2eRPMgSI2GZg=;4:yKQVG3q8XAL0x6WfXqHzYi77f/w8bJ58KfhFzKOHSjADWt87Y1MwXG+I7yoehv+H8t+FzD4Z2Dl8VkhxMU6WRK+Cz7k3364NDzXJHuEiM0OwykBwHYK2MnrvX+fxomL9NZSXnx1PEBOr3kicchfoKiKIXUON96KGXrVoms/JK84oCjctfWRMtMgHAw9VVHDoFnQkDgXYLd5V/iKTUjDdRVK53sLViHARhRfB3r1bJbd9sCC+E/QGTFpIJHjFJAr8Sy839XYKKi4ftEutc4fDOaXSAi9HpKtvtb2S3fFOo4/qWOf0+0ya9d6YZcoYTiNi4RVnq2zqqeEpr+/VouutaMVEKaNaE9RJum/PKUbxPgcBLC6joLWbCD3bNzoRGRvlOR4QH5bkRb4oGnU6skYqjHVwlxk4vLyJjmpTRb5M8ljoqmf3x1HWByx90joEpiaU X-Microsoft-Antispam-PRVS: X-Exchange-Antispam-Report-Test: UriScan:(84791874153150); X-Exchange-Antispam-Report-CFA-Test: BCL:0;PCL:0;RULEID:(6040176)(601004)(2401047)(5005006)(8121501046)(10201501046)(3002001);SRVR:BN6PR15MB1185;BCL:0;PCL:0;RULEID:;SRVR:BN6PR15MB1185; X-Forefront-PRVS: 0052308DC6 X-Forefront-Antispam-Report: SFV:NSPM;SFS:(10019020)(4630300001)(6049001)(6009001)(7916002)(69234005)(189002)(24454002)(199003)(377454003)(51344004)(51234002)(86362001)(4326007)(50466002)(33646002)(36756003)(81156014)(110136002)(64126003)(19580405001)(3846002)(6116002)(575784001)(2906002)(65806001)(66066001)(117156001)(81166006)(65956001)(47776003)(105586002)(65826007)(92566002)(8676002)(50986999)(54356999)(76176999)(19580395003)(97736004)(31696002)(15975445007)(189998001)(23746002)(106356001)(42186005)(77096005)(31686004)(83506001)(2950100001)(5660300001)(230700001)(68736007)(305945005)(586003)(7736002)(7846002)(101416001)(4001350100001);DIR:OUT;SFP:1102;SCL:1;SRVR:BN6PR15MB1185;H:[192.168.1.153];FPR:;SPF:None;PTR:InfoNoRecords;MX:1;A:1;LANG:en; X-Microsoft-Exchange-Diagnostics: =?Windows-1252?Q?1;BN6PR15MB1185;23:PTjYULOa0Y3Y75OdE62MaNazZ+iIzsIHOC1yR?= =?Windows-1252?Q?82kj8IL57TBIeZQHpuJA/O0KBUPWyjISDc6AgtYuTkl/w0V5Fu+j/nli?= =?Windows-1252?Q?IvwVco5qY4U/qo+94Mbv/NNmwwLYIEq3dCBFeFKbTTPO1lI57eCz38Xm?= =?Windows-1252?Q?pPmIkMv+BI06TNJJ3gbbcWtZju3cDcO1p3+k2gIUjHzZID51brQ3D/In?= =?Windows-1252?Q?ntM5hP28Pp/cs8tdmVDnH7UyymwNQ5zd0yRkrQ1OhHA0TmzdQf4R+u8m?= =?Windows-1252?Q?jEs7MlMDW5KyTprexXe6xAI5MvOKMAocuy/fqbdSmchTxn4UVyah92Si?= =?Windows-1252?Q?E7WIH+QShufOe8stkVIAWn7GHVFl6gNqOky2UF61TeRln43YZfQbUmeo?= =?Windows-1252?Q?k35+tnD7T253m4DIU1IaCWtcJC3JdADL3xL98kNhrdupOvvrQU5Lrgj8?= =?Windows-1252?Q?OJF11MiPE5Ex2fEEmBjAdREjEFsXyzgrHsIxVHn7n7lxRBBhZkBcUrLA?= =?Windows-1252?Q?o3QVmK9ANVgi9KBETjXC3LZXtbRYdbe0NJzDUc8gZ+lMXJCE48PrX052?= =?Windows-1252?Q?gJ33LLk0kDwsN7XnnYr1LcukmvnYgAe+Fmbv1jvH4/167QwkYRhcljL4?= =?Windows-1252?Q?DUOvoBTWp1J7hd4umYjA9j8PhrG3GYt9b7SKT/Hbx4ZusOzuCYRPE2P6?= =?Windows-1252?Q?DUJxG//JLNtMWwQSg5clge+3BAl/cpxdj4gZXm3VM/XT/xxh02akkckD?= =?Windows-1252?Q?VQlJYocVSIbdwb+FQVixxbl/syfO/ORLmKloSNLThU6IB5U5oYVRqCXw?= =?Windows-1252?Q?hXx0i7vxsdAvQuq4YnX/XlipQRXo44VI+elPV9XvsAKVfIg8DUJfqLs/?= =?Windows-1252?Q?jl63fMNPwAqtW0MclsVS1YX4BJAmVHU7DMqNigB8dS8RHh+4c+c/8sqW?= =?Windows-1252?Q?g8jS+ZmNffgqSfI3oWZrMmQ8HVU3Zmy6Q3nXc1Xa5/onzP+OweSG07uD?= =?Windows-1252?Q?kSlwIBO70UYti1cbr8rk/iYvkM7McaoH2Up6uKZSisA4+InbrTFxwFIJ?= =?Windows-1252?Q?CA4jY9614YnkkQygg3Ug4hVwOHMj1Z+WP/oGmlctEh55U7TLDGC1nz0U?= =?Windows-1252?Q?dbcglOt1iwziWsSdVgv/1oD3d9iKYpCbXiZdZMI+aLBHZJGXzVqOndrv?= =?Windows-1252?Q?8gLXML2ReGyDrFhQhwD9jP1VIA3UQ0lw8J0h0O38UtYw/VLHCtbWv12N?= =?Windows-1252?Q?SC2ISANdSSn+1xAB2GUzKLWcXzrnix+ZwOMNlqa4Y9P5aiOHV5TMCUac?= =?Windows-1252?Q?cneKoF7nQv1TDdruRzqzpgC+Fnwfju8n8FHAsgzAbtSXg3a8tw2LUlc3?= =?Windows-1252?Q?P3Bd0uqQrfDzR5EqwIDBvwLKoyiIq9dgDYkv427gU3fmM6SYGDCOl6Jd?= =?Windows-1252?Q?XoAyD9U28pxlSWU7hQ5GbzMwnV52xRZy4CdQlB02nforzvmaronjzY/i?= =?Windows-1252?Q?AhRvgIrQrAN7HnSasRS1Sc8viaMOAaXF8BqCUCkdXp4VCBxeq7wlzM2R?= =?Windows-1252?Q?orHFX4qUIHcnHc=3D?= X-Microsoft-Exchange-Diagnostics: 1;BN6PR15MB1185;6:I73m65B6L42MV+K88LcSrlW+TWT07EZlm/AaUvu8iGL4rbm8KJf3aEPrgE7LEnZOln/vNek+z9zFonybClgQoUPZGh6XHskCodhYvELyd1R9ajr/zkhqaCw3FvPMKFPBNLow8U+wmYqkfmhLtrHzin7q4pSeHauYX5ldkEiC1kIbPjOYIQVT+/D5M5kyqiZyLX1u683CCwW9X8Y4hkhE7x3zE+epG3Mg33KxohtYwquCx8GbG9hAuVONrOLyH6Dz9LVyqkGOL5G911Y+HZ39n+ivrljcColpCRxfg7hndVA=;5:MFzzPg/qbUu4frj7vbYymkXsjlNCDjRc4UOWgbthI8t2LfdP5OIAb9TgkO7cG14Gyw3DsdLKbKzlQEVlWqZswNg8mjObIjOh3iD/9VTQ+0mNBYmD0xfwdSBkfk4zUMsdQ+hAyRQ7FT0hDE4/ocoHug==;24:y0GFPG7qIng601YnaKVQnp9bbRzBB9wCWm8MjnxzZBQ5z/cXTdJW/LVr3oWZOevBj3Th077FIKXuwUl87kzmIyARzYvdvddWy6LS+6suqb4=;7:RsW1huzB2JlFgko9KHtTNP6QjFE7uqviGMwt8kP2aPyde+d/WoZgMUAlHM19jXtfuCLwtdjGGXmRp22vQQis5afcnQBARsBDhencg/cgUluv8VSePaudq/KpToB4I6ENQn/sm+EaPPnyh/EwHAmxHDUawoJw0whqEW+zgt6IPmlaKWuMyrHptaR0Oq9uVk7SlRKINPY7Mb3yPirLDRx8AlYaUcLqTxmMGmnKzS54IexUc8Uf8UynZVjIAnMPypBd SpamDiagnosticOutput: 1:99 SpamDiagnosticMetadata: NSPM X-Microsoft-Exchange-Diagnostics: 1;BN6PR15MB1185;20:NdloUlb+CVtnfCEj+Wpq/YnPHXH9pQu/V3A+GOV3lmdhj/iPf+wPXb03aoa9eKYMU+OOt0VvV5nolDwWFVFZzeIVjcy3+0DNxqCfbkORTX/uZ3s2BIY2D3d5TTzL3PHStk1YM22F2xldSiEP1yiwhckb00azBjZjLdTr9WXPyYk= X-MS-Exchange-CrossTenant-OriginalArrivalTime: 01 Sep 2016 20:13:40.9679 (UTC) X-MS-Exchange-CrossTenant-FromEntityHeader: Hosted X-MS-Exchange-Transport-CrossTenantHeadersStamped: BN6PR15MB1185 X-OriginatorOrg: fb.com X-Proofpoint-Spam-Reason: safe X-FB-Internal: Safe X-Proofpoint-Virus-Version: vendor=fsecure engine=2.50.10432:,, definitions=2016-09-01_08:,, signatures=0 Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On 09/01/2016 04:21 AM, kernel test robot wrote: > > FYI, we noticed the following commit: > > https://git.kernel.org/pub/scm/linux/kernel/git/axboe/linux-block.git wb-buf-throttle > commit 8bc4ad9498f81d6689da42457615db0989e720d3 ("writeback: throttle buffered writeback") > > in testcase: boot > > on test machine: qemu-system-x86_64 -enable-kvm -cpu Westmere -m 512M > > caused below changes: > > > +--------------------------------------+------------+------------+ > | | b2e4e4e34d | 8bc4ad9498 | > +--------------------------------------+------------+------------+ > | boot_successes | 6 | 0 | > | boot_failures | 0 | 8 | > | INFO:suspicious_RCU_usage | 0 | 8 | > | message:INFO:suspicious_RCU_usage | 0 | 0.0 | > | pattern:INFO:suspicious_RCU_usage | 0 | 0 | > | calltrace:disk_events_workfn | 0 | 8 | > | message:calltrace:disk_events_workfn | 0 | 0.0 | > | pattern:calltrace:disk_events_workfn | 0 | 0 | > +--------------------------------------+------------+------------+ > > > > [ 7.323356] cdrom: Uniform CD-ROM driver Revision: 3.20 > [ 7.334239] > [ 7.337256] =============================== > [ 7.340532] [ INFO: suspicious RCU usage. ] > [ 7.342419] 4.8.0-rc4-00008-g8bc4ad9 #1 Not tainted > [ 7.347065] ------------------------------- > [ 7.350132] include/linux/cgroup.h:435 suspicious rcu_dereference_check() usage! > [ 7.355928] > [ 7.355928] other info that might help us debug this: > [ 7.355928] > [ 7.360548] > [ 7.360548] rcu_scheduler_active = 1, debug_locks = 0 > [ 7.366185] 3 locks held by kworker/0:1/19: > [ 7.369225] #0: ("events_freezable_power_efficient"){.+.+..}, at: [] process_one_work+0x151/0x4ee > [ 7.377225] #1: ((&(&ev->dwork)->work)){+.+...}, at: [] process_one_work+0x151/0x4ee > [ 7.384175] #2: (&(&q->__queue_lock)->rlock){-.....}, at: [] cfq_set_request+0x5e/0x2a3 > [ 7.389264] > [ 7.389264] stack backtrace: > [ 7.391300] CPU: 0 PID: 19 Comm: kworker/0:1 Not tainted 4.8.0-rc4-00008-g8bc4ad9 #1 > [ 7.395279] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014 > [ 7.398012] Workqueue: events_freezable_power_ disk_events_workfn > [ 7.399933] 0000000000000000 ffff88003db1f8e8 ffffffff8178ed3b ffff88003db184c0 > [ 7.404348] 0000000000000001 ffff88003db1f918 ffffffff81123472 ffff88003db184c0 > [ 7.407158] ffffffff84091180 0000000000000003 ffff880039d4c400 ffff88003db1f938 > [ 7.410074] Call Trace: > [ 7.411328] [] dump_stack+0x82/0xb8 > [ 7.413982] [] lockdep_rcu_suspicious+0xf7/0x100 > [ 7.415828] [] bio_blkcg+0x89/0x93 > [ 7.417336] [] check_blkcg_changed+0x58/0x1b8 > [ 7.419027] [] ? lock_acquire+0x125/0x1be > [ 7.421418] [] ? lock_acquire+0x125/0x1be > [ 7.424049] [] ? lock_acquired+0xfc/0x2f2 > [ 7.426893] [] ? cfq_set_request+0x5e/0x2a3 > [ 7.428722] [] cfq_set_request+0xd1/0x2a3 > [ 7.430315] [] ? _raw_spin_unlock_irq+0x2c/0x3b > [ 7.432134] [] ? trace_hardirqs_on_caller+0x17c/0x198 > [ 7.434083] [] ? trace_hardirqs_on+0xd/0xf > [ 7.435703] [] ? _raw_spin_unlock_irq+0x31/0x3b > [ 7.437472] [] ? ioc_create_icq+0x15e/0x175 > [ 7.439690] [] elv_set_request+0x1f/0x24 > [ 7.442157] [] get_request+0x38f/0x77f > [ 7.444694] [] ? add_wait_queue_exclusive+0x44/0x44 > [ 7.447449] [] blk_get_request+0x65/0xa8 > [ 7.449868] [] ide_cd_queue_pc+0x76/0x19d > [ 7.451986] [] ? __lock_acquire+0x679/0xed1 > [ 7.453757] [] cdrom_check_status+0x51/0x53 > [ 7.455372] [] ide_cdrom_check_events_real+0x20/0x3f > [ 7.457294] [] cdrom_update_events+0x18/0x21 > [ 7.458987] [] cdrom_check_events+0x12/0x1f > [ 7.460713] [] idecd_check_events+0x1c/0x1e > [ 7.462393] [] disk_check_events+0x47/0x103 > [ 7.464129] [] disk_events_workfn+0x1c/0x1e > [ 7.465844] [] process_one_work+0x272/0x4ee > [ 7.467462] [] worker_thread+0x1eb/0x2c9 > [ 7.469137] [] ? rescuer_thread+0x2b3/0x2b3 > [ 7.471338] [] kthread+0xc5/0xcd > [ 7.472978] [] ret_from_fork+0x1f/0x40 > [ 7.474501] [] ? kthread_create_on_node+0x1b0/0x1b0 > [ 7.477061] Loading iSCSI transport class v2.0-870. > [ 7.479285] rdac: device handler registered Pretty sure this has nothing to do with the writeback code, it's just triggering and now blaming one of those commits since they touch the same function. The RCU usage in there dates back to: commit 598971bfbdfdc8701337dc1636c7919c44699914 Author: Tejun Heo Date: Mon Mar 19 15:10:58 2012 -0700 cfq: don't use icq_get_changed() Tejun, see what's up there, if anything? -- Jens Axboe