Hello, all.

I try again with patch:
1. cinder backup-create --force b7054216-e902-44bd-b1ea-6df8580808ce:
2016-11-04 21:55:44.726 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-d7b6a1c5-d062-4b3f-afc9-9d128feb7067 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received message msg_id: None reply to None __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:201
2016-11-04 21:55:44.786 167493 INFO cinder.backup.manager [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Create backup started, backup: 09feeaf1-6bbc-4107-93e8-bd90a224e9ee volume: b7054216-e902-44bd-b1ea-6df8580808ce.
2016-11-04 21:55:44.788 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 7a15e8c19ed74801a63cdf515962994e NOTIFY exchange 'cinder' topic 'notifications.info' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:438
2016-11-04 21:55:44.825 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:326
2016-11-04 21:55:44.947 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi" returned: 1 in 0.122s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:356
2016-11-04 21:55:44.948 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:404
2016-11-04 21:55:44.949 167493 WARNING os_brick.initiator.connector [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Could not find the iSCSI Initiator File /etc/iscsi/initiatorname.iscsi
2016-11-04 21:55:44.950 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:326
2016-11-04 21:55:45.065 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v" returned: 1 in 0.116s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:356
2016-11-04 21:55:45.066 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:404
2016-11-04 21:55:45.068 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:326
2016-11-04 21:55:45.185 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v" returned: 1 in 0.117s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:356
2016-11-04 21:55:45.186 167493 DEBUG oslo_concurrency.processutils [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:404
2016-11-04 21:55:45.188 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CALL msg_id: eedc1bd3b7744d20a7dba70141f98642 exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:55:46.460 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received reply msg_id: eedc1bd3b7744d20a7dba70141f98642 __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:302
2016-11-04 21:55:46.462 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CALL msg_id: 4c1c598fea544d19a6dd55992147ab8a exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:55:47.036 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received reply msg_id: 4c1c598fea544d19a6dd55992147ab8a __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:302
2016-11-04 21:55:47.037 167493 DEBUG os_brick.initiator.connector [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Factory for rbd on x86_64 factory /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:180
2016-11-04 21:55:47.172 167493 DEBUG oslo_db.sqlalchemy.engines [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] MySQL server mode set to STRICT_TRANS_TABLES,STRICT_ALL_TABLES,NO_ZERO_IN_DATE,NO_ZERO_DATE,ERROR_FOR_DIVISION_BY_ZERO,TRADITIONAL,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION _check_effective_sql_mode /usr/lib/python2.7/dist-packages/oslo_db/sqlalchemy/engines.py:256
2016-11-04 21:55:47.200 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Starting backup of volume='b7054216-e902-44bd-b1ea-6df8580808ce'. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:852
2016-11-04 21:55:47.201 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Volume file is RBD: attempting incremental backup. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:861
2016-11-04 21:55:47.202 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Using --from-snap 'None' for incremental backup of volume b7054216-e902-44bd-b1ea-6df8580808ce. _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:601
2016-11-04 21:55:47.212 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Creating base image 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _create_base_image /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:351
2016-11-04 21:55:48.322 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Creating backup snapshot='backup.09feeaf1-6bbc-4107-93e8-bd90a224e9ee.snap.1478278548.32' _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:640
2016-11-04 21:55:49.538 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Performing differential transfer from 'volume-b7054216-e902-44bd-b1ea-6df8580808ce' to 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _rbd_diff_transfer /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:526
2016-11-04 21:55:49.538 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Piping cmd1='rbd export-diff --id cinder --conf /etc/ceph/ceph.conf --pool os-volumes <email address hidden> -' into... _piped_execute /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:487
2016-11-04 21:55:49.539 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] cmd2='rbd import-diff --id cinder-backup --conf /etc/ceph/bak.conf --pool backups - backups/volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _piped_execute /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:488
2016-11-04 21:55:49.734 167493 INFO cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2cImporting image diff: 0% complete...failed.ret=33 stderr=
rbd: import-diff failed: (33) Numerical argument out of domain
)
2016-11-04 21:55:49.739 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Differential backup transfer failed _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:670
2016-11-04 21:55:49.773 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Looking for snapshot of backup base 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _get_backup_snap_name /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:771
2016-11-04 21:55:49.774 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Backup base 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' has no snapshots _get_backup_snap_name /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:774
2016-11-04 21:55:49.775 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] No backup snapshot to delete _delete_backup_snapshot /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:381
2016-11-04 21:55:49.781 167493 INFO cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Deleting backup base image='volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' of volume b7054216-e902-44bd-b1ea-6df8580808ce.
2016-11-04 21:55:51.541 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Base backup image='volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' of volume b7054216-e902-44bd-b1ea-6df8580808ce deleted. _try_delete_base_image /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:467
2016-11-04 21:55:51.545 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Deleting diff backup snapshot='backup.09feeaf1-6bbc-4107-93e8-bd90a224e9ee.snap.1478278548.32' of source volume='b7054216-e902-44bd-b1ea-6df8580808ce'. _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:680
2016-11-04 21:55:52.061 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Forcing full backup of volume b7054216-e902-44bd-b1ea-6df8580808ce. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:866
2016-11-04 21:55:52.069 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Creating backup base image='volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.09feeaf1-6bbc-4107-93e8-bd90a224e9ee' for volume b7054216-e902-44bd-b1ea-6df8580808ce. _full_backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:701
2016-11-04 21:55:52.163 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Copying data from volume b7054216-e902-44bd-b1ea-6df8580808ce. _full_backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:710
2016-11-04 21:55:52.179 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferring data between 'volume-b7054216-e902-44bd-b1ea-6df8580808ce' and 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.09feeaf1-6bbc-4107-93e8-bd90a224e9ee' _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:302
2016-11-04 21:55:52.179 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] 8 chunks of 134217728 bytes to be transferred _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:306
2016-11-04 21:55:54.158 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 1 of 8 (66267K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:55:54.159 167493 DEBUG oslo_service.periodic_task [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running periodic task BackupManager._publish_service_capabilities run_periodic_tasks /usr/lib/python2.7/dist-packages/oslo_service/periodic_task.py:215
2016-11-04 21:55:55.520 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 2 of 8 (96385K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:55:56.949 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 3 of 8 (91987K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:55:58.341 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 4 of 8 (94315K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:55:59.859 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 5 of 8 (86570K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:56:01.368 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 6 of 8 (87005K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:56:02.814 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 7 of 8 (90685K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:56:04.242 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 8 of 8 (91908K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:56:04.244 167493 WARNING oslo.service.loopingcall [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 4.52 sec
2016-11-04 21:56:04.327 167493 DEBUG cinder.backup.driver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Getting metadata type 'volume-base-metadata' _save_vol_base_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:80
2016-11-04 21:56:04.384 167493 DEBUG cinder.backup.driver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Completed fetching metadata type 'volume-base-metadata' _save_vol_base_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:96
2016-11-04 21:56:04.385 167493 DEBUG cinder.backup.driver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Getting metadata type 'volume-metadata' _save_vol_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:107
2016-11-04 21:56:04.483 167493 DEBUG cinder.backup.driver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] No metadata type 'volume-metadata' available _save_vol_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:121
2016-11-04 21:56:04.484 167493 DEBUG cinder.backup.driver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Getting metadata type 'volume-glance-metadata' _save_vol_glance_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:130
2016-11-04 21:56:04.581 167493 DEBUG cinder.backup.driver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Completed fetching metadata type 'volume-glance-metadata' _save_vol_glance_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:143
2016-11-04 21:56:04.582 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Backing up metadata for volume b7054216-e902-44bd-b1ea-6df8580808ce. _backup_metadata /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:831
2016-11-04 21:56:04.626 167493 DEBUG cinder.backup.drivers.ceph [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Backup '09feeaf1-6bbc-4107-93e8-bd90a224e9ee' of volume b7054216-e902-44bd-b1ea-6df8580808ce finished. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:888
2016-11-04 21:56:04.627 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CALL msg_id: 77dda47cf5a840a4b06ce7a5e668ad08 exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:56:04.688 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received reply msg_id: 77dda47cf5a840a4b06ce7a5e668ad08 __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:302
2016-11-04 21:56:04.690 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 25102110108c4495b45103877e1111ba exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:56:04.793 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: cb0a8ca289234321994403600d6c8ca6 exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:56:05.086 167493 INFO cinder.backup.manager [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Create backup finished. backup: 09feeaf1-6bbc-4107-93e8-bd90a224e9ee.
2016-11-04 21:56:05.088 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 1d84f40978e9490f8ab3846d75293aec NOTIFY exchange 'cinder' topic 'notifications.info' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:438


2. cinder backup-create --incremental --force b7054216-e902-44bd-b1ea-6df8580808ce:
==> /var/log/cinder/cinder-backup.log <==
2016-11-04 21:58:34.095 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-2f3fe860-2bc0-4a23-9700-4de20a017920 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received message msg_id: None reply to None __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:201
2016-11-04 21:58:34.159 167493 INFO cinder.backup.manager [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Create backup started, backup: 9d54adf4-fb28-471b-8f82-3707b277ce33 volume: b7054216-e902-44bd-b1ea-6df8580808ce.
2016-11-04 21:58:34.161 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 27983760a2e345c49be8649df1f47e21 NOTIFY exchange 'cinder' topic 'notifications.info' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:438
2016-11-04 21:58:34.194 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:326
2016-11-04 21:58:34.326 167493 DEBUG oslo_db.sqlalchemy.engines [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] MySQL server mode set to STRICT_TRANS_TABLES,STRICT_ALL_TABLES,NO_ZERO_IN_DATE,NO_ZERO_DATE,ERROR_FOR_DIVISION_BY_ZERO,TRADITIONAL,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION _check_effective_sql_mode /usr/lib/python2.7/dist-packages/oslo_db/sqlalchemy/engines.py:256
2016-11-04 21:58:34.357 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi" returned: 1 in 0.163s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:356
2016-11-04 21:58:34.358 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf cat /etc/iscsi/initiatorname.iscsi' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:404
2016-11-04 21:58:34.360 167493 WARNING os_brick.initiator.connector [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Could not find the iSCSI Initiator File /etc/iscsi/initiatorname.iscsi
2016-11-04 21:58:34.361 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:326
2016-11-04 21:58:34.503 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v" returned: 1 in 0.142s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:356
2016-11-04 21:58:34.504 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:404
2016-11-04 21:58:34.506 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:326
2016-11-04 21:58:34.660 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CMD "sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v" returned: 1 in 0.154s execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:356
2016-11-04 21:58:34.662 167493 DEBUG oslo_concurrency.processutils [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] u'sudo cinder-rootwrap /etc/cinder/rootwrap.conf systool -c fc_host -v' failed. Not Retrying. execute /usr/lib/python2.7/dist-packages/oslo_concurrency/processutils.py:404
2016-11-04 21:58:34.664 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CALL msg_id: d0c60221cd0b4d448e2a67e0e16f06fb exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:58:36.646 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received reply msg_id: d0c60221cd0b4d448e2a67e0e16f06fb __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:302
2016-11-04 21:58:36.648 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CALL msg_id: 81ab6f8a6bed4d2087ce82484b204c02 exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:58:37.035 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received reply msg_id: 81ab6f8a6bed4d2087ce82484b204c02 __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:302
2016-11-04 21:58:37.036 167493 DEBUG os_brick.initiator.connector [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Factory for rbd on x86_64 factory /usr/lib/python2.7/dist-packages/os_brick/initiator/connector.py:180
2016-11-04 21:58:37.186 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Starting backup of volume='b7054216-e902-44bd-b1ea-6df8580808ce'. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:852
2016-11-04 21:58:37.186 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Volume file is RBD: attempting incremental backup. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:861
2016-11-04 21:58:37.187 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Using --from-snap 'None' for incremental backup of volume b7054216-e902-44bd-b1ea-6df8580808ce. _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:601
2016-11-04 21:58:37.202 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Creating base image 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _create_base_image /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:351
2016-11-04 21:58:38.231 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Creating backup snapshot='backup.9d54adf4-fb28-471b-8f82-3707b277ce33.snap.1478278718.23' _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:640
2016-11-04 21:58:38.729 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Performing differential transfer from 'volume-b7054216-e902-44bd-b1ea-6df8580808ce' to 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _rbd_diff_transfer /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:526
2016-11-04 21:58:38.730 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Piping cmd1='rbd export-diff --id cinder --conf /etc/ceph/ceph.conf --pool os-volumes <email address hidden> -' into... _piped_execute /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:487
2016-11-04 21:58:38.731 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] cmd2='rbd import-diff --id cinder-backup --conf /etc/ceph/bak.conf --pool backups - backups/volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _piped_execute /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:488
2016-11-04 21:58:38.862 167493 INFO cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2cImporting image diff: 0% complete...failed.ret=33 stderr=
rbd: import-diff failed: (33) Numerical argument out of domain
)
2016-11-04 21:58:38.863 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Differential backup transfer failed _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:670
2016-11-04 21:58:38.885 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Looking for snapshot of backup base 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' _get_backup_snap_name /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:771
2016-11-04 21:58:38.885 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Backup base 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' has no snapshots _get_backup_snap_name /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:774
2016-11-04 21:58:38.886 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] No backup snapshot to delete _delete_backup_snapshot /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:381
2016-11-04 21:58:38.889 167493 INFO cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Deleting backup base image='volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' of volume b7054216-e902-44bd-b1ea-6df8580808ce.
2016-11-04 21:58:41.512 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Base backup image='volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.base' of volume b7054216-e902-44bd-b1ea-6df8580808ce deleted. _try_delete_base_image /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:467
2016-11-04 21:58:41.517 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Deleting diff backup snapshot='backup.9d54adf4-fb28-471b-8f82-3707b277ce33.snap.1478278718.23' of source volume='b7054216-e902-44bd-b1ea-6df8580808ce'. _backup_rbd /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:680
2016-11-04 21:58:42.354 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Forcing full backup of volume b7054216-e902-44bd-b1ea-6df8580808ce. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:866
2016-11-04 21:58:42.361 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Creating backup base image='volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.9d54adf4-fb28-471b-8f82-3707b277ce33' for volume b7054216-e902-44bd-b1ea-6df8580808ce. _full_backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:701
2016-11-04 21:58:42.485 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Copying data from volume b7054216-e902-44bd-b1ea-6df8580808ce. _full_backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:710
2016-11-04 21:58:42.497 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferring data between 'volume-b7054216-e902-44bd-b1ea-6df8580808ce' and 'volume-b7054216-e902-44bd-b1ea-6df8580808ce.backup.9d54adf4-fb28-471b-8f82-3707b277ce33' _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:302
2016-11-04 21:58:42.499 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] 8 chunks of 134217728 bytes to be transferred _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:306
2016-11-04 21:58:44.401 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 1 of 8 (68948K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:45.904 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 2 of 8 (87412K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:47.335 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 3 of 8 (91785K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:48.853 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 4 of 8 (86508K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:50.318 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 5 of 8 (89552K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:51.813 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 6 of 8 (87891K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:53.254 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 7 of 8 (91145K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:54.688 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Transferred chunk 8 of 8 (91650K/s) _transfer_data /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:328
2016-11-04 21:58:54.690 167493 DEBUG oslo_service.periodic_task [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Running periodic task BackupManager._publish_service_capabilities run_periodic_tasks /usr/lib/python2.7/dist-packages/oslo_service/periodic_task.py:215
2016-11-04 21:58:54.742 167493 WARNING oslo.service.loopingcall [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Function 'cinder.service.Service.report_state' run outlasted interval by 0.34 sec
2016-11-04 21:58:54.771 167493 DEBUG cinder.backup.driver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Getting metadata type 'volume-base-metadata' _save_vol_base_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:80
2016-11-04 21:58:54.822 167493 DEBUG cinder.backup.driver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Completed fetching metadata type 'volume-base-metadata' _save_vol_base_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:96
2016-11-04 21:58:54.824 167493 DEBUG cinder.backup.driver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Getting metadata type 'volume-metadata' _save_vol_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:107
2016-11-04 21:58:54.923 167493 DEBUG cinder.backup.driver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] No metadata type 'volume-metadata' available _save_vol_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:121
2016-11-04 21:58:54.924 167493 DEBUG cinder.backup.driver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Getting metadata type 'volume-glance-metadata' _save_vol_glance_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:130
2016-11-04 21:58:55.021 167493 DEBUG cinder.backup.driver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Completed fetching metadata type 'volume-glance-metadata' _save_vol_glance_meta /usr/lib/python2.7/dist-packages/cinder/backup/driver.py:143
2016-11-04 21:58:55.022 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Backing up metadata for volume b7054216-e902-44bd-b1ea-6df8580808ce. _backup_metadata /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:831
2016-11-04 21:58:55.033 167493 DEBUG cinder.backup.drivers.ceph [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Backup '9d54adf4-fb28-471b-8f82-3707b277ce33' of volume b7054216-e902-44bd-b1ea-6df8580808ce finished. backup /usr/lib/python2.7/dist-packages/cinder/backup/drivers/ceph.py:888
2016-11-04 21:58:55.035 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CALL msg_id: 43be451ad1cb446c937923db715afe5a exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:58:55.091 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] received reply msg_id: 43be451ad1cb446c937923db715afe5a __call__ /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:302
2016-11-04 21:58:55.093 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 1e97e415c29243daaf3ad7db2ab93d6d exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:58:55.153 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 3be3ef4bf6d44da1b144f09ce01584fd exchange 'cinder' topic 'cinder-volume' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:454
2016-11-04 21:58:55.356 167493 INFO cinder.backup.manager [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] Create backup finished. backup: 9d54adf4-fb28-471b-8f82-3707b277ce33.
2016-11-04 21:58:55.358 167493 DEBUG oslo_messaging._drivers.amqpdriver [req-3ced9445-b2ec-4cf3-94b2-ce8b00d32ad7 8d384cc45d0c467eb46ef3f980cf314d 3029462986ac400ca2c2c6ac4c9af5a7 - - -] CAST unique_id: 1e5334500c4e49a299ac787ebeec1bca NOTIFY exchange 'cinder' topic 'notifications.info' _send /usr/lib/python2.7/dist-packages/oslo_messaging/_drivers/amqpdriver.py:438