failed to freezer-agent restore data with --consistency-checksum

[Shangzhong Zhu]

Bug Description:
1. Backup data with --consistency-check
zhusz@ubuntu:~/freezer$ freezer-agent --action backup --path-to-backup /home/zhusz/python-freezerclient/ --backup-name backup-test --storage local --container ~/backup20161209 --consistency-check
+----------------------+------------------------------------------------------------------+
| Property | Value |
+----------------------+------------------------------------------------------------------+
| ssh port | 22 |
| consistency checksum | 7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df |
| curr backup level | 0 |
| backup name | backup-test |
| container | /home/zhusz/backup20161209 |
| compression | gzip |
| dry run | |
| hostname | ubuntu |
| storage | local |
| vol snap path | /home/zhusz/python-freezerclient/ |
| os auth version | |
| client os | linux2 |
| time stamp | 1481246470 |
| container segments | |
| ssh username | |
| path to backup | /home/zhusz/python-freezerclient/ |
| ssh key | |
| proxy | |
| always level | |
| max level | |
| backup media | fs |
| ssh host | |
| mode | fs |
| fs real path | /home/zhusz/python-freezerclient/ |
| action | backup |
| client version | 3.0.0 |
| log file | /home/zhusz/.freezer/freezer.log |
+----------------------+------------------------------------------------------------------+
2. Restore data with --consistency-checksum
zhusz@ubuntu:~/freezer$ freezer-agent --action restore --restore-abs-path /home/zhusz/restore20161209-1 --backup-name backup-test --storage local --container ~/backup20161209 --consistency-checksum 7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df
Critical Error: Backup Consistency Check failed: backup checksum (7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df) and restore checksum (5e9cac558eb54a323adb9860f1855fc3792373cfede54e4b2f42a8f275ca8ca7) did not match.

Logs:
2016-12-09 09:21:09.879 8409 INFO freezer.main [-] Begin freezer agent process with args: ['/usr/local/bin/freezer-agent', '--action', 'backup', '--path-to-backup', '/home/zhusz/python-freezerclient/', '--backup-name', 'backup-test', '--storage', 'local', '--container', '/home/zhusz/backup20161209', '--consistency-check']
2016-12-09 09:21:09.879 8409 INFO freezer.main [-] log file at /home/zhusz/.freezer/freezer.log
2016-12-09 09:21:09.958 8409 INFO freezer.job [-] Validating args for the backup job.
2016-12-09 09:21:09.958 8409 INFO freezer.main [-] Job execution Started at: 2016-12-09 09:21:09
2016-12-09 09:21:09.958 8409 INFO freezer.job [-] Backup job started. backup_name: backup-test, container: /home/zhusz/backup20161209, hostname: ubuntu, mode: fs, Storage: local, compression: gzip
2016-12-09 09:21:09.959 8409 INFO freezer.job [-] Executing sync to flush the file system buffer.
2016-12-09 09:21:10.089 8409 INFO freezer.job [-] Path to backup: /home/zhusz/python-freezerclient/
2016-12-09 09:21:40.082 8409 INFO freezer.job [-] Computed checksum for consistency 7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df
2016-12-09 09:21:40.084 8409 INFO freezer.engine.tar.tar [-] Starting Tar engine backup stream
2016-12-09 09:21:40.085 8409 INFO freezer.engine.tar.tar [-] Execution command:
/usr/local/bin/tar --create -z --warning=none --no-check-device --one-file-system --preserve-permissions --same-owner --seek --ignore-failed-read --listed-incremental=/tmp/tmpQYqh48/engine_meta .
2016-12-09 09:21:47.578 8409 INFO freezer.engine.tar.tar [-] Tar engine stream completed
2016-12-09 09:21:47.622 8409 INFO freezer.main [-] Job execution Finished, at: 2016-12-09 09:21:47
2016-12-09 09:21:47.622 8409 INFO freezer.main [-] Job time Elapsed: 0:00:37.663777
2016-12-09 09:21:47.623 8409 INFO freezer.main [-] Backup metadata received: {"ssh_port": 22, "consistency_checksum": "7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df", "curr_backup_level": 0, "backup_name": "backup-test", "container": "/home/zhusz/backup20161209", "compression": "gzip", "dry_run": "", "hostname": "ubuntu", "storage": "local", "vol_snap_path": "/home/zhusz/python-freezerclient/", "os_auth_version": "", "client_os": "linux2", "time_stamp": 1481246470, "container_segments": "", "ssh_username": "", "path_to_backup": "/home/zhusz/python-freezerclient/", "ssh_key": "", "proxy": "", "always_level": "", "max_level": "", "backup_media": "fs", "ssh_host": "", "mode": "fs", "fs_real_path": "/home/zhusz/python-freezerclient/", "action": "backup", "client_version": "3.0.0", "log_file": "/home/zhusz/.freezer/freezer.log"}
2016-12-09 09:21:47.623 8409 INFO freezer.main [-] End freezer agent process successfully
2016-12-09 09:24:36.623 8492 INFO freezer.main [-] Begin freezer agent process with args: ['/usr/local/bin/freezer-agent', '--action', 'restore', '--restore-abs-path', '/home/zhusz/restore20161209-1', '--backup-name', 'backup-test', '--storage', 'local', '--container', '/home/zhusz/backup20161209', '--consistency-checksum', '7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df']

2016-12-09 09:24:36.623 8492 INFO freezer.main [-] log file at /home/zhusz/.freezer/freezer.log
2016-12-09 09:24:36.627 8492 INFO freezer.job [-] Validating args for the restore job.
2016-12-09 09:24:36.627 8492 INFO freezer.main [-] Job execution Started at: 2016-12-09 09:24:36
2016-12-09 09:24:36.627 8492 INFO freezer.job [-] Executing FS restore...
2016-12-09 09:24:36.627 8492 INFO freezer.engine.engine [-] Creating restore path: /home/zhusz/restore20161209-1
2016-12-09 09:24:36.628 8492 INFO freezer.engine.engine [-] Restore path creation completed
2016-12-09 09:24:36.646 8492 INFO freezer.engine.engine [-] Restoring backup ubuntu_backup-test
2016-12-09 09:24:36.658 8498 INFO freezer.storage.base [-] metadata content download ['{"encryption": false, "compression": "gzip", "engine_name": "tar"}']
2016-12-09 09:24:38.345 8498 INFO freezer.engine.tar.tar [-] Pipe closed as EOF reached. Data transmitted successfully
2016-12-09 09:24:38.359 8492 INFO freezer.engine.engine [-] Restore completed successfully for backup name ubuntu_backup-test
2016-12-09 09:24:38.699 8492 ERROR freezer.main [-] Backup Consistency Check failed: backup checksum (7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df) and restore checksum (5e9cac558eb54a323adb9860f1855fc3792373cfede54e4b2f42a8f275ca8ca7) did not match.
2016-12-09 09:24:38.699 8492 ERROR freezer.main Traceback (most recent call last):
2016-12-09 09:24:38.699 8492 ERROR freezer.main File "/usr/local/lib/python2.7/dist-packages/freezer/main.py", line 227, in main
2016-12-09 09:24:38.699 8492 ERROR freezer.main freezer_main(backup_args)
2016-12-09 09:24:38.699 8492 ERROR freezer.main File "/usr/local/lib/python2.7/dist-packages/freezer/main.py", line 111, in freezer_main
2016-12-09 09:24:38.699 8492 ERROR freezer.main return run_job(backup_args, storage)
2016-12-09 09:24:38.699 8492 ERROR freezer.main File "/usr/local/lib/python2.7/dist-packages/freezer/main.py", line 124, in run_job
2016-12-09 09:24:38.699 8492 ERROR freezer.main response = freezer_job.execute()
2016-12-09 09:24:38.699 8492 ERROR freezer.main File "/usr/local/lib/python2.7/dist-packages/freezer/job.py", line 286, in execute
2016-12-09 09:24:38.699 8492 ERROR freezer.main format(backup_checksum, restore_checksum.checksum))
2016-12-09 09:24:38.699 8492 ERROR freezer.main ConsistencyCheckException: Backup Consistency Check failed: backup checksum (7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df) and restore checksum (5e9cac558eb54a323adb9860f1855fc3792373cfede54e4b2f42a8f275ca8ca7) did not match.
2016-12-09 09:24:38.699 8492 ERROR freezer.main
2016-12-09 09:24:38.699 8492 CRITICAL freezer.main [-] End freezer agent process unsuccessfully
2016-12-09 09:24:38.700 8492 CRITICAL freezer.main [-] Critical Error: Backup Consistency Check failed: backup checksum (7abe0deed85ac578f977e5257dca3bf0373da22af944c4d65e279c571aca78df) and restore checksum (5e9cac558eb54a323adb9860f1855fc3792373cfede54e4b2f42a8f275ca8ca7) did not match.