Observed on Linux 3.2.9 after the controller/disk flaked in-out. (The world still needs a SCSI error decoding tool to tell normal people what cmd and res are about.) [ 157.732885] device label srv devid 4 transid 11292 /dev/sdf [ 157.733201] btrfs: disk space caching is enabled [ 172.936515] device label srv devid 4 transid 11292 /dev/sdf [44106.091461] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 [44106.091470] ata4.01: BMDMA stat 0x64 [44106.091478] ata4.01: failed command: READ DMA EXT [44106.091492] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in [44106.091495] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) [44106.091503] ata4.01: status: { DRDY ERR } [44106.091508] ata4.01: error: { UNC } [44106.120384] ata4.00: configured for UDMA/133 [44106.128349] ata4.01: configured for UDMA/133 [44106.128374] ata4: EH complete [44109.364633] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 [44109.364642] ata4.01: BMDMA stat 0x64 [44109.364651] ata4.01: failed command: READ DMA EXT [44109.364666] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in [44109.364669] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) [44109.364676] ata4.01: status: { DRDY ERR } [44109.364682] ata4.01: error: { UNC } [44109.849346] ata4.00: configured for UDMA/133 [44109.856320] ata4.01: configured for UDMA/133 [44109.856347] ata4: EH complete [44112.450194] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 [44112.450203] ata4.01: BMDMA stat 0x64 [44112.450211] ata4.01: failed command: READ DMA EXT [44112.450226] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in [44112.450229] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) [44112.450236] ata4.01: status: { DRDY ERR } [44112.450241] ata4.01: error: { UNC } [44112.488695] ata4.00: configured for UDMA/133 [44112.496417] ata4.01: configured for UDMA/133 [44112.496441] ata4: EH complete [44115.215066] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 [44115.215074] ata4.01: BMDMA stat 0x64 [44115.215082] ata4.01: failed command: READ DMA EXT [44115.215097] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in [44115.215100] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) [44115.215107] ata4.01: status: { DRDY ERR } [44115.215113] ata4.01: error: { UNC } [44115.268483] ata4.00: configured for UDMA/133 [44115.276312] ata4.01: configured for UDMA/133 [44115.276340] ata4: EH complete [44117.869315] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 [44117.869323] ata4.01: BMDMA stat 0x64 [44117.869332] ata4.01: failed command: READ DMA EXT [44117.869347] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in [44117.869350] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) [44117.869357] ata4.01: status: { DRDY ERR } [44117.869363] ata4.01: error: { UNC } [44118.052386] ata4.00: configured for UDMA/133 [44118.060320] ata4.01: configured for UDMA/133 [44118.060348] ata4: EH complete [44121.695861] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 [44121.695870] ata4.01: BMDMA stat 0x64 [44121.695878] ata4.01: failed command: READ DMA EXT [44121.695893] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in [44121.695896] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) [44121.695903] ata4.01: status: { DRDY ERR } [44121.695908] ata4.01: error: { UNC } [44121.736972] ata4.00: configured for UDMA/133 [44121.744314] ata4.01: configured for UDMA/133 [44121.744347] sd 3:0:1:0: [sdf] Unhandled sense code [44121.744354] sd 3:0:1:0: [sdf] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE [44121.744362] sd 3:0:1:0: [sdf] Sense Key : Medium Error [current] [descriptor] [44121.744372] Descriptor sense data with sense descriptors (in hex): [44121.744377] 72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 [44121.744398] 4f 72 ca a3 [44121.744408] sd 3:0:1:0: [sdf] Add. Sense: Unrecovered read error - auto reallocate failed [44121.744419] sd 3:0:1:0: [sdf] CDB: Read(10): 28 00 4f 72 ca a0 00 00 08 00 [44121.744439] end_request: I/O error, dev sdf, sector 1332923043 [44121.744477] ata4: EH complete [44121.744519] BUG: unable to handle kernel NULL pointer dereference at (null) [44121.744556] IP: [<ffffffffa0321715>] ClearPageError+0x5/0x5 [btrfs] [44121.744666] PGD 37fb6067 PUD 3b504067 PMD 0 [44121.744692] Oops: 0000 [#1] SMP [44121.744707] CPU 2 [44121.744714] Modules linked in: f71882fg coretemp nfsd nfs lockd fscache auth_rpcgss nfs_acl sunrpc bonding mperf btrfs zlib_deflate i915 drm_kms_helper drm i2c_algo_bit intel_agp intel_gtt iTCO_wdt iTCO_vendor_support snd_hda_codec_realtek snd_hda_intel snd_hda_codec sg snd_hwdep snd_pcm snd_timer snd soundcore r8169 i2c_i801 agpgart i2c_core pcspkr mii snd_page_alloc rng_core video button autofs4 raid456 async_raid6_recov async_pq raid6_pq async_xor xor async_memcpy async_tx raid10 raid0 raid1 md_mod uhci_hcd ehci_hcd usbcore usb_common processor thermal_sys hwmon [44121.744956] [44121.744966] Pid: 2569, comm: btrfs-endio-meta-3 Not tainted 3.2.9-jng4-default #1 MICRO-STAR INTERNATIONAL CO.,LTD RX945GC/RX945GC [44121.745000] RIP: 0010:[<ffffffffa0321715>] [<ffffffffa0321715>] ClearPageError+0x5/0x5 [btrfs] [44121.745090] RSP: 0018:ffff880032de9e08 EFLAGS: 00010246 [44121.745104] RAX: 0000000000000000 RBX: 0000013dcc4b5000 RCX: 0000000000000000 [44121.745133] RDX: 0000000000000001 RSI: 0000000013dcc4b5 RDI: 0000000000000000 [44121.745162] RBP: ffff880035156c18 R08: 0000000000000000 R09: 0000000000000037 [44121.745191] R10: 0000000000004003 R11: 0000013dcc4b5000 R12: 0000013dcc4b55ff [44121.745219] R13: 0000000000000000 R14: ffff880032c46280 R15: ffff880032de9e98 [44121.745249] FS: 0000000000000000(0000) GS:ffff88003f731000(0000) knlGS:0000000000000000 [44121.745290] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [44121.745316] CR2: 0000000000000000 CR3: 000000003bcdb000 CR4: 00000000000006e0 [44121.745345] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [44121.745374] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 [44121.745402] Process btrfs-endio-meta-3 (pid: 2569, threadinfo ffff880032de8000, task ffff880032c46280) [44121.745444] Stack: [44121.745465] ffffffffa0325e6f ffff8800307776c0 ffff88001c9f3d78 ffff8800335d3000 [44121.745517] ffff88001c9f3d78 ffff880032c46280 ffffffffa0307bc0 ffff8800337721c0 [44121.745567] ffff880032c46280 ffff880032c46280 ffffffffa032ccf5 0000000000000000 [44121.745616] Call Trace: [44121.745941] [<ffffffffa0325e6f>] extent_range_uptodate+0x71/0xa1 [btrfs] [44121.746260] [<ffffffffa0307bc0>] end_workqueue_fn+0x23/0x67 [btrfs] [44121.746457] [<ffffffffa032ccf5>] worker_loop+0x92/0x2ad [btrfs] [44121.746751] [<ffffffff81056d59>] kthread+0x76/0x7e [44121.746787] [<ffffffff813d2234>] kernel_thread_helper+0x4/0x10 [44121.746817] Code: 5d c3 0f 18 0f c3 f0 ff 07 c3 f0 ff 0f 0f 94 c0 84 c0 0f 95 c0 0f b6 c0 c3 f0 81 07 00 00 10 00 c3 f0 80 0f 02 c3 f0 80 27 fd c3 <48> 8b 07 48 c1 e8 03 83 e0 01 74 00 c3 f0 80 0f 08 c3 f0 80 27 [44121.747047] RIP [<ffffffffa0321715>] ClearPageError+0x5/0x5 [btrfs] [44121.747139] RSP <ffff880032de9e08> [44121.747161] CR2: 0000000000000000 [44121.747444] ---[ end trace 2649150eabf37498 ]--- -- To unsubscribe from this list: send the line "unsubscribe linux-btrfs" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html
On 03/23/2012 08:07 PM, Jan Engelhardt wrote:> Observed on Linux 3.2.9 after the controller/disk flaked in-out. > (The world still needs a SCSI error decoding tool to tell normal people > what cmd and res are about.) >I''m not that sure if your 3.2.9-jng4-default build contains this commit or not: commit 8bedd51b6121c4607784d75f852828d25d119c52 (Btrfs: Check for NULL page in extent_range_uptodate) thanks, liubo> [ 157.732885] device label srv devid 4 transid 11292 /dev/sdf > [ 157.733201] btrfs: disk space caching is enabled > [ 172.936515] device label srv devid 4 transid 11292 /dev/sdf > [44106.091461] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 > [44106.091470] ata4.01: BMDMA stat 0x64 > [44106.091478] ata4.01: failed command: READ DMA EXT > [44106.091492] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in > [44106.091495] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) > [44106.091503] ata4.01: status: { DRDY ERR } > [44106.091508] ata4.01: error: { UNC } > [44106.120384] ata4.00: configured for UDMA/133 > [44106.128349] ata4.01: configured for UDMA/133 > [44106.128374] ata4: EH complete > [44109.364633] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 > [44109.364642] ata4.01: BMDMA stat 0x64 > [44109.364651] ata4.01: failed command: READ DMA EXT > [44109.364666] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in > [44109.364669] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) > [44109.364676] ata4.01: status: { DRDY ERR } > [44109.364682] ata4.01: error: { UNC } > [44109.849346] ata4.00: configured for UDMA/133 > [44109.856320] ata4.01: configured for UDMA/133 > [44109.856347] ata4: EH complete > [44112.450194] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 > [44112.450203] ata4.01: BMDMA stat 0x64 > [44112.450211] ata4.01: failed command: READ DMA EXT > [44112.450226] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in > [44112.450229] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) > [44112.450236] ata4.01: status: { DRDY ERR } > [44112.450241] ata4.01: error: { UNC } > [44112.488695] ata4.00: configured for UDMA/133 > [44112.496417] ata4.01: configured for UDMA/133 > [44112.496441] ata4: EH complete > [44115.215066] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 > [44115.215074] ata4.01: BMDMA stat 0x64 > [44115.215082] ata4.01: failed command: READ DMA EXT > [44115.215097] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in > [44115.215100] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) > [44115.215107] ata4.01: status: { DRDY ERR } > [44115.215113] ata4.01: error: { UNC } > [44115.268483] ata4.00: configured for UDMA/133 > [44115.276312] ata4.01: configured for UDMA/133 > [44115.276340] ata4: EH complete > [44117.869315] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 > [44117.869323] ata4.01: BMDMA stat 0x64 > [44117.869332] ata4.01: failed command: READ DMA EXT > [44117.869347] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in > [44117.869350] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) > [44117.869357] ata4.01: status: { DRDY ERR } > [44117.869363] ata4.01: error: { UNC } > [44118.052386] ata4.00: configured for UDMA/133 > [44118.060320] ata4.01: configured for UDMA/133 > [44118.060348] ata4: EH complete > [44121.695861] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 > [44121.695870] ata4.01: BMDMA stat 0x64 > [44121.695878] ata4.01: failed command: READ DMA EXT > [44121.695893] ata4.01: cmd 25/00:08:a0:ca:72/00:00:4f:00:00/f0 tag 0 dma 4096 in > [44121.695896] res 51/40:00:a3:ca:72/40:00:4f:00:00/f0 Emask 0x9 (media error) > [44121.695903] ata4.01: status: { DRDY ERR } > [44121.695908] ata4.01: error: { UNC } > [44121.736972] ata4.00: configured for UDMA/133 > [44121.744314] ata4.01: configured for UDMA/133 > [44121.744347] sd 3:0:1:0: [sdf] Unhandled sense code > [44121.744354] sd 3:0:1:0: [sdf] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE > [44121.744362] sd 3:0:1:0: [sdf] Sense Key : Medium Error [current] [descriptor] > [44121.744372] Descriptor sense data with sense descriptors (in hex): > [44121.744377] 72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 > [44121.744398] 4f 72 ca a3 > [44121.744408] sd 3:0:1:0: [sdf] Add. Sense: Unrecovered read error - auto reallocate failed > [44121.744419] sd 3:0:1:0: [sdf] CDB: Read(10): 28 00 4f 72 ca a0 00 00 08 00 > [44121.744439] end_request: I/O error, dev sdf, sector 1332923043 > [44121.744477] ata4: EH complete > [44121.744519] BUG: unable to handle kernel NULL pointer dereference at (null) > [44121.744556] IP: [<ffffffffa0321715>] ClearPageError+0x5/0x5 [btrfs] > [44121.744666] PGD 37fb6067 PUD 3b504067 PMD 0 > [44121.744692] Oops: 0000 [#1] SMP > [44121.744707] CPU 2 > [44121.744714] Modules linked in: f71882fg coretemp nfsd nfs lockd fscache auth_rpcgss nfs_acl sunrpc bonding mperf btrfs zlib_deflate i915 drm_kms_helper drm i2c_algo_bit intel_agp intel_gtt iTCO_wdt iTCO_vendor_support snd_hda_codec_realtek snd_hda_intel snd_hda_codec sg snd_hwdep snd_pcm snd_timer snd soundcore r8169 i2c_i801 agpgart i2c_core pcspkr mii snd_page_alloc rng_core video button autofs4 raid456 async_raid6_recov async_pq raid6_pq async_xor xor async_memcpy async_tx raid10 raid0 raid1 md_mod uhci_hcd ehci_hcd usbcore usb_common processor thermal_sys hwmon > [44121.744956] > [44121.744966] Pid: 2569, comm: btrfs-endio-meta-3 Not tainted 3.2.9-jng4-default #1 MICRO-STAR INTERNATIONAL CO.,LTD RX945GC/RX945GC > [44121.745000] RIP: 0010:[<ffffffffa0321715>] [<ffffffffa0321715>] ClearPageError+0x5/0x5 [btrfs] > [44121.745090] RSP: 0018:ffff880032de9e08 EFLAGS: 00010246 > [44121.745104] RAX: 0000000000000000 RBX: 0000013dcc4b5000 RCX: 0000000000000000 > [44121.745133] RDX: 0000000000000001 RSI: 0000000013dcc4b5 RDI: 0000000000000000 > [44121.745162] RBP: ffff880035156c18 R08: 0000000000000000 R09: 0000000000000037 > [44121.745191] R10: 0000000000004003 R11: 0000013dcc4b5000 R12: 0000013dcc4b55ff > [44121.745219] R13: 0000000000000000 R14: ffff880032c46280 R15: ffff880032de9e98 > [44121.745249] FS: 0000000000000000(0000) GS:ffff88003f731000(0000) knlGS:0000000000000000 > [44121.745290] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b > [44121.745316] CR2: 0000000000000000 CR3: 000000003bcdb000 CR4: 00000000000006e0 > [44121.745345] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 > [44121.745374] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 > [44121.745402] Process btrfs-endio-meta-3 (pid: 2569, threadinfo ffff880032de8000, task ffff880032c46280) > [44121.745444] Stack: > [44121.745465] ffffffffa0325e6f ffff8800307776c0 ffff88001c9f3d78 ffff8800335d3000 > [44121.745517] ffff88001c9f3d78 ffff880032c46280 ffffffffa0307bc0 ffff8800337721c0 > [44121.745567] ffff880032c46280 ffff880032c46280 ffffffffa032ccf5 0000000000000000 > [44121.745616] Call Trace: > [44121.745941] [<ffffffffa0325e6f>] extent_range_uptodate+0x71/0xa1 [btrfs] > [44121.746260] [<ffffffffa0307bc0>] end_workqueue_fn+0x23/0x67 [btrfs] > [44121.746457] [<ffffffffa032ccf5>] worker_loop+0x92/0x2ad [btrfs] > [44121.746751] [<ffffffff81056d59>] kthread+0x76/0x7e > [44121.746787] [<ffffffff813d2234>] kernel_thread_helper+0x4/0x10 > [44121.746817] Code: 5d c3 0f 18 0f c3 f0 ff 07 c3 f0 ff 0f 0f 94 c0 84 c0 0f 95 c0 0f b6 c0 c3 f0 81 07 00 00 10 00 c3 f0 80 0f 02 c3 f0 80 27 fd c3 <48> 8b 07 48 c1 e8 03 83 e0 01 74 00 c3 f0 80 0f 08 c3 f0 80 27 > [44121.747047] RIP [<ffffffffa0321715>] ClearPageError+0x5/0x5 [btrfs] > [44121.747139] RSP <ffff880032de9e08> > [44121.747161] CR2: 0000000000000000 > [44121.747444] ---[ end trace 2649150eabf37498 ]--- > > -- > To unsubscribe from this list: send the line "unsubscribe linux-btrfs" in > the body of a message to majordomo@vger.kernel.org > More majordomo info at http://vger.kernel.org/majordomo-info.html >-- To unsubscribe from this list: send the line "unsubscribe linux-btrfs" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html
On Monday 2012-03-26 03:42, Liu Bo wrote:>On 03/23/2012 08:07 PM, Jan Engelhardt wrote: >> Observed on Linux 3.2.9 after the controller/disk flaked in-out. >> (The world still needs a SCSI error decoding tool to tell normal people >> what cmd and res are about.) >> > >I''m not that sure if your 3.2.9-jng4-default build contains this commit or not: > >commit 8bedd51b6121c4607784d75f852828d25d119c52 >(Btrfs: Check for NULL page in extent_range_uptodate)8bedd isn''t in 3.2.9; thanks for the hint, I will try that one. -- To unsubscribe from this list: send the line "unsubscribe linux-btrfs" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html