Received: by 2002:a05:6a10:1287:0:0:0:0 with SMTP id d7csp3188360pxv; Sun, 18 Jul 2021 14:11:52 -0700 (PDT) X-Google-Smtp-Source: ABdhPJxnyXcnDNJV1xoAFzZxzofwpmhFgZLczE7A6ErvGGMjX7vj5N9QB8dQJN6lt0zh0VWRqZ4P X-Received: by 2002:a05:6402:17d3:: with SMTP id s19mr30267817edy.49.1626642712622; Sun, 18 Jul 2021 14:11:52 -0700 (PDT) ARC-Seal: i=1; a=rsa-sha256; t=1626642712; cv=none; d=google.com; s=arc-20160816; b=G37tPXhjEXAbYos6cdEwGfGWLKhatOL/HodtxSaqT/LMU6SDvUZ+3E4cFIcGWi0fc9 jHPkZT0zT2rQLlhSMc/sq/dcGMbqjWh61DENM2SHAYAjgVPh6IidtQNONLu/yqiEOwcg nHlTIca8TOMslJj0QHDtc+145NytYBDGAF1810eKZy276c+7AE+x8DAgZi6+NSxp6dt/ tOC+cJhIV72oSUlBJQOM1do6AoWFTD2OtLL2pjSK9Hj7JBeCa3N7M0I+dvwS5U3xVTlI Hkk3rgv8G9/p6ROIVIXYJxURF4pMKieWs6uOhuPqXzj6iNvYthLnwN6GzSFqI7TPtPbr /h6g== ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=google.com; s=arc-20160816; h=list-id:precedence:content-transfer-encoding:mime-version :references:in-reply-to:message-id:date:subject:cc:to:from :dkim-signature; bh=PmKqY7ljEwhU9tG2sg3FmGqcAXo9p4TrmGzCfCaOkmo=; b=dX1Sc+DtWLwEv1TLZJ/2nRFu2E+4RBqKXLrRxOxjRaip1JU2fvFXG3MeFy0XcMc38z xkRxUnC70ljedCAUoL8SeKyRfaLQ2pmR+YxiNXxRk/DllYdgnmDMQ01CppnMeH7Bv6Wl t9VGeovvkWtD+0HX6u7/aZD4m2KtPiD3F/B6LHtJttUA2ywSizgtZLnzvnIWqY3Pu1w/ u1tGphmLutAMErRCs/IzhMvbZUPYfiKXf+bnuAM4HIE4mQ6fougAK9x8vCGiJdmyOr2c fI1DbhRXQGXY1Nr0pTIu9GE7Vs/uu6Y0RdzB6eW9suCTC3dq9VVfa8PX39l2b4wUWJdb Ffcg== ARC-Authentication-Results: i=1; mx.google.com; dkim=pass header.i=@natalenko.name header.s=dkim-20170712 header.b=N7Rhu2B6; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=REJECT sp=REJECT dis=NONE) header.from=natalenko.name Return-Path: Received: from vger.kernel.org (vger.kernel.org. [23.128.96.18]) by mx.google.com with ESMTP id rs15si3136281ejb.282.2021.07.18.14.11.29; Sun, 18 Jul 2021 14:11:52 -0700 (PDT) Received-SPF: pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) client-ip=23.128.96.18; Authentication-Results: mx.google.com; dkim=pass header.i=@natalenko.name header.s=dkim-20170712 header.b=N7Rhu2B6; spf=pass (google.com: domain of linux-kernel-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) smtp.mailfrom=linux-kernel-owner@vger.kernel.org; dmarc=pass (p=REJECT sp=REJECT dis=NONE) header.from=natalenko.name Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S232621AbhGRVL4 (ORCPT + 99 others); Sun, 18 Jul 2021 17:11:56 -0400 Received: from lindbergh.monkeyblade.net ([23.128.96.19]:53262 "EHLO lindbergh.monkeyblade.net" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S229697AbhGRVLz (ORCPT ); Sun, 18 Jul 2021 17:11:55 -0400 Received: from vulcan.natalenko.name (vulcan.natalenko.name [IPv6:2001:19f0:6c00:8846:5400:ff:fe0c:dfa0]) by lindbergh.monkeyblade.net (Postfix) with ESMTPS id D80B7C061762; Sun, 18 Jul 2021 14:08:56 -0700 (PDT) Received: from spock.localnet (unknown [151.237.229.131]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (2048 bits) server-digest SHA256) (No client certificate requested) by vulcan.natalenko.name (Postfix) with ESMTPSA id 69505B3DDF6; Sun, 18 Jul 2021 23:08:55 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=natalenko.name; s=dkim-20170712; t=1626642535; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:cc:mime-version:mime-version:content-type:content-type: content-transfer-encoding:content-transfer-encoding: in-reply-to:in-reply-to:references:references; bh=PmKqY7ljEwhU9tG2sg3FmGqcAXo9p4TrmGzCfCaOkmo=; b=N7Rhu2B69FzO0NLipg7lyUqp8cTMLnGpTpgUM7/9xUF037OpL1+gtHp1zQdLlQt05nOkmF v1EhqH7IQxDTje05166IzfUc1VfoY+vroiWluQx5DjS115se1ZIkMeaxXSh51pxb2GfzFD Rb9Dvry9GmDgetFh/KGuYc7WjWOUpi8= From: Oleksandr Natalenko To: linux-kernel@vger.kernel.org Cc: jim.cromie@gmail.com, Paolo Valente , Jens Axboe , linux-block@vger.kernel.org Subject: Re: 5.14.0-rc1 KASAN use after free Date: Sun, 18 Jul 2021 23:08:52 +0200 Message-ID: <8057650.rSI8SBESIY@natalenko.name> In-Reply-To: References: MIME-Version: 1.0 Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset="UTF-8" Precedence: bulk List-ID: X-Mailing-List: linux-kernel@vger.kernel.org + Paolo, Jens et al. On =C4=8Dtvrtek 15. =C4=8Dervence 2021 16:32:29 CEST jim.cromie@gmail.com w= rote: > hi all, >=20 > I noticed this report this morning, from 3 days ago, > about 10 minutes after boot. > Its easiest to ignore it, and I dont want to make a fuss, > but it looks useful to someone >=20 >=20 > [ 33.663464] Bluetooth: RFCOMM ver 1.11 > [ 646.343628] > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D [=20 > 646.343649] BUG: KASAN: use-after-free in bfq_get_queue+0x47d/0x900 [=20 > 646.343680] Read of size 8 at addr ffff88810d864a00 by task > journal-offline/1639 >=20 > [ 646.343708] CPU: 2 PID: 1639 Comm: journal-offline Not tainted > 5.14.0-rc1-lm1 #66 > [ 646.343730] Hardware name: TOSHIBA Satellite L55-C/06F4 > , BIOS 1.20 10/08/2015 > [ 646.343745] Call Trace: > [ 646.343757] dump_stack_lvl+0x46/0x5a > [ 646.343781] print_address_description.constprop.0+0x1f/0x140 > [ 646.343808] ? bfq_get_queue+0x47d/0x900 > [ 646.343829] kasan_report.cold+0x7f/0x11b > [ 646.343854] ? bfq_init_bfqq+0x2a0/0x330 > [ 646.343873] ? bfq_get_queue+0x47d/0x900 > [ 646.343895] bfq_get_queue+0x47d/0x900 > [ 646.343918] ? bfq_merge_bfqqs+0x7a0/0x7a0 > [ 646.343939] ? _raw_write_unlock_bh+0x30/0x30 > [ 646.343965] bfq_get_bfqq_handle_split+0xa1/0x240 > [ 646.343991] bfq_init_rq+0x1e0/0x15e0 > [ 646.344013] ? submit_bio_noacct+0x7f0/0x7f0 > [ 646.344036] ? percpu_counter_add_batch+0x1f/0x90 > [ 646.344059] ? bfq_get_bfqq_handle_split+0x240/0x240 > [ 646.344082] ? btrfs_map_bio+0x404/0x830 > [ 646.344100] ? elv_rqhash_find+0x42/0x180 > [ 646.344121] ? _raw_spin_lock_irq+0x71/0xb0 > [ 646.344142] ? _raw_write_lock_irq+0xb0/0xb0 > [ 646.344164] bfq_insert_requests+0xe2/0x2a10 > [ 646.344190] ? btrfs_submit_data_bio+0x186/0x340 > [ 646.344215] ? submit_one_bio+0x81/0xc0 > [ 646.344235] ? bfq_request_merged+0x110/0x110 > [ 646.344256] ? blk_status_to_errno+0x20/0x30 > [ 646.344279] ? extent_write_locked_range+0x360/0x360 > [ 646.344302] ? btrfs_use_block_rsv+0x320/0x320 > [ 646.344324] blk_mq_sched_insert_requests+0xa6/0x1a0 > [ 646.344347] blk_mq_flush_plug_list+0x1fa/0x2f0 > [ 646.344374] ? blk_mq_insert_requests+0x1a0/0x1a0 > [ 646.344399] ? __filemap_fdatawrite_range+0x176/0x1c0 > [ 646.344423] blk_flush_plug_list+0x1d4/0x200 > [ 646.344449] ? blk_insert_cloned_request+0x170/0x170 > [ 646.344476] blk_finish_plug+0x3c/0x60 > [ 646.344500] start_ordered_ops.constprop.0+0xc3/0xf0 > [ 646.344525] ? btrfs_file_write_iter+0x5b0/0x5b0 > [ 646.344554] btrfs_sync_file+0x135/0x880 > [ 646.344578] ? rwsem_mark_wake+0x460/0x460 > [ 646.344601] ? start_ordered_ops.constprop.0+0xf0/0xf0 > [ 646.344628] ? vfs_fsync_range+0x86/0x100 > [ 646.344650] __x64_sys_fsync+0x3f/0x70 > [ 646.344672] do_syscall_64+0x3b/0x90 > [ 646.344696] entry_SYSCALL_64_after_hwframe+0x44/0xae > [ 646.344720] RIP: 0033:0x7f6eedeecebb > [ 646.344738] Code: 4a 00 00 00 0f 05 48 3d 00 f0 ff ff 77 41 c3 48 > 83 ec 18 89 7c 24 0c e8 53 f7 ff ff 8b 7c 24 0c 41 89 c0 b8 4a 00 00 > 00 0f 05 <48> 3d 00 f0 ff ff 77 35 44 89 c7 89 44 24 0c e8 b1 f7 ff ff > 8b 44 > [ 646.344760] RSP: 002b:00007f6ee919ca10 EFLAGS: 00000293 ORIG_RAX: > 000000000000004a > [ 646.344784] RAX: ffffffffffffffda RBX: 00005614c6093620 RCX: > 00007f6eedeecebb [ 646.344801] RDX: 0000000000000002 RSI: 00007f6eee1cb9= e2 > RDI: 0000000000000017 [ 646.344815] RBP: 00007f6eee1cd610 R08: > 0000000000000000 R09: 00007f6ee919d640 [ 646.344830] R10: 00000000000000= 02 > R11: 0000000000000293 R12: 0000000000000002 [ 646.344843] R13: > 00007ffec2aede3f R14: 0000000000000000 R15: 00007f6ee919d640 >=20 > [ 646.344874] Allocated by task 1626: > [ 646.344886] kasan_save_stack+0x1b/0x40 > [ 646.344906] __kasan_slab_alloc+0x61/0x80 > [ 646.344924] kmem_cache_alloc_node+0x151/0x2d0 > [ 646.344942] bfq_get_queue+0x209/0x900 > [ 646.344961] bfq_get_bfqq_handle_split+0xa1/0x240 > [ 646.344982] bfq_init_rq+0x1e0/0x15e0 > [ 646.345001] bfq_insert_requests+0xe2/0x2a10 > [ 646.345020] blk_mq_sched_insert_requests+0xa6/0x1a0 > [ 646.345039] blk_mq_flush_plug_list+0x1fa/0x2f0 > [ 646.345060] blk_flush_plug_list+0x1d4/0x200 > [ 646.345082] blk_finish_plug+0x3c/0x60 > [ 646.345103] start_ordered_ops.constprop.0+0xc3/0xf0 > [ 646.345124] btrfs_sync_file+0x135/0x880 > [ 646.345144] __x64_sys_fsync+0x3f/0x70 > [ 646.345163] do_syscall_64+0x3b/0x90 > [ 646.345183] entry_SYSCALL_64_after_hwframe+0x44/0xae >=20 > [ 646.345212] The buggy address belongs to the object at ffff88810d864810 > which belongs to the cache bfq_queue of size 560 > [ 646.345229] The buggy address is located 496 bytes inside of > 560-byte region [ffff88810d864810, ffff88810d864a40) > [ 646.345248] The buggy address belongs to the page: > [ 646.345258] page:0000000040e75441 refcount:1 mapcount:0 > mapping:0000000000000000 index:0xffff88810d864810 pfn:0x10d864 > [ 646.345280] head:0000000040e75441 order:2 compound_mapcount:0 > compound_pincount:0 > [ 646.345296] flags: > 0x17ffffc0010200(slab|head|node=3D0|zone=3D2|lastcpupid=3D0x1fffff) > [ 646.345326] raw: 0017ffffc0010200 0000000000000000 dead000000000122 > ffff88810145b400 > [ 646.345345] raw: ffff88810d864810 000000008017000f 00000001ffffffff > 0000000000000000 > [ 646.345357] page dumped because: kasan: bad access detected >=20 > [ 646.345375] Memory state around the buggy address: > [ 646.345387] ffff88810d864900: fb fb fb fb fb fb fb fb fb fb fb fb > fb fb fb fb > [ 646.345403] ffff88810d864980: fb fb fb fb fb fb fb fb fb fb fb fb > fb fb fb fb > [ 646.345417] >ffff88810d864a00: fb fb fb fb fb fb fb fb fc fc fc fc > fc fc fc fc > [ 646.345428] ^ > [ 646.345441] ffff88810d864a80: fc fc fc fc fc fc fc fc fb fb fb fb > fb fb fb fb > [ 646.345455] ffff88810d864b00: fb fb fb fb fb fb fb fb fb fb fb fb > fb fb fb fb > [ 646.345467] > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D [=20 > 646.345476] Disabling lock debugging due to kernel taint > [20632.749805] rfkill: input handler enabled > [20636.319701] rfkill: input handler disabled > [22201.638710] perf: interrupt took too long (2514 > 2500), lowering > kernel.perf_event_max_sample_rate to 79000 > [22539.009414] perf: interrupt took too long (3145 > 3142), lowering > kernel.perf_event_max_sample_rate to 63000 > [23091.879235] perf: interrupt took too long (3960 > 3931), lowering > kernel.perf_event_max_sample_rate to 50000 > [24206.193740] perf: interrupt took too long (4972 > 4950), lowering > kernel.perf_event_max_sample_rate to 40000 > [25306.264832] L1TF CPU bug present and SMT on, data leak possible. > See CVE-2018-3646 and > https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/l1tf.html > for details. > [38172.611967] nf_conntrack: default automatic helper assignment has > been turned off for security reasons and CT-based firewall rule not > found. Use the iptables CT target to attach helpers instead. > [123082.553154] psmouse serio2: bad data from KBC - timeout > [211716.941601] ------------[ cut here ]------------ > [211716.941610] cfs_rq->avg.load_avg || cfs_rq->avg.util_avg || > cfs_rq->avg.runnable_avg > [211716.941619] WARNING: CPU: 4 PID: 33 at kernel/sched/fair.c:3307 > update_blocked_averages+0xb7c/0xbf0 > [211716.941641] Modules linked in: uinput rfcomm xt_CHECKSUM > xt_MASQUERADE xt_conntrack ipt_REJECT nf_nat_tftp nf_conntrack_tftp > bridge stp llc ccm nft_objref nf_conntrack_netbios_ns > nf_conntrack_broadcast nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib > nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject nft_ct > nft_chain_nat ip6table_nat ip6table_mangle ip6table_raw > ip6table_security iptable_nat nf_nat nf_conntrack nf_defrag_ipv6 > nf_defrag_ipv4 iptable_mangle iptable_raw iptable_security ip_set > nf_tables nfnetlink ip6table_filter ip6_tables iptable_filter cmac > bnep sunrpc vfat iwlmvm fat snd_hda_codec_hdmi intel_rapl_msr mac80211 > snd_hda_codec_conexant at24 snd_hda_codec_generic ledtrig_audio > iTCO_wdt intel_pmc_bxt mei_hdcp iTCO_vendor_support libarc4 > intel_wmi_thunderbolt wmi_bmof uvcvideo btusb snd_hda_intel btrtl > btbcm snd_intel_dspcfg videobuf2_vmalloc videobuf2_memops btintel > videobuf2_v4l2 videobuf2_common intel_rapl_common snd_hda_codec > x86_pkg_temp_thermal iwlwifi > [211716.941813] intel_powerclamp videodev coretemp snd_hwdep rapl > snd_hda_core bluetooth intel_cstate snd_seq mc intel_uncore joydev > snd_seq_device ecdh_generic cfg80211 ecc pcspkr snd_pcm snd_timer > mei_me snd toshiba_acpi i2c_i801 mei sparse_keymap soundcore > industrialio i2c_smbus toshiba_bluetooth rfkill wmi acpi_pad ip_tables > rtsx_pci_sdmmc mmc_core crct10dif_pclmul crc32_pclmul i915 > crc32c_intel i2c_algo_bit ttm ghash_clmulni_intel serio_raw r8169 > drm_kms_helper cec rtsx_pci drm video fuse > [211716.941897] CPU: 4 PID: 33 Comm: ksoftirqd/4 Tainted: G B > 5.14.0-rc1-lm1 #66 > [211716.941903] Hardware name: TOSHIBA Satellite L55-C/06F4 > , BIOS 1.20 10/08/2015 > [211716.941907] RIP: 0010:update_blocked_averages+0xb7c/0xbf0 > [211716.941916] Code: c0 5e ab 90 c6 05 98 15 a7 02 01 e8 21 48 14 01 > 0f 0b e9 13 f6 ff ff 48 c7 c7 20 5f ab 90 c6 05 7a 15 a7 02 01 e8 07 > 48 14 01 <0f> 0b 48 8b 7c 24 20 e8 38 61 31 00 45 8b af 38 01 00 00 e9 > e3 f9 > [211716.941921] RSP: 0018:ffff888100fefc58 EFLAGS: 00010082 > [211716.941926] RAX: 0000000000000000 RBX: ffff88821bb334c0 RCX: > 0000000000000000 > [211716.941930] RDX: 0000000000000027 RSI: 0000000000000004 RDI: > ffffed10201fdf81 > [211716.941934] RBP: ffff88821bb32de0 R08: ffffffff8f3f445e R09: > ffff88821bb20a8b > [211716.941937] R10: ffffed1043764151 R11: 0000000000000001 R12: > ffffffff927d77c0 > [211716.941941] R13: 0000000000000001 R14: ffff88821bb33728 R15: > ffff88821bb32d40 > [211716.941945] FS: 0000000000000000(0000) GS:ffff88821bb00000(0000) > knlGS:0000000000000000 > [211716.941949] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 > [211716.941952] CR2: 0000556174b0d040 CR3: 0000000140414001 CR4: > 00000000003726e0 > [211716.941956] DR0: 0000000000000000 DR1: 0000000000000000 DR2: > 0000000000000000 > [211716.941959] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: > 0000000000000400 > [211716.941963] Call Trace: > [211716.941968] newidle_balance+0x34d/0x6b0 > [211716.941974] ? update_cfs_group+0x1e/0x150 > [211716.941980] ? load_balance+0x1290/0x1290 > [211716.941985] ? update_min_vruntime+0x44/0xc0 > [211716.941991] pick_next_task_fair+0x59/0x660 > [211716.941997] __schedule+0x225/0xeb0 > [211716.942005] ? io_schedule_timeout+0xb0/0xb0 > [211716.942011] ? __do_softirq+0x209/0x373 > [211716.942017] schedule+0x6d/0x120 > [211716.942023] smpboot_thread_fn+0x1b7/0x250 > [211716.942030] ? smpboot_register_percpu_thread+0x190/0x190 > [211716.942036] kthread+0x1d2/0x200 > [211716.942041] ? set_kthread_struct+0x80/0x80 > [211716.942046] ret_from_fork+0x22/0x30 > [211716.942055] ---[ end trace ee8f02e72a76fda7 ]--- > [256577.295169] show_signal_msg: 4 callbacks suppressed > [256577.295176] gnome-shell[2236]: segfault at 18 ip 00007f16ab0563ee > sp 00007ffcd6601570 error 4 in libgjs.so.0.0.0[7f16ab025000+92000] > [256577.295205] Code: ec 30 64 48 8b 04 25 28 00 00 00 48 89 44 24 28 > 31 c0 e8 05 32 00 00 48 89 c3 e8 8d 36 fd ff 48 89 c7 e8 c5 30 fd ff > 48 89 c5 <48> 8b 43 18 48 85 c0 0f 84 85 00 00 00 48 8b 50 18 48 8d 45 > 18 49 > [256588.051436] rfkill: input handler enabled =2D-=20 Oleksandr Natalenko (post-factum)