Xtrabackup never completes

[adamp_oh]

[root@rmdb10 bin]# ./xtrabackup --defaults-file=/etc/my.cnf --backup --datadir=/usr/local/mysql/data/ --user=user --password=password --target-dir=/tmp/backup
./xtrabackup version 2.0.5 for Percona Server 5.1.59 unknown-linux-gnu (x86_64) (revision id: undefined)
xtrabackup: uses posix_fadvise().
xtrabackup: cd to /usr/local/mysql/data/
xtrabackup: Target instance is assumed as followings.
xtrabackup: innodb_data_home_dir = ./
xtrabackup: innodb_data_file_path = ibdata1:5G;ibdata2:5G;ibdata3:5G;ibdata4:5G;ibdata5:5G;ibdat a6:5G;ibdata7:5G;ibdata8:5G;ibdata9:5G;ibdata10:5G;ibdata11: 5G;ibdata12:5G;ibdata13:5G;ibdata14:5G;ibdata15:5G;ibdata16: 5G;ibdata17:5G
xtrabackup: innodb_log_group_home_dir = /usr/local/mysql/data_logs
xtrabackup: innodb_log_files_in_group = 3
xtrabackup: innodb_log_file_size = 1073741824

log scanned up to (1294896943557)
[01] Copying ./ibdata1 to /tmp/backup/ibdata1
log scanned up to (1294897163717)
log scanned up to (1294897174767)
log scanned up to (1294897347900)
log scanned up to (1294897404841)
log scanned up to (1294897412998)
log scanned up to (1294897526026)
log scanned up to (1294897538226)
[01] …done
[01] Copying ./ibdata2 to /tmp/backup/ibdata2
log scanned up to (1294897597032)
log scanned up to (1294897609358)
log scanned up to (1294897627305)
log scanned up to (1294897630275)
log scanned up to (1294897639186)
log scanned up to (1294897806185)
log scanned up to (1294897839832)
log scanned up to (1294897848438)
[01] …done
[01] Copying ./ibdata3 to /tmp/backup/ibdata3
log scanned up to (1294897859578)
log scanned up to (1294898010539)
log scanned up to (1294898080398)
log scanned up to (1294898088112)
log scanned up to (1294898101987)
log scanned up to (1294898265032)
log scanned up to (1294898523635)
log scanned up to (1294898537707)
[01] …done
[01] Copying ./ibdata4 to /tmp/backup/ibdata4
log scanned up to (1294898545246)
log scanned up to (1294898554495)
log scanned up to (1294898561570)
log scanned up to (1294898565894)
log scanned up to (1294898588754)
log scanned up to (1294898596920)
log scanned up to (1294898683301)
log scanned up to (1294899056230)
[01] …done
[01] Copying ./ibdata5 to /tmp/backup/ibdata5
log scanned up to (1294899059897)
log scanned up to (1294899135571)
log scanned up to (1294899147639)
log scanned up to (1294899193587)
log scanned up to (1294899219111)
log scanned up to (1294899253444)
log scanned up to (1294899274831)
log scanned up to (1294899290944)
[01] …done
[01] Copying ./ibdata6 to /tmp/backup/ibdata6
log scanned up to (1294899317311)
log scanned up to (1294899336643)
log scanned up to (1294899340449)
log scanned up to (1294899542370)
log scanned up to (1294899559514)
log scanned up to (1294899628347)
log scanned up to (1294899686247)
log scanned up to (1294899710141)
log scanned up to (1294899735476)
[01] …done
[01] Copying ./ibdata7 to /tmp/backup/ibdata7
log scanned up to (1294899745827)
log scanned up to (1294899809844)
log scanned up to (1294899894300)
log scanned up to (1294899909002)
log scanned up to (1294899932974)
log scanned up to (1294900104981)
log scanned up to (1294900203359)
log scanned up to (1294900349791)
[01] …done
[01] Copying ./ibdata8 to /tmp/backup/ibdata8
log scanned up to (1294900397801)
log scanned up to (1294900539437)
log scanned up to (1294900629356)
log scanned up to (1294900773661)
log scanned up to (1294900826645)
log scanned up to (1294900898887)
log scanned up to (1294901076978)
log scanned up to (1294901089894)
[01] …done
[01] Copying ./ibdata9 to /tmp/backup/ibdata9
log scanned up to (1294901257327)
log scanned up to (1294901358835)
log scanned up to (1294901664352)
log scanned up to (1294901686758)
log scanned up to (1294901746112)
log scanned up to (1294901756506)
log scanned up to (1294901796117)
log scanned up to (1294901815980)
[01] …done
[01] Copying ./ibdata10 to /tmp/backup/ibdata10
log scanned up to (1294901963838)
log scanned up to (1294902091562)
log scanned up to (1294902254237)
log scanned up to (1294902391476)
log scanned up to (1294902475799)
log scanned up to (1294902494779)
log scanned up to (1294902516348)
[01] …done
[01] Copying ./ibdata11 to /tmp/backup/ibdata11
log scanned up to (1294902741781)
log scanned up to (1294903137966)
log scanned up to (1294903157472)
log scanned up to (1294903164353)
log scanned up to (1294903184775)
log scanned up to (1294903493406)
log scanned up to (1294903525933)
[01] …done
[01] Copying ./ibdata12 to /tmp/backup/ibdata12
log scanned up to (1294903614688)
log scanned up to (1294903757725)
log scanned up to (1294903927441)
log scanned up to (1294903933655)
log scanned up to (1294903957402)
log scanned up to (1294903976012)
log scanned up to (1294904055375)
log scanned up to (1294904091283)
[01] …done
[01] Copying ./ibdata13 to /tmp/backup/ibdata13
log scanned up to (1294904154593)
log scanned up to (1294904244056)
log scanned up to (1294904270402)
log scanned up to (1294904339740)
log scanned up to (1294904446612)
log scanned up to (1294904470805)
log scanned up to (1294904488853)
[01] …done
[01] Copying ./ibdata14 to /tmp/backup/ibdata14
log scanned up to (1294904504193)
log scanned up to (1294904528100)
log scanned up to (1294904538303)
log scanned up to (1294904674728)
log scanned up to (1294904697175)
log scanned up to (1294904716631)
[01] …done
[01] Copying ./ibdata15 to /tmp/backup/ibdata15
log scanned up to (1294904725623)
log scanned up to (1294904771683)
log scanned up to (1294904793766)
log scanned up to (1294904828794)
[01] …done
[01] Copying ./ibdata16 to /tmp/backup/ibdata16
log scanned up to (1294904841918)
log scanned up to (1294905027112)
log scanned up to (1294905032304)
log scanned up to (1294905191862)
log scanned up to (1294905328154)
[01] …done
[01] Copying ./ibdata17 to /tmp/backup/ibdata17
log scanned up to (1294905330792)
log scanned up to (1294905389561)
log scanned up to (1294905532183)
log scanned up to (1294905546778)
log scanned up to (1294905597620)
[01] …done
xtrabackup: The latest check point (for incremental): ‘1294905501114’
xtrabackup: Stopping log copying thread.
.>> log scanned up to (1294905601958)

xtrabackup: Transaction log of lsn (1294896651478) to (1294905601958) was copied.

I never receive a completed message. It always ends with the last line about transaction log. Is that normal?

[miguelangelnieto]

Yes, that is normal. xtrabackup binary only copies .ibd tablespace and log files, that’s all. If you want a full backup with myisam tables and .frm files you should use innobackupex instead.

innobackupex --defaults-file=/root/sandboxes/msb_5_5_28/my.sandbox.cnf --user=msandbox --password=msandbox /tmp/backup

InnoDB Backup Utility v1.5.1-xtrabackup; Copyright 2003, 2009 Innobase Oy
and Percona Inc 2009-2012. All Rights Reserved.

This software is published under
the GNU GENERAL PUBLIC LICENSE Version 2, June 1991.

130206 12:25:05 innobackupex: Starting mysql with options: --defaults-file=‘/root/sandboxes/msb_5_5_28/my.sandbox.cnf’ --password=xxxxxxxx --user=‘msandbox’ --unbuffered –
130206 12:25:05 innobackupex: Connected to database with mysql child process (pid=1881)
130206 12:25:11 innobackupex: Connection to database server closed
IMPORTANT: Please check that the backup run completes successfully.
At the end of a successful backup run innobackupex
prints “completed OK!”.

innobackupex: Using mysql Ver 15.1 Distrib 10.0.0-MariaDB, for debian-linux-gnu (x86_64) using readline 5.1
innobackupex: Using mysql server version Copyright (c) 2000, 2012, Oracle, Monty Program Ab and others.

innobackupex: Created backup directory /tmp/backup/2013-02-06_12-25-12
130206 12:25:12 innobackupex: Starting mysql with options: --defaults-file=‘/root/sandboxes/msb_5_5_28/my.sandbox.cnf’ --password=xxxxxxxx --user=‘msandbox’ --unbuffered –
130206 12:25:12 innobackupex: Connected to database with mysql child process (pid=1910)
130206 12:25:14 innobackupex: Connection to database server closed

130206 12:25:14 innobackupex: Starting ibbackup with command: xtrabackup_55 --defaults-file=“/root/sandboxes/msb_5_5_28/my.sandbox.cnf” --defaults-group=“mysqld” --backup --suspend-at-end --target-dir=/tmp/backup/2013-02-06_12-25-12
innobackupex: Waiting for ibbackup (pid=1918) to suspend
innobackupex: Suspend file ‘/tmp/backup/2013-02-06_12-25-12/xtrabackup_suspended’

xtrabackup_55 version 2.0.3 for Percona Server 5.5.16 Linux (x86_64) (revision id: undefined)
xtrabackup: uses posix_fadvise().
xtrabackup: cd to /root/sandboxes/msb_5_5_28/data
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 = 5242880
130206 12:25:14 InnoDB: Using Linux native AIO

log scanned up to (1597945)
[01] Copying ./ibdata1 to /tmp/backup/2013-02-06_12-25-12/ibdata1
[01] …done

130206 12:25:18 innobackupex: Continuing after ibbackup has suspended
130206 12:25:18 innobackupex: Starting mysql with options: --defaults-file=‘/root/sandboxes/msb_5_5_28/my.sandbox.cnf’ --password=xxxxxxxx --user=‘msandbox’ --unbuffered –
130206 12:25:18 innobackupex: Connected to database with mysql child process (pid=1933)

log scanned up to (1597945)
130206 12:25:20 innobackupex: Starting to lock all tables…
log scanned up to (1597945)
log scanned up to (1597945)
130206 12:25:31 innobackupex: All tables locked and flushed to disk

130206 12:25:31 innobackupex: Starting to backup .frm, .MRG, .MYD, .MYI,
innobackupex: .TRG, .TRN, .ARM, .ARZ, .CSM, .CSV and .opt files in
innobackupex: subdirectories of ‘/root/sandboxes/msb_5_5_28/data’
innobackupex: Backing up files ‘/root/sandboxes/msb_5_5_28/data/mysql/.{frm,MYD,MYI,MRG,TR G,TRN,ARM,ARZ,CSM,CSV,opt,par}’ (72 files)
innobackupex: Backing up files '/root/sandboxes/msb_5_5_28/data/performance_schema/.{frm,M YD,MYI,MRG,TRG,TRN,ARM,ARZ,CSM,CSV,opt,par}’ (18 files)
130206 12:25:34 innobackupex: Finished backing up .frm, .MRG, .MYD, .MYI, .TRG, .TRN, .ARM, .ARZ, .CSV, .CSM and .opt files

innobackupex: Resuming ibbackup

xtrabackup: The latest check point (for incremental): ‘1597945’
xtrabackup: Stopping log copying thread.
.>> log scanned up to (1597945)

xtrabackup: Transaction log of lsn (1597945) to (1597945) was copied.
130206 12:25:37 innobackupex: All tables unlocked
130206 12:25:37 innobackupex: Connection to database server closed

innobackupex: Backup created in directory ‘/tmp/backup/2013-02-06_12-25-12’
innobackupex: MySQL binlog position: filename ‘’, position
130206 12:25:37 innobackupex: completed OK!