Hi, I get unaligned access errors and I suspect mysqld to cause these, as it reports page corruption just before. How would I find out whether this is true? is mysql-server-5.0 the package to file a bug report for the problem. What else to include in the report?
mysql-Version: 5.0.32-7etch1 Description of what is happening: mysqld finds a page corruption in a InnoDB database. The kernel then reports an unaligned access. mysqld restarts and performs the crash recovery. unaligned access occurs again and ssh, ping, apache, mysql do not respond anymore starting from this point. Based on /var/log/syslog (see attachment) I suspect mysqld causes these problems. Any help would be appreciated -- Dominik Reusser
Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: Database page corruption on disk or a failed Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: file read of page 1672847. Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: You may have to recover from a backup. Dec 10 10:11:44 libdb mysqld[3524]: 071210 10:11:43 InnoDB: Page dump in ascii and hex (16384 bytes): Dec 10 10:11:44 libdb mysqld[3524]: len 16384; hex c4deb3030 --snip-- ;InnoDB: End of page dump Dec 10 10:11:44 libdb mysqld[3524]: 071210 10:11:44 InnoDB: Page checksum 2910718267, prior-to-4.0.14-form checksum 3244052608 Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: stored checksum 3302929155, prior-to-4.0.14-form stored checksum 126 Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: Page lsn 49 3962043205, low 4 bytes of lsn at page end 4096 Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: Page number (if stored to page already) 1672847, Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: space id (if created with >= MySQL-4.1.1 and stored already) 0 Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: Page may be an index page where index id is 0 523 Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: (index LocatID_2 of table iller/TBL_Sample) Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: Database page corruption on disk or a failed Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: file read of page 1672847. Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: You may have to recover from a backup. Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: It is also possible that your operating Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: system has corrupted its own file cache Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: and rebooting your computer removes the Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: error. Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: If the corrupt page is an index page Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: you can also try to fix the corruption Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: by dumping, dropping, and reimporting Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: the corrupt table. You can use CHECK Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: TABLE to scan your table for corruption. Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: See also InnoDB: http://dev.mysql.com/doc/refman/5.0/en/forcing-recovery.html Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: about forcing recovery. Dec 10 10:11:44 libdb mysqld[3524]: InnoDB: Ending processing because of a corrupt database page. Dec 10 10:11:45 libdb kernel: Kernel unaligned access at TPC[4c6af0] do_task_stat+0x188/0x588 Dec 10 10:11:45 libdb kernel: Unable to handle kernel paging request in mna handler<1> at virtual address 8000000000a65493 Dec 10 10:11:45 libdb kernel: current->{active_,}mm->context = 000000000000099b Dec 10 10:11:45 libdb kernel: current->{active_,}mm->pgd = fffff80016884000 Dec 10 10:11:45 libdb kernel: \|/ ____ \|/ Dec 10 10:11:45 libdb kernel: "@'/ .. \`@" Dec 10 10:11:45 libdb kernel: /_| \__/ |_\ Dec 10 10:11:45 libdb kernel: \__U_/ Dec 10 10:11:45 libdb kernel: ps(6642): Oops [#1] Dec 10 10:11:45 libdb kernel: TSTATE: 0000004411009603 TPC: 00000000004c6af0 TNPC: 00000000004c6af4 Y: 00000000 Not tainted Dec 10 10:11:45 libdb kernel: TPC: <do_task_stat+0x188/0x588> Dec 10 10:11:45 libdb kernel: g0: fffff800110dd000 g1: 0000000000000013 g2: 8000000000a653e7 g3: 00000000170008a0 Dec 10 10:11:45 libdb kernel: g4: fffff80011062980 g5: 7374617400000000 g6: fffff8001c660000 g7: fffff8001fb41c60 Dec 10 10:11:45 libdb kernel: o0: fffff8001fc82500 o1: fffff8001c663c38 o2: fffff8001c663c30 o3: fffff8001c663d48 Dec 10 10:11:45 libdb kernel: o4: 0000000000000252 o5: fffff80010d18800 sp: fffff8001c6631c1 ret_pc: 00000000004c6a3c Dec 10 10:11:45 libdb kernel: RPC: <do_task_stat+0xd4/0x588> Dec 10 10:11:45 libdb kernel: l0: 0000000000000007 l1: fffff8001c663d48 l2: fffff8001fb86e40 l3: 0000000000000013 Dec 10 10:11:45 libdb kernel: l4: 0000000000000011 l5: 0000000000000001 l6: 0000000000030400 l7: 0000000000000053 Dec 10 10:11:45 libdb kernel: i0: fffff8001fc82500 i1: fffff800148ea000 i2: 0000000000000001 i3: 0000000000000000 Dec 10 10:11:45 libdb kernel: i4: 0000000000000001 i5: 0000000000000000 i6: fffff8001c663451 i7: 00000000004c4aa0 Dec 10 10:11:45 libdb kernel: I7: <proc_info_read+0x54/0xb4> Dec 10 10:11:45 libdb kernel: Caller[00000000004c4aa0]: proc_info_read+0x54/0xb4 Dec 10 10:11:45 libdb kernel: Caller[000000000048ab50]: vfs_read+0xa0/0x14c Dec 10 10:11:45 libdb kernel: Caller[000000000048ae3c]: sys_read+0x34/0x60 Dec 10 10:11:45 libdb kernel: Caller[00000000004069d4]: linux_sparc_syscall32+0x3c/0x40 Dec 10 10:11:45 libdb kernel: Caller[00000000f7f11adc]: 0xf7f11ae4 Dec 10 10:11:45 libdb kernel: Instruction DUMP: c427a78b c45b6008 c6036010 <c200a0ac> c400a0b0 83286014 da036108 82104002 053ffc00 Dec 10 10:11:45 libdb mysqld_safe[6648]: Number of processes running now: 0 Dec 10 10:11:45 libdb mysqld_safe[6650]: restarted Dec 10 10:11:47 libdb mysqld[6653]: 071210 10:11:47 InnoDB: Database was not shut down normally! Dec 10 10:11:47 libdb mysqld[6653]: InnoDB: Starting crash recovery. Dec 10 10:11:47 libdb mysqld[6653]: InnoDB: Reading tablespace information from the .ibd files... Dec 10 10:11:48 libdb mysqld[6653]: InnoDB: Restoring possible half-written data pages from the doublewrite Dec 10 10:11:48 libdb mysqld[6653]: InnoDB: buffer... Dec 10 10:11:49 libdb /etc/mysql/debian-start[6664]: WARNING: mysqlcheck has found corrupt tables Dec 10 10:11:49 libdb /etc/mysql/debian-start[6664]: /usr/bin/mysqlcheck: Got error: 2013: Lost connection to MySQL server during query when executing 'CHECK TABLE ... FAST' Dec 10 10:11:49 libdb /etc/mysql/debian-start[6664]: Dec 10 10:11:49 libdb /etc/mysql/debian-start[6664]: Improperly closed tables are also reported if clients are accessing Dec 10 10:11:49 libdb /etc/mysql/debian-start[6664]: the tables *now*. A list of current connections is below. Dec 10 10:11:49 libdb /etc/mysql/debian-start[6664]: Dec 10 10:11:50 libdb mysqld[6653]: 071210 10:11:50 InnoDB: Starting log scan based on checkpoint at Dec 10 10:11:50 libdb mysqld[6653]: InnoDB: log sequence number 58 1742537654. Dec 10 10:11:50 libdb mysqld[6653]: InnoDB: Doing recovery: scanned up to log sequence number 58 1742537654 Dec 10 10:11:51 libdb mysqld[6653]: InnoDB: Last MySQL binlog file position 0 1080000, file name /var/log/mysql/mysql-bin.000128 Dec 10 10:11:51 libdb mysqld[6653]: 071210 10:11:51 InnoDB: Started; log sequence number 58 1742537654 Dec 10 10:11:51 libdb mysqld[6653]: 071210 10:11:51 [Note] Recovering after a crash using /var/log/mysql/mysql-bin Dec 10 10:11:51 libdb mysqld[6653]: 071210 10:11:51 [Note] Starting crash recovery... Dec 10 10:11:51 libdb mysqld[6653]: 071210 10:11:51 [Note] Crash recovery finished. Dec 10 10:11:51 libdb mysqld[6653]: 071210 10:11:51 [Note] /usr/sbin/mysqld: ready for connections. Dec 10 10:11:51 libdb mysqld[6653]: Version: '5.0.32-Debian_7etch1-log' socket: '/var/run/mysqld/mysqld.sock' port: 3306 Debian etch distribution Dec 10 10:17:01 libdb /USR/SBIN/CRON[6674]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Dec 10 10:22:36 libdb kernel: Kernel unaligned access at TPC[557c60] pty_chars_in_buffer+0x24/0x50 Dec 10 10:22:36 libdb kernel: Unsupported unaligned load/store trap for kernel at <0000000000557c60>. Dec 10 10:22:36 libdb kernel: \|/ ____ \|/ Dec 10 10:22:36 libdb kernel: "@'/ .. \`@" Dec 10 10:22:36 libdb kernel: /_| \__/ |_\ Dec 10 10:22:36 libdb kernel: \__U_/ Dec 10 10:22:36 libdb kernel: sshd(4723): Kernel does fpu/atomic unaligned load/store. [#2] Dec 10 10:22:36 libdb kernel: TSTATE: 0000004411009600 TPC: 0000000000557c60 TNPC: 0000000000557c64 Y: 00000000 Not tainted Dec 10 10:22:36 libdb kernel: TPC: <pty_chars_in_buffer+0x24/0x50> Dec 10 10:22:36 libdb kernel: g0: fffff80017b82ef1 g1: 4557412920284457 g2: 0000000000000001 g3: 0000000000000001 Dec 10 10:22:36 libdb kernel: g4: fffff80018287260 g5: 00000000000019fc g6: fffff80017b80000 g7: 0000000000000000 Dec 10 10:22:36 libdb kernel: o0: fffff80010d18800 o1: fffff8001f8d6340 o2: 00000000006b21d0 o3: fffff80010717000 Dec 10 10:22:36 libdb kernel: o4: 0000000000000000 o5: 0000000000004a00 sp: fffff80017b82f31 ret_pc: 00000000005c57a0 Dec 10 10:22:36 libdb kernel: RPC: <netif_receive_skb+0x408/0x484> Dec 10 10:22:36 libdb kernel: l0: fffff8001126f1e0 l1: 00000000006b21d0 l2: fffff80010717000 l3: 00000000007694e0 Dec 10 10:22:36 libdb kernel: l4: 0000000000000800 l5: 000000000000001d l6: fffff80010b720e8 l7: 00000000864a0000 Dec 10 10:22:36 libdb kernel: i0: fffff8001038f000 i1: fffff8001038f1e0 i2: 0000000000000000 i3: 0000000000650f78 Dec 10 10:22:36 libdb kernel: i4: 0000000000769070 i5: 0000000000000010 i6: fffff80017b82ff1 i7: 0000000000556238 Dec 10 10:22:36 libdb kernel: I7: <normal_poll+0x14c/0x17c> Dec 10 10:22:36 libdb kernel: Caller[0000000000556238]: normal_poll+0x14c/0x17c Dec 10 10:22:36 libdb kernel: Caller[0000000000551f18]: tty_poll+0x60/0x78 Dec 10 10:22:36 libdb kernel: Caller[000000000049d8d4]: do_select+0x204/0x3bc Dec 10 10:22:36 libdb kernel: Caller[00000000004b45a8]: compat_core_sys_select+0x11c/0x18c Dec 10 10:22:36 libdb kernel: Caller[00000000004b6c98]: compat_sys_select+0xe0/0x180 Dec 10 10:22:36 libdb kernel: Caller[00000000004069d4]: linux_sparc_syscall32+0x3c/0x40 Dec 10 10:22:36 libdb kernel: Caller[000000000001c584]: 0x1c58c Dec 10 10:22:36 libdb kernel: Instruction DUMP: c2586048 22c0400c 90102000 <9fc04000> 01000000 c25e2008 c25060be 80a06002 02400005