lkml.org 
[lkml]   [2021]   [Jul]   [18]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
    /
    From
    SubjectRe: 5.14.0-rc1 KASAN use after free
    Date
    + Paolo, Jens et al.

    On čtvrtek 15. července 2021 16:32:29 CEST jim.cromie@gmail.com wrote:
    > hi all,
    >
    > 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
    >
    >
    > [ 33.663464] Bluetooth: RFCOMM ver 1.11
    > [ 646.343628]
    > ================================================================== [
    > 646.343649] BUG: KASAN: use-after-free in bfq_get_queue+0x47d/0x900 [
    > 646.343680] Read of size 8 at addr ffff88810d864a00 by task
    > journal-offline/1639
    >
    > [ 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: 00007f6eee1cb9e2
    > RDI: 0000000000000017 [ 646.344815] RBP: 00007f6eee1cd610 R08:
    > 0000000000000000 R09: 00007f6ee919d640 [ 646.344830] R10: 0000000000000002
    > R11: 0000000000000293 R12: 0000000000000002 [ 646.344843] R13:
    > 00007ffec2aede3f R14: 0000000000000000 R15: 00007f6ee919d640
    >
    > [ 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
    >
    > [ 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=0|zone=2|lastcpupid=0x1fffff)
    > [ 646.345326] raw: 0017ffffc0010200 0000000000000000 dead000000000122
    > ffff88810145b400
    > [ 646.345345] raw: ffff88810d864810 000000008017000f 00000001ffffffff
    > 0000000000000000
    > [ 646.345357] page dumped because: kasan: bad access detected
    >
    > [ 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]
    > ================================================================== [
    > 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


    --
    Oleksandr Natalenko (post-factum)


    \
     
     \ /
      Last update: 2021-07-18 23:10    [W:3.126 / U:0.464 seconds]
    ©2003-2020 Jasper Spaans|hosted at Digital Ocean and TransIP|Read the blog|Advertise on this site