Messages in this thread |  | | Date | Wed, 4 Dec 2013 08:59:40 +1100 | From | Dave Chinner <> | Subject | [OOPS, 3.13-rc2] null ptr in dio_complete() |
| |
Hi Jens,
Not sure who to direct this to or CC, so I figured you are the person to do that. I just had xfstests generic/299 (an AIO/DIO test) oops in dio_complete() like so:
[ 9650.586724] general protection fault: 0000 [#1] SMP DEBUG_PAGEALLOC [ 9650.590131] Modules linked in: [ 9650.590630] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 3.13.0-rc2-dgc+ #73 [ 9650.590630] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9650.590630] task: ffffffff81f35480 ti: ffffffff81f24000 task.ti: ffffffff81f24000 [ 9650.590630] RIP: 0010:[<ffffffff811ee5f1>] [<ffffffff811ee5f1>] aio_complete+0xb1/0x1e0 [ 9650.590630] RSP: 0018:ffff88007f803cf8 EFLAGS: 00010003 [ 9650.590630] RAX: 0000000000000086 RBX: ffff8800688ae000 RCX: 0000000000d6d6d6 [ 9650.590630] RDX: 6b6b6b6b6b6b6b6b RSI: 0000000000001000 RDI: ffff8800688ae1c4 [ 9650.590630] RBP: ffff88007f803d28 R08: 0000000000000000 R09: 000000006b6b6b6c [ 9650.590630] R10: ff79a1a35779b009 R11: 0000000000000010 R12: ffff88006953a540 [ 9650.590630] R13: 0000000000001000 R14: 0000000000000000 R15: ffff8800688ae1c4 [ 9650.590630] FS: 0000000000000000(0000) GS:ffff88007f800000(0000) knlGS:0000000000000000 [ 9650.590630] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 9650.590630] CR2: 00007ffa9c48448f CR3: 0000000028061000 CR4: 00000000000006f0 [ 9650.590630] Stack: [ 9650.590630] ffff88007f803d18 0000000000001000 ffff880068b80800 0000000000001000 [ 9650.590630] 0000000000000001 0000000031f6d000 ffff88007f803d68 ffffffff811ddae3 [ 9650.590630] 0000000000000086 ffff880068b80800 0000000000000000 ffff880068b80828 [ 9650.590630] Call Trace: [ 9650.590630] <IRQ> [ 9650.590630] [<ffffffff811ddae3>] dio_complete+0xa3/0x140 [ 9650.590630] [<ffffffff811ddc2a>] dio_bio_end_aio+0x7a/0x110 [ 9650.590630] [<ffffffff811ddbb5>] ? dio_bio_end_aio+0x5/0x110 [ 9650.590630] [<ffffffff811d8a9d>] bio_endio+0x1d/0x30 [ 9650.590630] [<ffffffff8175d65f>] blk_mq_complete_request+0x5f/0x120 [ 9650.590630] [<ffffffff8175d736>] __blk_mq_end_io+0x16/0x20 [ 9650.590630] [<ffffffff8175d7a8>] blk_mq_end_io+0x68/0xd0 [ 9650.590630] [<ffffffff818539a7>] virtblk_done+0x67/0x110 [ 9650.590630] [<ffffffff817f74c5>] vring_interrupt+0x35/0x60 [ 9650.590630] [<ffffffff810e4e34>] handle_irq_event_percpu+0x54/0x1e0 [ 9650.590630] [<ffffffff810e5008>] handle_irq_event+0x48/0x70 [ 9650.590630] [<ffffffff8107d6f5>] ? kvm_guest_apic_eoi_write+0x5/0x50 [ 9650.590630] [<ffffffff810e7807>] handle_edge_irq+0x77/0x110 [ 9650.590630] [<ffffffff8104796f>] handle_irq+0xbf/0x150 [ 9650.590630] [<ffffffff810478b5>] ? handle_irq+0x5/0x150 [ 9650.590630] [<ffffffff81aee136>] ? atomic_notifier_call_chain+0x16/0x20 [ 9650.590630] [<ffffffff81af43ca>] do_IRQ+0x5a/0xe0 [ 9650.590630] [<ffffffff81aea16d>] common_interrupt+0x6d/0x6d [ 9650.590630] <EOI> [ 9650.590630] [<ffffffff8107e616>] ? native_safe_halt+0x6/0x10 [ 9650.590630] [<ffffffff8104e4f5>] ? default_idle+0x5/0xc0 [ 9650.590630] [<ffffffff8104e50f>] default_idle+0x1f/0xc0 [ 9650.590630] [<ffffffff8104edb6>] arch_cpu_idle+0x26/0x30 [ 9650.590630] [<ffffffff810e4331>] cpu_startup_entry+0x81/0x240 [ 9650.590630] [<ffffffff81ad5d67>] rest_init+0x77/0x80 [ 9650.590630] [<ffffffff8203ceec>] start_kernel+0x3cd/0x3da [ 9650.590630] [<ffffffff8203c91e>] ? repair_env_string+0x5e/0x5e [ 9650.590630] [<ffffffff8203c117>] ? early_idt_handlers+0x117/0x120 [ 9650.590630] [<ffffffff8203c5c2>] x86_64_start_reservations+0x2a/0x2c [ 9650.590630] [<ffffffff8203c70a>] x86_64_start_kernel+0x146/0x155 [ 9650.590630] Code: e8 05 b5 8f 00 44 8b 8b c0 01 00 00 45 31 c0 48 8b 93 98 00 00 00 41 83 c1 01 44 3b 8b 80 00 00 00 44 89 c9 45 0f 42 c1 c1 e9 07 <48> 8b 0c ca 65 [ 9650.590630] RIP [<ffffffff811ee5f1>] aio_complete+0xb1/0x1e0 [ 9650.590630] RSP <ffff88007f803cf8>
I'm not sure if it is related to blk_mq, virtio, dio or bio changes (or even somethign else), but I haven't seen anything like this before. I've only seen it once so far (haven't rerun the test yet at all).
FWIW, I'd also like to point out that I'm seeing XFS on the virtio stack get perilously close to blowing the stack on 3.13-rc2:
[ 387.048771] fill (11196) used greatest stack depth: 1048 bytes left
This is new - on 3.12 generally the output would be around 2500 bytes left, so somewhere below of XFS there a lot more stack being consumed in the IO path on 3.13. I'll do some more tracing of this to try to find out where it is all going....
Cheers,
Dave. -- Dave Chinner david@fromorbit.com
|  |