Comment 0 for bug 1206309

Revision history for this message
Nickolay Ihalainen (ihanick) wrote : Xtrabackup 2.0.7 failed: expected log block no. 5458041, but got no. 1070811265 from the log file

percona-xtrabackup-2.0.7-552.rhel5

xtrabackup version 2.0.7 for Percona Server 5.1.59 unknown-linux-gnu (x86_64) (revision id: 552)
xtrabackup: uses posix_fadvise().
xtrabackup: cd to /database/mysql
xtrabackup: Target instance is assumed as followings.
xtrabackup: innodb_data_home_dir = ./
xtrabackup: innodb_data_file_path = ibdata1:10M:autoextend
xtrabackup: innodb_log_group_home_dir = ./
xtrabackup: innodb_log_files_in_group = 2
xtrabackup: innodb_log_file_size = 2147483648
xtrabackup: using O_DIRECT
InnoDB: Warning: allocated tablespace 424, old maximum was 9 ...

xtrabackup: error: log block numbers mismatch:
xtrabackup: error: expected log block no. 5458041, but got no. 1070811265 from the log file.
xtrabackup: Error: xtrabackup_copy_logfile() failed.
innobackupex: Error: ibbackup child process has died at /usr/bin/innobackupex line 386.

The error emitted after 179m45.411s since backup start.

Before backup start transaction log information:
Log sequence number 186856561401671
Log flushed up to 186856559716066
Last checkpoint at 186854266314745
Max checkpoint age 3476324967
Checkpoint age target 3367689812
Modified age 2265855030
Checkpoint age 2295086926
0 pending log writes, 0 pending chkp writes
1019036192 log i/o's done, 220.02 log i/o's/second

Right after xtrabackup crash:
Log sequence number 186919780065548
Log flushed up to 186919776998233
Last checkpoint at 186917463665464
Max checkpoint age 3476324967
Checkpoint age target 3367689812
Modified age 2313548226
Checkpoint age 2316400084
0 pending log writes, 0 pending chkp writes
1020869843 log i/o's done, 88.32 log i/o's/second

There are several errors from previous runs:
 xtrabackup: error: expected log block no. 3388077, but got no. 1068741301 from the log file.

>> log scanned up to (183619734185080)
>> log scanned up to (183619741409546)
xtrabackup: error: log block numbers mismatch:
xtrabackup: error: expected log block no. 2551941, but got no. 1067905165 from the log file.

From previous month innodb recovery information:
130618 23:46:21 Percona XtraDB (http://www.percona.com) 1.0.15-12.7 started; log sequence number 166828151398485

As you can see block no. diff is always 1065353224 compare to much smaller LSN change:
(186919780065548-186856561401671)/1024/1024/1024 = 58G