Where to report bug? sparc64 crashes probably from mysqld
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
Reply to: