Sep 26 09:22:41 ck01 kernel: Buffer I/O error on device sda2, logical block 2

错误

kernel: sd 0:2:0:0: SCSI error: return code

kernel: end_request: I/O error, dev sda, sector 2308509

kernel: Buffer I/O error on device sda2, logical block 2

kernel: Buffer I/O error on device sda2, logical block 2

硬盘扇区问题？

更换一块硬盘，重装系统就好了！

附带一篇好文

1. 一次疑似磁盘故障的系统故障分析 
2. Posted on June 3, 2011 by Ding Honghui 
3.  
4. 我调查了一下这个问题，我得到的结论是磁盘应该是没有问题的。 
5.  
6. 从操作系统日志来分析： 磁盘libata子模块发起了一个的命令READ DMA EXT(op code 25h)[1] 
7.  
8. Jun  1 13:03:07 dna-616 kernel: [61661.891788] ata1.00: cmd 25/00:20:47:63:f8/00:00:1a:00:00/e0 tag 0 dma 16384 in 
9.  
10. 返回了一个错误为media error，具体为不可修复的错误(UNC Uncorrectable error – often due to bad sectors on the disk)[2] 
11.  
12. Jun  1 13:03:07 dna-616 kernel: [61661.891790]          res 51/40:20:47:63:f8/40:00:1a:00:00/e0 Emask 0x9 (media error) 
13. Jun  1 13:03:07 dna-616 kernel: [61661.891885] ata1.00: status: { DRDY ERR } 
14. Jun  1 13:03:07 dna-616 kernel: [61661.891910] ata1.00: error: { UNC } 
15.  
16. 同时，磁盘驱动也报告了不可读扇区的问题以及reallocate扇区失败 
17.  
18. Jun  1 13:03:15 dna-616 kernel: [61672.300045] sd 0:0:0:0: [sda] Add. Sense: Unrecovered read error - auto reallocate failed 
19.  
20. 其中报告的不可读扇区为/dev/sda的452485959 
21.  
22. Jun  1 13:03:15 dna-616 kernel: [61672.300098] end_request: I/O error, dev sda, sector 452485959 
23.  
24. 为了确定该扇区是否是真正有问题，我采取了网上一位作者提供的方法[3]，用hdparm直接读取该扇区 
25.  
26. hdparm --read-sector 452485959 /dev/sda | grep read 
27.  
28. 返回的结果为： 
29.  
30. reading sector 452485959: succeeded 
31.  
32. 对时间分析表明，该错误发生的时间段很集中: 在13:01-14:13总共12分钟之内出现了47次，分布在26个不同的扇区，随即在15:00左右系统重启。 和13:01之前约6小时比较，没有发生一起扇区读错误，在重启之后的6小时出现一次扇区读错误。 
33.  
34. 我检测了所有这26个报告有问题的扇区，都是可读的。 
35.  
36. 从以上现象推测， 
37.  
38. 首先，一个磁盘在某个时刻同时有26个扇区错误这个可能性是极小的。 
39.  
40. 其次，对这26个扇区的测试表明，这些扇区并没有问题 
41.  
42. 所以，最终我们可以推测，这个磁盘有问题的可能性是极小的。 
43.  
44. 排除了磁盘的问题，我们可以猜测RAID卡或者主板可能有问题，有可能是过热等等因素。 
45.  
46. [1]  http://www.t13.org/Documents/UploadedDocuments/project/d1410r3b-ATA-ATAPI-6.pdf 8.26节 
47.  
48. [2]  https://ata.wiki.kernel.org/index.php/Libata_error_messages#ATA_error_expansion 
49.  
50. [3]  https://guust.tuxes.nl/~bas/wordpress/?p=12 
51.  
52. [4] 系统日志摘要 
53.  
54. Jun  1 13:03:07 dna-616 kernel: [61661.891725] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 
55. Jun  1 13:03:07 dna-616 kernel: [61661.891759] ata1.00: BMDMA stat 0x65 
56. Jun  1 13:03:07 dna-616 kernel: [61661.891788] ata1.00: cmd 25/00:20:47:63:f8/00:00:1a:00:00/e0 tag 0 dma 16384 in 
57. Jun  1 13:03:07 dna-616 kernel: [61661.891790]          res 51/40:20:47:63:f8/40:00:1a:00:00/e0 Emask 0x9 (media error) 
58. Jun  1 13:03:07 dna-616 kernel: [61661.891885] ata1.00: status: { DRDY ERR } 
59. Jun  1 13:03:07 dna-616 kernel: [61661.891910] ata1.00: error: { UNC } 
60. Jun  1 13:03:07 dna-616 kernel: [61661.911623] ata1.00: configured for UDMA/133 
61. Jun  1 13:03:07 dna-616 kernel: [61661.947685] ata1.01: configured for UDMA/100 
62. Jun  1 13:03:07 dna-616 kernel: [61661.947685] ata1: EH complete 
63. Jun  1 13:03:15 dna-616 kernel: [61672.221414] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 
64. Jun  1 13:03:15 dna-616 kernel: [61672.221414] ata1.00: BMDMA stat 0x65 
65. Jun  1 13:03:15 dna-616 kernel: [61672.221414] ata1.00: cmd 25/00:20:47:63:f8/00:00:1a:00:00/e0 tag 0 dma 16384 in 
66. Jun  1 13:03:15 dna-616 kernel: [61672.221414]          res 51/40:20:47:63:f8/40:00:1a:00:00/e0 Emask 0x9 (media error) 
67. Jun  1 13:03:15 dna-616 kernel: [61672.221414] ata1.00: status: { DRDY ERR } 
68. Jun  1 13:03:15 dna-616 kernel: [61672.221414] ata1.00: error: { UNC } 
69. Jun  1 13:03:15 dna-616 kernel: [61672.270903] ata1.00: configured for UDMA/133 
70. Jun  1 13:03:15 dna-616 kernel: [61672.299759] ata1.01: configured for UDMA/100 
71. Jun  1 13:03:15 dna-616 kernel: [61672.299803] sd 0:0:0:0: [sda] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK 
72. Jun  1 13:03:15 dna-616 kernel: [61672.299853] sd 0:0:0:0: [sda] Sense Key : Medium Error [current] [descriptor] 
73. Jun  1 13:03:15 dna-616 kernel: [61672.299896] Descriptor sense data with sense descriptors (in hex): 
74. Jun  1 13:03:15 dna-616 kernel: [61672.299927]         72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 
75. Jun  1 13:03:15 dna-616 kernel: [61672.300006]         1a f8 63 47 
76. Jun  1 13:03:15 dna-616 kernel: [61672.300045] sd 0:0:0:0: [sda] Add. Sense: Unrecovered read error - auto reallocate failed 
77. Jun  1 13:03:15 dna-616 kernel: [61672.300098] end_request: I/O error, dev sda, sector 452485959 
78. Jun  1 13:03:15 dna-616 kernel: [61672.300142] ata1: EH complete 
79. Jun  1 13:03:15 dna-616 kernel: [61672.300696] sd 0:0:0:0: [sda] Write Protect is off 
80. Jun  1 13:03:15 dna-616 kernel: [61672.300726] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 
81. Jun  1 13:03:20 dna-616 kernel: [61678.954058] INFO: task kjournald:1194 blocked for more than 120 seconds. 
82. Jun  1 13:03:20 dna-616 kernel: [61678.954058] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. 
83. Jun  1 13:03:20 dna-616 kernel: [61678.954058] kjournald     D 0000000000000000     0  1194      2 
84. Jun  1 13:03:20 dna-616 kernel: [61678.954058]  ffff81023bce9d30 0000000000000046 0000000000000000 ffffffff8024ac66 
85. Jun  1 13:03:20 dna-616 kernel: [61678.962067]  ffff81023f2e3730 ffff81023f13b4f0 ffff81023f2e39b8 0000000380248d72 
86. Jun  1 13:03:20 dna-616 kernel: [61678.962128]  0000000000000001 0000000000000000 00000000ffffffff 0000000000000000 
87. Jun  1 13:03:20 dna-616 kernel: [61678.962172] Call Trace: 
88. Jun  1 13:03:20 dna-616 kernel: [61678.962225]  [<ffffffff8024ac66>] getnstimeofday+0x39/0x98 
89. Jun  1 13:03:20 dna-616 kernel: [61678.962256]  [<ffffffff802bb28d>] sync_buffer+0x0/0x3f 
90. Jun  1 13:03:20 dna-616 kernel: [61678.962284]  [<ffffffff80429667>] io_schedule+0x5c/0x9e