Skip to content

Instantly share code, notes, and snippets.

@sombrafam

sombrafam/hds-iscsi.log

Created March 11, 2014 17:12
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save sombrafam/9490408 to your computer and use it in GitHub Desktop.
Save sombrafam/9490408 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
hds-iscsi.log
ubuntu@ubuntu:/opt/stack/tempest$ cd /opt/stack/cinder && /opt/stack/cinder/bin/cinder-volume --config-file /etc/cinder/cinder.conf & echo $! >/opt/stack/status/stack/c-vol.pid; fg || echo "c-vol failed to start" | tee "/opt/stack/status/stack/c-vol.failure"
[1] 22014
cd /opt/stack/cinder && /opt/stack/cinder/bin/cinder-volume --config-file /etc/cinder/cinder.conf
2014-03-11 12:09:40.670 INFO cinder.volume.drivers.hds.iscsi [-] mgmt_ip0: 10.0.80.47
2014-03-11 12:09:40.671 INFO cinder.volume.drivers.hds.iscsi [-] username: supervisor
2014-03-11 12:09:40.671 INFO cinder.volume.drivers.hds.iscsi [-] password: supervisor
2014-03-11 12:09:40.671 INFO cinder.volume.drivers.hds.iscsi [-] hnas_cmd: None
2014-03-11 12:09:40.671 INFO cinder.volume.drivers.hds.iscsi [-] chap_enabled: True
2014-03-11 12:09:40.671 INFO cinder.volume.drivers.hds.iscsi [-] svc_0:
2014-03-11 12:09:40.672 INFO cinder.volume.drivers.hds.iscsi [-] svc_0/volume_type: default
2014-03-11 12:09:40.672 INFO cinder.volume.drivers.hds.iscsi [-] svc_0/hdp: fs-01
2014-03-11 12:09:40.672 INFO cinder.volume.drivers.hds.iscsi [-] svc_0/iscsi_ip: 10.0.80.31
2014-03-11 12:09:40.672 INFO cinder.volume.drivers.hds.iscsi [-] svc_1: None
2014-03-11 12:09:40.672 INFO cinder.volume.drivers.hds.iscsi [-] svc_2: None
2014-03-11 12:09:40.673 INFO cinder.volume.drivers.hds.iscsi [-] svc_3: None
2014-03-11 12:09:40.673 INFO cinder.volume.drivers.hds.iscsi [-] Backend type: HNAS
2014-03-11 12:09:40.673 INFO cinder.openstack.common.service [-] Starting 1 workers
2014-03-11 12:09:40.674 INFO cinder.openstack.common.service [-] Started child 22021
2014-03-11 12:09:40.675 DEBUG cinder.openstack.common.service [-] Full set of CONF: from (pid=22015) wait /opt/stack/cinder/cinder/openstack/common/service.py:384
2014-03-11 12:09:40.676 DEBUG cinder.openstack.common.service [-] ******************************************************************************** from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1900
2014-03-11 12:09:40.676 DEBUG cinder.openstack.common.service [-] Configuration options gathered from: from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1901
2014-03-11 12:09:40.676 DEBUG cinder.openstack.common.service [-] command line args: ['--config-file', '/etc/cinder/cinder.conf'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1902
2014-03-11 12:09:40.676 DEBUG cinder.openstack.common.service [-] config files: ['/etc/cinder/cinder.conf'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1903
2014-03-11 12:09:40.676 DEBUG cinder.openstack.common.service [-] ================================================================================ from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1904
2014-03-11 12:09:40.677 DEBUG cinder.openstack.common.service [-] allowed_direct_url_schemes = [] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.677 DEBUG cinder.openstack.common.service [-] allowed_rpc_exception_modules = ['nova.exception', 'cinder.exception', 'exceptions'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.677 DEBUG cinder.openstack.common.service [-] api_paste_config = /etc/cinder/api-paste.ini from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.677 DEBUG cinder.openstack.common.service [-] api_rate_limit = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.678 DEBUG cinder.openstack.common.service [-] auth_strategy = keystone from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.678 AUDIT cinder.service [-] Starting cinder-volume node (version 2014.1)
2014-03-11 12:09:40.678 DEBUG cinder.openstack.common.service [-] backdoor_port = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.678 DEBUG cinder.openstack.common.service [-] backup_api_class = cinder.backup.api.API from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.678 DEBUG cinder.openstack.common.service [-] backup_manager = cinder.backup.manager.BackupManager from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.679 DEBUG cinder.openstack.common.service [-] backup_name_template = backup-%s from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.679 DEBUG cinder.openstack.common.service [-] backup_topic = cinder-backup from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.679 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "dbapi_backend" for method "__get_backend"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:09:40.679 DEBUG cinder.openstack.common.service [-] cloned_volume_same_az = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.679 DEBUG cinder.openstack.common.service [-] compute_api_class = cinder.compute.nova.API from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.679 DEBUG cinder.openstack.common.service [-] config_dir = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.680 DEBUG cinder.openstack.common.service [-] config_file = ['/etc/cinder/cinder.conf'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.680 DEBUG cinder.openstack.common.service [-] control_exchange = openstack from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.680 DEBUG cinder.openstack.common.service [-] db_backend = sqlalchemy from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.680 DEBUG cinder.openstack.common.service [-] db_driver = cinder.db from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.680 DEBUG cinder.openstack.common.service [-] debug = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.681 DEBUG cinder.openstack.common.service [-] default_availability_zone = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.681 DEBUG cinder.openstack.common.service [-] default_log_levels = ['amqp=WARN', 'amqplib=WARN', 'boto=WARN', 'qpid=WARN', 'sqlalchemy=WARN', 'suds=INFO', 'iso8601=WARN'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.681 DEBUG cinder.openstack.common.service [-] default_notification_level = INFO from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.681 DEBUG cinder.openstack.common.service [-] default_publisher_id = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.682 DEBUG cinder.openstack.common.service [-] default_volume_type = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.682 DEBUG cinder.openstack.common.service [-] disable_process_locking = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.682 DEBUG cinder.openstack.common.service [-] enable_new_services = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.682 DEBUG cinder.openstack.common.service [-] enable_v1_api = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.682 DEBUG cinder.openstack.common.service [-] enable_v2_api = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.683 DEBUG cinder.openstack.common.service [-] enabled_backends = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.683 DEBUG cinder.openstack.common.service [-] fake_rabbit = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.683 DEBUG cinder.openstack.common.service [-] fatal_deprecations = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.683 DEBUG cinder.openstack.common.service [-] fatal_exception_format_errors = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.684 DEBUG cinder.openstack.common.service [-] glance_api_insecure = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.684 DEBUG cinder.openstack.common.service [-] glance_api_servers = ['192.168.1.56:9292'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.684 DEBUG cinder.openstack.common.service [-] glance_api_ssl_compression = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.685 DEBUG cinder.openstack.common.service [-] glance_api_version = 1 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.685 DEBUG cinder.openstack.common.service [-] glance_host = 192.168.1.56 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.685 DEBUG cinder.openstack.common.service [-] glance_num_retries = 0 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.685 DEBUG cinder.openstack.common.service [-] glance_port = 9292 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.685 DEBUG cinder.openstack.common.service [-] glance_request_timeout = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.685 DEBUG cinder.openstack.common.service [-] hds_iscsi_config_file = /opt/hds/hnas/cinder_iscsi_conf.xml from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.686 DEBUG cinder.openstack.common.service [-] host = ubuntu from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.686 DEBUG cinder.openstack.common.service [-] iet_conf = /etc/iet/ietd.conf from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.686 DEBUG cinder.openstack.common.service [-] image_conversion_dir = /opt/stack/data/cinder/conversion from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.686 DEBUG cinder.openstack.common.service [-] instance_format = [instance: %(uuid)s] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.687 DEBUG cinder.openstack.common.service [-] instance_uuid_format = [instance: %(uuid)s] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.687 DEBUG cinder.openstack.common.service [-] iscsi_helper = tgtadm from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.687 DEBUG cinder.openstack.common.service [-] iscsi_iotype = fileio from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.687 DEBUG cinder.openstack.common.service [-] iscsi_ip_address = 192.168.1.56 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.687 DEBUG cinder.openstack.common.service [-] iscsi_num_targets = 100 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.688 DEBUG cinder.openstack.common.service [-] iscsi_port = 3260 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.688 DEBUG cinder.openstack.common.service [-] iscsi_target_prefix = iqn.2010-10.org.openstack: from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.688 DEBUG cinder.openstack.common.service [-] iser_helper = tgtadm from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.688 DEBUG cinder.openstack.common.service [-] iser_ip_address = 192.168.1.56 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.688 DEBUG cinder.openstack.common.service [-] iser_num_targets = 100 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.689 DEBUG cinder.openstack.common.service [-] iser_port = 3260 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.689 DEBUG cinder.openstack.common.service [-] iser_target_prefix = iqn.2010-10.org.iser.openstack: from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.689 DEBUG cinder.openstack.common.service [-] lio_initiator_iqns = from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.689 DEBUG cinder.openstack.common.service [-] lock_path = /opt/stack/data/cinder from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.689 DEBUG cinder.openstack.common.service [-] log_config_append = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.690 DEBUG cinder.openstack.common.service [-] log_date_format = %Y-%m-%d %H:%M:%S from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.690 DEBUG cinder.openstack.common.service [-] log_dir = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.690 DEBUG cinder.openstack.common.service [-] log_file = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.690 DEBUG cinder.openstack.common.service [-] log_format = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.690 DEBUG cinder.openstack.common.service [-] logging_context_format_string = %(asctime)s.%(msecs)03d %(color)s%(levelname)s %(name)s [%(request_id)s %(user_id)s %(project_id)s%(color)s] %(instance)s%(color)s%(message)s from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.690 DEBUG cinder.openstack.common.service [-] logging_debug_format_suffix = from (pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.691 DEBUG cinder.openstack.common.service [-] logging_default_format_string = %(asctime)s.%(msecs)03d %(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.691 DEBUG cinder.openstack.common.service [-] logging_exception_prefix = %(color)s%(asctime)s.%(msecs)03d TRACE %(name)s %(instance)s from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.691 DEBUG cinder.openstack.common.service [-] max_age = 0 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.691 DEBUG cinder.openstack.common.service [-] max_header_line = 16384 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.691 DEBUG cinder.openstack.common.service [-] migration_create_volume_timeout_secs = 300 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.692 DEBUG cinder.openstack.common.service [-] monkey_patch = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.692 DEBUG cinder.openstack.common.service [-] monkey_patch_modules = [] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.692 DEBUG cinder.openstack.common.service [-] my_ip = 192.168.1.56 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.692 DEBUG cinder.openstack.common.service [-] no_snapshot_gb_quota = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.692 DEBUG cinder.openstack.common.service [-] notification_driver = [] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.693 DEBUG cinder.openstack.common.service [-] num_iser_scan_tries = 3 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.693 DEBUG cinder.openstack.common.service [-] num_shell_tries = 3 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.693 DEBUG cinder.openstack.common.service [-] num_volume_device_scan_tries = 3 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.693 DEBUG cinder.openstack.common.service [-] osapi_volume_ext_list = [] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.693 DEBUG cinder.openstack.common.service [-] osapi_volume_extension = ['cinder.api.contrib.standard_extensions'] from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.693 DEBUG cinder.openstack.common.service [-] osapi_volume_listen = 0.0.0.0 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.694 DEBUG cinder.openstack.common.service [-] osapi_volume_listen_port = 8776 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.694 DEBUG cinder.openstack.common.service [-] osapi_volume_workers = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.694 DEBUG cinder.openstack.common.service [-] periodic_fuzzy_delay = 60 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.694 DEBUG cinder.openstack.common.service [-] periodic_interval = 60 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.695 DEBUG cinder.openstack.common.service [-] policy_default_rule = default from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.695 DEBUG cinder.openstack.common.service [-] policy_file = policy.json from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.695 DEBUG cinder.openstack.common.service [-] publish_errors = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.695 DEBUG cinder.openstack.common.service [-] quota_driver = cinder.quota.DbQuotaDriver from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.695 DEBUG cinder.openstack.common.service [-] quota_gigabytes = 1000 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.695 DEBUG cinder.openstack.common.service [-] quota_snapshots = 10 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.696 DEBUG cinder.openstack.common.service [-] quota_volumes = 10 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.696 DEBUG cinder.openstack.common.service [-] report_interval = 10 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.696 DEBUG cinder.openstack.common.service [-] reservation_expire = 86400 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.696 DEBUG cinder.openstack.common.service [-] reserved_percentage = 0 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.696 DEBUG cinder.openstack.common.service [-] rootwrap_config = /etc/cinder/rootwrap.conf from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.697 DEBUG cinder.openstack.common.service [-] rpc_backend = cinder.openstack.common.rpc.impl_kombu from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.697 DEBUG cinder.openstack.common.service [-] rpc_cast_timeout = 30 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.697 DEBUG cinder.openstack.common.service [-] rpc_conn_pool_size = 30 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.697 DEBUG cinder.openstack.common.service [-] rpc_response_timeout = 60 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.697 DEBUG cinder.openstack.common.service [-] rpc_thread_pool_size = 64 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.698 DEBUG cinder.openstack.common.service [-] run_external_periodic_tasks = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.698 DEBUG cinder.openstack.common.service [-] scheduler_manager = cinder.scheduler.manager.SchedulerManager from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.698 DEBUG cinder.openstack.common.service [-] scheduler_topic = cinder-scheduler from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.698 DEBUG cinder.openstack.common.service [-] service_down_time = 60 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.698 DEBUG cinder.openstack.common.service [-] snapshot_name_template = snapshot-%s from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.698 DEBUG cinder.openstack.common.service [-] snapshot_same_host = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.699 DEBUG cinder.openstack.common.service [-] ssl_ca_file = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.699 DEBUG cinder.openstack.common.service [-] ssl_cert_file = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.699 DEBUG cinder.openstack.common.service [-] ssl_key_file = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.699 DEBUG cinder.openstack.common.service [-] state_path = /opt/stack/data/cinder from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.699 DEBUG cinder.openstack.common.service [-] storage_availability_zone = nova from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.700 DEBUG cinder.openstack.common.service [-] syslog_log_facility = LOG_USER from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.700 DEBUG cinder.openstack.common.service [-] tcp_keepidle = 600 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.700 DEBUG cinder.openstack.common.service [-] transfer_api_class = cinder.transfer.api.API from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.700 DEBUG cinder.openstack.common.service [-] until_refresh = 0 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.700 DEBUG cinder.openstack.common.service [-] use_default_quota_class = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.700 DEBUG cinder.openstack.common.service [-] use_multipath_for_image_xfer = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.701 DEBUG cinder.openstack.common.service [-] use_stderr = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.701 DEBUG cinder.openstack.common.service [-] use_syslog = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.701 DEBUG cinder.openstack.common.service [-] verbose = True from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.701 DEBUG cinder.openstack.common.service [-] volume_api_class = cinder.volume.api.API from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.701 DEBUG cinder.openstack.common.service [-] volume_backend_name = None from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.702 DEBUG cinder.openstack.common.service [-] volume_clear = none from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.702 DEBUG cinder.openstack.common.service [-] volume_clear_size = 0 from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.702 DEBUG cinder.openstack.common.service [-] volume_dd_blocksize = 1M from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.702 DEBUG cinder.openstack.common.service [-] volume_driver = cinder.volume.drivers.hds.iscsi.HDSISCSIDriver from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.702 DEBUG cinder.openstack.common.service [-] volume_manager = cinder.volume.manager.VolumeManager from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.703 DEBUG cinder.openstack.common.service [-] volume_name_template = volume-%s from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.703 DEBUG cinder.openstack.common.service [-] volume_service_inithost_offload = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.703 DEBUG cinder.openstack.common.service [-] volume_topic = cinder-volume from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.703 DEBUG cinder.openstack.common.service [-] volume_usage_audit_period = month from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.703 DEBUG cinder.openstack.common.service [-] volumes_dir = /opt/stack/data/cinder/volumes from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1913
2014-03-11 12:09:40.704 DEBUG cinder.openstack.common.service [-] keymgr.api_class = cinder.keymgr.conf_key_mgr.ConfKeyManager from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1921
2014-03-11 12:09:40.704 DEBUG cinder.openstack.common.service [-] database.backend = sqlalchemy from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1921
2014-03-11 12:09:40.704 DEBUG cinder.openstack.common.service [-] database.use_tpool = False from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1921
2014-03-11 12:09:40.704 DEBUG cinder.openstack.common.service [-] ******************************************************************************** from (pid=22015) log_opt_values /opt/stack/oslo.config/oslo/config/cfg.py:1923
2014-03-11 12:09:41.003 INFO cinder.openstack.common.rpc.common [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Connected to AMQP server on localhost:5672
2014-03-11 12:09:41.003 DEBUG cinder.service [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Creating Consumer connection for Service cinder-volume from (pid=22021) start /opt/stack/cinder/cinder/service.py:109
2014-03-11 12:09:41.011 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Starting volume driver HDSISCSIDriver (N/A)
2014-03-11 12:09:41.012 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -version from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:09:41.019 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 cluster-getmac from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:09:42.284 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 ver from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:09:43.706 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_version: Array_ID: 95-25-E4-3D-FD-EC (3090-G2) version: 11.1.3225.02 LU: 256 RG: 0 RG_LU: 0 Utility_version: 11.1.3225.01 -- from (pid=22021) get_version /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:65
2014-03-11 12:09:43.707 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 df -a from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:09:46.207 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_hdp_info: HDP: 1024 112000 MB 56600 MB 50 % LUs: 256 Normal fs-01
HDP: 1025 4940 MB 2520 MB 51 % LUs: 256 Normal HN1
HDP: 1026 9940 MB 3540 MB 36 % LUs: 256 Normal HNdemo
HDP: 1029 28100 MB 2380 MB 8 % LUs: 256 Normal TestFS
HDP: 1030 28100 MB 2380 MB 8 % LUs: 256 Normal TestNewFS
HDP: 1031 28100 MB 2380 MB 8 % LUs: 256 Normal Amazon
HDP: 1032 28100 MB 2190 MB 8 % LUs: 256 Normal Liz_test
-- from (pid=22021) get_hdp_info /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:136
2014-03-11 12:09:46.208 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] HDP list: ['fs-01', 'HN1', 'HNdemo', 'TestFS', 'TestNewFS', 'Amazon', 'Liz_test']
2014-03-11 12:09:46.209 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsipaddr -l from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:09:57.495 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_iscsi_info: EVS Type Label IP Address Mask Port
---------- --------------- ------------------ --------------- ------
admin ADMIN 192.0.2.2 255.255.255.0 eth1
admin ADMIN 10.0.80.47 255.255.255.0 ag1
evs 1 EVS01-Source 10.0.80.31 255.0.0.0 ag1
evs 2 EVS02 10.0.80.32 255.0.0.0 ag1
evs 3 EVS03 10.0.80.33 255.0.0.0 ag1
evs 4 EVS04-Target 10.0.80.34 255.0.0.0 ag1
-- from (pid=22021) get_iscsi_info /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:91
2014-03-11 12:09:57.496 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] portal: 10.0.80.31:3260, CTL: 1, port: 0 from (pid=22021) _get_iscsi_info /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:184
2014-03-11 12:09:57.496 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] portal: 10.0.80.32:3260, CTL: 2, port: 0 from (pid=22021) _get_iscsi_info /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:184
2014-03-11 12:09:57.497 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] portal: 10.0.80.33:3260, CTL: 3, port: 0 from (pid=22021) _get_iscsi_info /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:184
2014-03-11 12:09:57.497 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] portal: 10.0.80.34:3260, CTL: 4, port: 0 from (pid=22021) _get_iscsi_info /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:184
2014-03-11 12:09:57.498 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] do_setup: {'10.0.80.34': {'ctl': '4', 'iscsi_port': '3260', 'port': '0'}, '10.0.80.32': {'ctl': '2', 'iscsi_port': '3260', 'port': '0'}, '10.0.80.33': {'ctl': '3', 'iscsi_port': '3260', 'port': '0'}, '10.0.80.31': {'ctl': '1', 'iscsi_port': '3260', 'port': '0'}}
2014-03-11 12:09:57.498 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] iSCSI portal found for service: 10.0.80.31
2014-03-11 12:09:57.518 DEBUG cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Re-exporting 7 volumes from (pid=22021) init_host /opt/stack/cinder/cinder/volume/manager.py:244
2014-03-11 12:09:57.518 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 0821cab8-bb83-4085-8337-62ba639c8e97: skipping export
2014-03-11 12:09:57.518 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 4c56c0ac-799b-45ca-8c04-5a8756e0f796: skipping export
2014-03-11 12:09:57.519 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 5568b612-89b2-4a79-bf53-ef9735747c3c: skipping export
2014-03-11 12:09:57.519 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 6324fc12-be4a-4f3b-9e9b-feca759785c7: skipping export
2014-03-11 12:09:57.519 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume a42c7bc0-0408-4b94-8465-479397ddcf6d: skipping export
2014-03-11 12:09:57.519 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume c0fca468-db6c-44d3-902b-01384bd00ce0: skipping export
2014-03-11 12:09:57.519 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume dba3c5f2-01ad-4c36-858f-cfcb302afaa4: skipping export
2014-03-11 12:09:57.520 DEBUG cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Resuming any in progress delete operations from (pid=22021) init_host /opt/stack/cinder/cinder/volume/manager.py:283
2014-03-11 12:09:57.520 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Resuming delete on volume: 5568b612-89b2-4a79-bf53-ef9735747c3c
2014-03-11 12:09:57.520 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "5568b612-89b2-4a79-bf53-ef9735747c3c-delete_volume" for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:09:57.520 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "5568b612-89b2-4a79-bf53-ef9735747c3c-delete_volume" for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:09:57.534 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "5568b612-89b2-4a79-bf53-ef9735747c3c-delete_volume" at /opt/stack/data/cinder/cinder-5568b612-89b2-4a79-bf53-ef9735747c3c-delete_volume for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:09:57.561 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 5568b612-89b2-4a79-bf53-ef9735747c3c: deleting
2014-03-11 12:09:57.561 DEBUG cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 5568b612-89b2-4a79-bf53-ef9735747c3c: removing export from (pid=22021) delete_volume /opt/stack/cinder/cinder/volume/manager.py:389
2014-03-11 12:09:57.561 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] remove_export provider 95-25-E4-3D-FD-EC.volume-5568b612-89b2-4a79-bf53-ef9735747c3c on volume-5568b612-89b2-4a79-bf53-ef9735747c3c from (pid=22021) remove_export /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:418
2014-03-11 12:09:57.562 DEBUG cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 5568b612-89b2-4a79-bf53-ef9735747c3c: deleting from (pid=22021) delete_volume /opt/stack/cinder/cinder/volume/manager.py:391
2014-03-11 12:09:57.562 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "hds_hnas" for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:09:57.562 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "hds_hnas" for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:09:57.562 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:09:57.563 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Parse_loc: 95-25-E4-3D-FD-EC.volume-5568b612-89b2-4a79-bf53-ef9735747c3c
2014-03-11 12:09:57.563 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] delete lun volume-5568b612-89b2-4a79-bf53-ef9735747c3c on hnas_95-25-E4-3D-FD-EC from (pid=22021) delete_volume /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:526
2014-03-11 12:09:57.563 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] No secret configuration found for service: default
2014-03-11 12:09:57.563 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p 2014-03-11 12:10:01.317 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_evs: out 42
FS ID FS Label FS Permanent ID EVS ID EVS Label nc 12$(L) n-xvnc 13$(L) n-cauth 14$(L) n-obj 15$(L) c-api 16-$(L) c-sch 17$(L) c-vol*
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:10:01.318 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-target list cinder-default from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:02.815 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] set tgt CHAP default secret None
2014-03-11 12:10:02.815 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] No target configuration found for service: default
2014-03-11 12:10:02.816 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsfs list from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:04.766 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_evs: out
FS ID FS Label FS Permanent ID EVS ID EVS Label
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:10:04.767 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-target list cinder-default from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:06.093 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsfs list from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:07.373 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_evs: out
FS ID FS Label FS Permanent ID EVS ID EVS Label
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:10:07.373 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-target list cinder-default from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:08.792 INFO cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] targetlist: cinder-default -- Alias : cinder-default
Globally unique name: iqn.2014-02.net.switchnap.openstack:evs01-source.cinder-default
Comment :
Secret : None
Authentication : Enabled
Logical units :
LUN Logical Unit
---- --------------------------------
0 volume-395f40b8-cc42-4193-8d7...
1 volume-f2f1c7ae-e257-42e3-9af...
2 volume-e249640b-7bb1-4853-9c4...
3 volume-bfdb0fea-d2a5-4407-a57...
Access configuration :
2014-03-11 12:10:08.793 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-target mod -s None -a enable cinder-default from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:10.266 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsfs list from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:14.080 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_evs: out
FS ID FS Label FS Permanent ID EVS ID EVS Label
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:10:14.081 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-lu del -d -f volume-5568b612-89b2-4a79-bf53-ef9735747c3c from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:16.559 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] delete_lu: Logical unit deleted successfully.
-- from (pid=22021) delete_lu /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:288
2014-03-11 12:10:16.560 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:16.605 DEBUG cinder.quota [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Created reservations ['4c768569-a8bd-42c7-8eed-844a329a4eef', '0b6302fe-8a2f-47bb-a25e-f1d89e8544e3'] from (pid=22021) reserve /opt/stack/cinder/cinder/quota.py:748
2014-03-11 12:10:16.645 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume 5568b612-89b2-4a79-bf53-ef9735747c3c: deleted successfully
2014-03-11 12:10:16.675 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Updating volume status
2014-03-11 12:10:16.675 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:10:16.676 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:10:16.676 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:10:16.677 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 df -a from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:18.300 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_hdp_info: HDP: 1024 112000 MB 56600 MB 50 % LUs: 256 Normal fs-01
HDP: 1025 4940 MB 2520 MB 51 % LUs: 256 Normal HN1
HDP: 1026 9940 MB 3540 MB 36 % LUs: 256 Normal HNdemo
HDP: 1029 28100 MB 2380 MB 8 % LUs: 256 Normal TestFS
HDP: 1030 28100 MB 2380 MB 8 % LUs: 256 Normal TestNewFS
HDP: 1031 28100 MB 2380 MB 8 % LUs: 256 Normal Amazon
HDP: 1032 28100 MB 2190 MB 8 % LUs: 256 Normal Liz_test
-- from (pid=22021) get_hdp_info /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:136
2014-03-11 12:10:18.300 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1024 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.300 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1025 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.301 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1026 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.301 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1029 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.301 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1030 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.301 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1031 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.301 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1032 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:18.301 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: total: 112000 used: 56600
2014-03-11 12:10:18.302 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: stats: {'QoS_support': False, 'volume_backend_name': 'HDSISCSIDriver', 'free_capacity_gb': 54, 'driver_version': '1.0.0', 'total_capacity_gb': 109, 'reserved_percentage': 0, 'vendor_name': 'HDS', 'storage_protocol': 'iSCSI'}
2014-03-11 12:10:18.302 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:18.302 DEBUG cinder.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Notifying Schedulers of capabilities ... from (pid=22021) _publish_service_capabilities /opt/stack/cinder/cinder/manager.py:133
2014-03-11 12:10:18.303 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Making asynchronous fanout cast... from (pid=22021) fanout_cast /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:592
2014-03-11 12:10:18.303 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] UNIQUE_ID is f9b103e98aeb4ecdb2dff2c920c14bac. from (pid=22021) _add_unique_id /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:342
2014-03-11 12:10:18.303 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Pool creating new connection from (pid=22021) create /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:75
2014-03-11 12:10:18.328 INFO cinder.openstack.common.rpc.common [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Connected to AMQP server on localhost:5672
2014-03-11 12:10:18.331 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "5568b612-89b2-4a79-bf53-ef9735747c3c-delete_volume" at /opt/stack/data/cinder/cinder-5568b612-89b2-4a79-bf53-ef9735747c3c-delete_volume for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:18.331 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Resuming delete on volume: a42c7bc0-0408-4b94-8465-479397ddcf6d
2014-03-11 12:10:18.332 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "a42c7bc0-0408-4b94-8465-479397ddcf6d-delete_volume" for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:10:18.332 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "a42c7bc0-0408-4b94-8465-479397ddcf6d-delete_volume" for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:10:18.332 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "a42c7bc0-0408-4b94-8465-479397ddcf6d-delete_volume" at /opt/stack/data/cinder/cinder-a42c7bc0-0408-4b94-8465-479397ddcf6d-delete_volume for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:10:18.354 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume a42c7bc0-0408-4b94-8465-479397ddcf6d: deleting
2014-03-11 12:10:18.355 DEBUG cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume a42c7bc0-0408-4b94-8465-479397ddcf6d: removing export from (pid=22021) delete_volume /opt/stack/cinder/cinder/volume/manager.py:389
2014-03-11 12:10:18.355 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] remove_export provider 95-25-E4-3D-FD-EC.volume-a42c7bc0-0408-4b94-8465-479397ddcf6d on volume-a42c7bc0-0408-4b94-8465-479397ddcf6d from (pid=22021) remove_export /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:418
2014-03-11 12:10:18.355 DEBUG cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume a42c7bc0-0408-4b94-8465-479397ddcf6d: deleting from (pid=22021) delete_volume /opt/stack/cinder/cinder/volume/manager.py:391
2014-03-11 12:10:18.355 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "hds_hnas" for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:10:18.355 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "hds_hnas" for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:10:18.356 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:10:18.356 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Parse_loc: 95-25-E4-3D-FD-EC.volume-a42c7bc0-0408-4b94-8465-479397ddcf6d
2014-03-11 12:10:18.356 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] delete lun volume-a42c7bc0-0408-4b94-8465-479397ddcf6d on hnas_95-25-E4-3D-FD-EC from (pid=22021) delete_volume /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:526
2014-03-11 12:10:18.356 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsfs list from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:19.687 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_evs: out
FS ID FS Label FS Permanent ID EVS ID EVS Label
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:10:19.688 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-lu del -d -f volume-a42c7bc0-0408-4b94-8465-479397ddcf6d from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:24.872 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] delete_lu: Logical unit deleted successfully.
-- from (pid=22021) delete_lu /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:288
2014-03-11 12:10:24.873 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "delete_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:24.919 DEBUG cinder.quota [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Created reservations ['5a274df3-7a6c-457e-ba9e-33bd1f37ade2', 'a1d6b83c-8884-415e-aa48-7b295b2cb437'] from (pid=22021) reserve /opt/stack/cinder/cinder/quota.py:748
2014-03-11 12:10:24.949 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] volume a42c7bc0-0408-4b94-8465-479397ddcf6d: deleted successfully
2014-03-11 12:10:24.985 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Updating volume status
2014-03-11 12:10:24.985 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:10:24.986 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:10:24.986 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:10:24.987 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 df -a from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:27.465 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_hdp_info: HDP: 1024 112000 MB 54700 MB 49 % LUs: 256 Normal fs-01
HDP: 1025 4940 MB 2520 MB 51 % LUs: 256 Normal HN1
HDP: 1026 9940 MB 3540 MB 36 % LUs: 256 Normal HNdemo
HDP: 1029 28100 MB 2380 MB 8 % LUs: 256 Normal TestFS
HDP: 1030 28100 MB 2380 MB 8 % LUs: 256 Normal TestNewFS
HDP: 1031 28100 MB 2380 MB 8 % LUs: 256 Normal Amazon
HDP: 1032 28100 MB 2190 MB 8 % LUs: 256 Normal Liz_test
-- from (pid=22021) get_hdp_info /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:136
2014-03-11 12:10:27.466 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1024 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.467 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1025 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.467 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1026 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.467 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1029 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.468 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1030 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.468 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1031 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.468 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1032 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:27.469 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: total: 112000 used: 54700
2014-03-11 12:10:27.469 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: stats: {'QoS_support': False, 'volume_backend_name': 'HDSISCSIDriver', 'free_capacity_gb': 55, 'driver_version': '1.0.0', 'total_capacity_gb': 109, 'reserved_percentage': 0, 'vendor_name': 'HDS', 'storage_protocol': 'iSCSI'}
2014-03-11 12:10:27.469 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:27.470 DEBUG cinder.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Notifying Schedulers of capabilities ... from (pid=22021) _publish_service_capabilities /opt/stack/cinder/cinder/manager.py:133
2014-03-11 12:10:27.470 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Making asynchronous fanout cast... from (pid=22021) fanout_cast /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:592
2014-03-11 12:10:27.470 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] UNIQUE_ID is e9e77550e31d432e821428006b995139. from (pid=22021) _add_unique_id /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:342
2014-03-11 12:10:27.474 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "a42c7bc0-0408-4b94-8465-479397ddcf6d-delete_volume" at /opt/stack/data/cinder/cinder-a42c7bc0-0408-4b94-8465-479397ddcf6d-delete_volume for method "lvo_inner2"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:27.474 INFO cinder.volume.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Updating volume status
2014-03-11 12:10:27.474 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got semaphore "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:10:27.474 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Attempting to grab file lock "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:10:27.475 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:10:27.475 DEBUG cinder.openstack.common.processutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 df -a from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:10:30.033 DEBUG cinder.volume.driver [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] get_hdp_info: HDP: 1024 112000 MB 54700 MB 49 % LUs: 256 Normal fs-01
HDP: 1025 4940 MB 2520 MB 51 % LUs: 256 Normal HN1
HDP: 1026 9940 MB 3540 MB 36 % LUs: 256 Normal HNdemo
HDP: 1029 28100 MB 2380 MB 8 % LUs: 256 Normal TestFS
HDP: 1030 28100 MB 2380 MB 8 % LUs: 256 Normal TestNewFS
HDP: 1031 28100 MB 2380 MB 8 % LUs: 256 Normal Amazon
HDP: 1032 28100 MB 2190 MB 8 % LUs: 256 Normal Liz_test
-- from (pid=22021) get_hdp_info /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:136
2014-03-11 12:10:30.033 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1024 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.033 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1025 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.033 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1026 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.034 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1029 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.034 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1030 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.034 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1031 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.034 DEBUG cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: looking for: 1032 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:10:30.034 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: total: 112000 used: 54700
2014-03-11 12:10:30.035 INFO cinder.volume.drivers.hds.iscsi [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] stats: stats: {'QoS_support': False, 'volume_backend_name': 'HDSISCSIDriver', 'free_capacity_gb': 55, 'driver_version': '1.0.0', 'total_capacity_gb': 109, 'reserved_percentage': 0, 'vendor_name': 'HDS', 'storage_protocol': 'iSCSI'}
2014-03-11 12:10:30.035 DEBUG cinder.openstack.common.lockutils [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:10:30.035 DEBUG cinder.manager [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Notifying Schedulers of capabilities ... from (pid=22021) _publish_service_capabilities /opt/stack/cinder/cinder/manager.py:133
2014-03-11 12:10:30.036 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] Making asynchronous fanout cast... from (pid=22021) fanout_cast /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:592
2014-03-11 12:10:30.036 DEBUG cinder.openstack.common.rpc.amqp [req-3d85c4d3-7b54-4040-99a3-4ba397b16708 None None] UNIQUE_ID is c97cd621f954486e97b00d2e8e498831. from (pid=22021) _add_unique_id /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:342
2014-03-11 12:10:58.047 DEBUG cinder.openstack.common.periodic_task [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Running periodic task VolumeManager._publish_service_capabilities from (pid=22021) run_periodic_tasks /opt/stack/cinder/cinder/openstack/common/periodic_task.py:178
2014-03-11 12:10:58.047 DEBUG cinder.manager [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Notifying Schedulers of capabilities ... from (pid=22021) _publish_service_capabilities /opt/stack/cinder/cinder/manager.py:133
2014-03-11 12:10:58.048 DEBUG cinder.openstack.common.rpc.amqp [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Making asynchronous fanout cast... from (pid=22021) fanout_cast /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:592
2014-03-11 12:10:58.048 DEBUG cinder.openstack.common.rpc.amqp [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] UNIQUE_ID is 219e38eb5e374299936b493d0e788e46. from (pid=22021) _add_unique_id /opt/stack/cinder/cinder/openstack/common/rpc/amqp.py:342
2014-03-11 12:10:58.052 DEBUG cinder.openstack.common.periodic_task [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Running periodic task VolumeManager._report_driver_status from (pid=22021) run_periodic_tasks /opt/stack/cinder/cinder/openstack/common/periodic_task.py:178
2014-03-11 12:10:58.052 INFO cinder.volume.manager [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Updating volume status
2014-03-11 12:10:58.052 DEBUG cinder.openstack.common.lockutils [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Got semaphore "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:10:58.053 DEBUG cinder.openstack.common.lockutils [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Attempting to grab file lock "hds_hnas" for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:10:58.053 DEBUG cinder.openstack.common.lockutils [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:10:58.053 DEBUG cinder.openstack.common.processutils [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Running cmd (subprocess): ssc -u supervisor -p 2014-03-11 12:11:00.587 DEBUG cinder.volume.driver [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] get_hdp_info: HDP: 1024 112000 MB 54700 MB 49 % LUs: 256 Normal fs-01u 7$(L) n-cond 8$(L) n-crt 9$(L) n-net 10$(L) n-sch 11$(L) n-novnc 12$(L) n-xvnc 13$(L) n-cauth 14$(L) n-obj 15$(L) c-api 16-$(L) c-sch 17$(L) c-vol*
HDP: 1025 4940 MB 2520 MB 51 % LUs: 256 Normal HN1
HDP: 1026 9940 MB 3530 MB 36 % LUs: 256 Normal HNdemo
HDP: 1029 28100 MB 2380 MB 8 % LUs: 256 Normal TestFS
HDP: 1030 28100 MB 2380 MB 8 % LUs: 256 Normal TestNewFS
HDP: 1031 28100 MB 2380 MB 8 % LUs: 256 Normal Amazon
HDP: 1032 28100 MB 2190 MB 8 % LUs: 256 Normal Liz_test
-- from (pid=22021) get_hdp_info /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:136
2014-03-11 12:11:00.588 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1024 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.589 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1025 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.589 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1026 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.589 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1029 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.590 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1030 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.590 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1031 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.590 DEBUG cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: looking for: 1032 from (pid=22021) _get_stats /opt/stack/cinder/cinder/volume/drivers/hds/iscsi.py:286
2014-03-11 12:11:00.590 INFO cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: total: 112000 used: 54700
2014-03-11 12:11:00.591 INFO cinder.volume.drivers.hds.iscsi [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] stats: stats: {'QoS_support': False, 'volume_backend_name': 'HDSISCSIDriver', 'free_capacity_gb': 55, 'driver_version': '1.0.0', 'total_capacity_gb': 109, 'reserved_percentage': 0, 'vendor_name': 'HDS', 'storage_protocol': 'iSCSI'}
2014-03-11 12:11:00.591 DEBUG cinder.openstack.common.lockutils [req-daa56a47-2e51-493f-8f0e-d661661850dc None None] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "get_volume_stats"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:11:01.733 DEBUG cinder.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'_member_', u'admin'], u'_context_request_id': u'req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956', u'_context_quota_class': None, u'_context_project_name': u'admin', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'region': u'RegionOne', u'id': u'4476241330cb4a63bb4f341563d0fe61', u'internalURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'publicURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6'}], u'endpoints_links': [], u'type': u'compute', u'name': u'nova'}], u'_context_tenant': u'720120a0418f4cd987bcde8dbb7aeae6', u'args': {u'request_spec': {u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'display_name': None, u'availability_zone': u'nova', u'reservations': [u'13ca167d-4dcd-40cd-951d-aff71a690b36', u'7a99a2ba-1309-4edd-bc3d-139333727979'], u'snapshot_id': None, u'attach_status': u'detached', u'display_description': None, u'volume_metadata': [], u'source_volid': None, u'volume_admin_metadata': [], u'encryption_key_id': None, u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'size': 1, u'metadata': {}}, u'volume_type': {}, u'image_id': None, u'snapshot_id': None, u'source_volid': None, u'volume_id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': None, u'availability_zone': u'nova', u'reservations': [u'13ca167d-4dcd-40cd-951d-aff71a690b36', u'7a99a2ba-1309-4edd-bc3d-139333727979'], u'volume_admin_metadata': [], u'attach_status': u'detached', u'source_volid': None, u'metadata': {}, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'volume_metadata': [], u'display_description': None, u'snapshot_id': None, u'encryption_key_id': None, u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'size': 1}}, u'volume_id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'allow_reschedule': True, u'filter_properties': {u'config_options': {}, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'availability_zone': u'nova', u'volume_type': {}, u'request_spec': {u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'availability_zone': u'nova', u'reservations': [u'13ca167d-4dcd-40cd-951d-aff71a690b36', u'7a99a2ba-1309-4edd-bc3d-139333727979'], u'volume_admin_metadata': [], u'attach_status': u'detached', u'display_description': None, u'metadata': {}, u'volume_metadata': [], u'source_volid': None, u'snapshot_id': None, u'encryption_key_id': None, u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'size': 1, u'display_name': None}, u'source_volid': None, u'image_id': None, u'snapshot_id': None, u'volume_type': {}, u'volume_id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': None, u'volume_metadata': [], u'reservations': [u'13ca167d-4dcd-40cd-951d-aff71a690b36', u'7a99a2ba-1309-4edd-bc3d-139333727979'], u'availability_zone': u'nova', u'attach_status': u'detached', u'source_volid': None, u'snapshot_id': None, u'encryption_key_id': None, u'display_description': None, u'volume_admin_metadata': [], u'user_id': u'55c36448461f411c9beedb46588c9e75', u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'4e33c20d-1a1f-4023-a4c8-c024829eee7a', u'size': 1, u'metadata': {}}}, u'qos_specs': None, u'retry': {u'num_attempts': 1, u'hosts': [u'ubuntu']}, u'size': 1, u'resource_type': {}, u'metadata': {}}, u'source_volid': None, u'image_id': None, u'snapshot_id': None}, u'namespace': None, u'_context_auth_token': '<SANITIZED>', u'_context_timestamp': u'2014-03-11T17:11:01.526661', u'_context_is_admin': True, u'version': u'1.4', u'_context_project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'_context_user': u'55c36448461f411c9beedb46588c9e75', u'_unique_id': u'5f0d61cf13d545daaacd0b513ea08d78', u'_context_read_deleted': u'no', u'_context_user_id': u'55c36448461f411c9beedb46588c9e75', u'method': u'create_volume', u'_context_remote_address': u'192.168.1.56'} from (pid=22021) _safe_log /opt/stack/cinder/cinder/openstack/common/rpc/common.py:280
2014-03-11 12:11:01.735 DEBUG cinder.openstack.common.rpc.amqp [-] unpacked context: {'project_name': u'admin', 'user_id': u'55c36448461f411c9beedb46588c9e75', 'roles': [u'_member_', u'admin'], 'timestamp': u'2014-03-11T17:11:01.526661', 'auth_token': '<SANITIZED>', 'remote_address': u'192.168.1.56', 'quota_class': None, 'is_admin': True, 'user': u'55c36448461f411c9beedb46588c9e75', 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'region': u'RegionOne', u'publicURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'id': u'4476241330cb4a63bb4f341563d0fe61', u'internalURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6'}], u'type': u'compute', u'name': u'nova'}], 'request_id': u'req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956', 'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', 'read_deleted': u'no', 'tenant': u'720120a0418f4cd987bcde8dbb7aeae6'} from (pid=22021) _safe_log /opt/stack/cinder/cinder/openstack/common/rpc/common.py:280
2014-03-11 12:11:01.738 DEBUG stevedore.extension [-] found extension EntryPoint.parse('default = taskflow.engines.action_engine.engine:SingleThreadedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:01.791 DEBUG stevedore.extension [-] found extension EntryPoint.parse('serial = taskflow.engines.action_engine.engine:SingleThreadedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:01.791 DEBUG stevedore.extension [-] found extension EntryPoint.parse('worker-based = taskflow.engines.worker_based.engine:WorkerBasedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:01.791 DEBUG stevedore.extension [-] found extension EntryPoint.parse('parallel = taskflow.engines.action_engine.engine:MultiThreadedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:01.791 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.791 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.792 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'taskflow.patterns.linear_flow.Flow: volume_create_manager; 6' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.792 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.792 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.792 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.792 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.793 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.794 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.795 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.796 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.797 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.798 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.799 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.800 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:01.800 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.800 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.800 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.800 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.800 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'taskflow.patterns.linear_flow.Flow: volume_create_manager; 6' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:01.801 DEBUG taskflow.utils.flow_utils [-] Translated 'taskflow.patterns.linear_flow.Flow: volume_create_manager; 6' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:01.802 DEBUG taskflow.utils.flow_utils [-] Name: volume_create_manager from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.802 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.802 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.802 DEBUG taskflow.utils.flow_utils [-] Nodes: 6 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.802 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] Edges: 5 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 -> cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.803 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.804 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.804 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.804 DEBUG taskflow.utils.flow_utils [-] Density: 0.167 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.804 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:01.804 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.804 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.805 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.805 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.805 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.805 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.806 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_flow_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.806 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.806 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.828 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.828 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.829 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.829 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.829 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.829 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.830 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.830 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.830 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.830 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.830 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.831 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.831 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.831 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.831 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.832 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:01.832 INFO cinder.volume.flows.manager.create_volume [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Volume 4e33c20d-1a1f-4023-a4c8-c024829eee7a: being created using CreateVolumeFromSpecTask._create_raw_volume with specification: {'status': u'creating', 'volume_size': 1, 'volume_name': u'volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a'}
2014-03-11 12:11:01.832 DEBUG cinder.openstack.common.lockutils [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Got semaphore "hds_hnas" for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:11:01.833 DEBUG cinder.openstack.common.lockutils [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Attempting to grab file lock "hds_hnas" for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:11:01.833 DEBUG cinder.openstack.common.lockutils [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:11:01.833 DEBUG cinder.openstack.common.processutils [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsfs list from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:11:05.118 DEBUG cinder.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'_member_', u'admin'], u'_context_request_id': u'req-27c92014-2859-4eda-a5fc-34a70d394f3f', u'_context_quota_class': None, u'_context_project_name': u'admin', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'region': u'RegionOne', u'id': u'4476241330cb4a63bb4f341563d0fe61', u'internalURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'publicURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6'}], u'endpoints_links': [], u'type': u'compute', u'name': u'nova'}], u'_context_tenant': u'720120a0418f4cd987bcde8dbb7aeae6', u'args': {u'request_spec': {u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'display_name': None, u'availability_zone': u'nova', u'reservations': [u'7dbbd819-8d3e-492f-835a-274c1fc2fb19', u'92bae6a3-eb89-4312-9bfe-50f793df9555'], u'snapshot_id': None, u'attach_status': u'detached', u'display_description': None, u'volume_metadata': [], u'source_volid': None, u'volume_admin_metadata': [], u'encryption_key_id': None, u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'size': 3, u'metadata': {}}, u'volume_type': {}, u'image_id': None, u'snapshot_id': None, u'source_volid': None, u'volume_id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': None, u'availability_zone': u'nova', u'reservations': [u'7dbbd819-8d3e-492f-835a-274c1fc2fb19', u'92bae6a3-eb89-4312-9bfe-50f793df9555'], u'volume_admin_metadata': [], u'attach_status': u'detached', u'source_volid': None, u'metadata': {}, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'volume_metadata': [], u'display_description': None, u'snapshot_id': None, u'encryption_key_id': None, u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'size': 3}}, u'volume_id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'allow_reschedule': True, u'filter_properties': {u'config_options': {}, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'availability_zone': u'nova', u'volume_type': {}, u'request_spec': {u'volume_properties': {u'status': u'creating', u'volume_type_id': None, u'user_id': u'55c36448461f411c9beedb46588c9e75', u'availability_zone': u'nova', u'reservations': [u'7dbbd819-8d3e-492f-835a-274c1fc2fb19', u'92bae6a3-eb89-4312-9bfe-50f793df9555'], u'volume_admin_metadata': [], u'attach_status': u'detached', u'display_description': None, u'metadata': {}, u'volume_metadata': [], u'source_volid': None, u'snapshot_id': None, u'encryption_key_id': None, u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'size': 3, u'display_name': None}, u'source_volid': None, u'image_id': None, u'snapshot_id': None, u'volume_type': {}, u'volume_id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'resource_properties': {u'status': u'creating', u'volume_type_id': None, u'display_name': None, u'volume_metadata': [], u'reservations': [u'7dbbd819-8d3e-492f-835a-274c1fc2fb19', u'92bae6a3-eb89-4312-9bfe-50f793df9555'], u'availability_zone': u'nova', u'attach_status': u'detached', u'source_volid': None, u'snapshot_id': None, u'encryption_key_id': None, u'display_description': None, u'volume_admin_metadata': [], u'user_id': u'55c36448461f411c9beedb46588c9e75', u'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'id': u'b9b9a66a-c679-46e8-bc68-81f79e9114ea', u'size': 3, u'metadata': {}}}, u'qos_specs': None, u'retry': {u'num_attempts': 1, u'hosts': [u'ubuntu']}, u'size': 3, u'resource_type': {}, u'metadata': {}}, u'source_volid': None, u'image_id': None, u'snapshot_id': None}, u'namespace': None, u'_context_auth_token': '<SANITIZED>', u'_context_timestamp': u'2014-03-11T17:11:04.916412', u'_context_is_admin': True, u'version': u'1.4', u'_context_project_id': u'720120a0418f4cd987bcde8dbb7aeae6', u'_context_user': u'55c36448461f411c9beedb46588c9e75', u'_unique_id': u'63f32222230b4027ae0f561028c6e83f', u'_context_read_deleted': u'no', u'_context_user_id': u'55c36448461f411c9beedb46588c9e75', u'method': u'create_volume', u'_context_remote_address': u'192.168.1.56'} from (pid=22021) _safe_log /opt/stack/cinder/cinder/openstack/common/rpc/common.py:280
2014-03-11 12:11:05.120 DEBUG cinder.openstack.common.rpc.amqp [-] unpacked context: {'project_name': u'admin', 'user_id': u'55c36448461f411c9beedb46588c9e75', 'roles': [u'_member_', u'admin'], 'timestamp': u'2014-03-11T17:11:04.916412', 'auth_token': '<SANITIZED>', 'remote_address': u'192.168.1.56', 'quota_class': None, 'is_admin': True, 'user': u'55c36448461f411c9beedb46588c9e75', 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'region': u'RegionOne', u'publicURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6', u'id': u'4476241330cb4a63bb4f341563d0fe61', u'internalURL': u'http://192.168.1.56:8774/v2/720120a0418f4cd987bcde8dbb7aeae6'}], u'type': u'compute', u'name': u'nova'}], 'request_id': u'req-27c92014-2859-4eda-a5fc-34a70d394f3f', 'project_id': u'720120a0418f4cd987bcde8dbb7aeae6', 'read_deleted': u'no', 'tenant': u'720120a0418f4cd987bcde8dbb7aeae6'} from (pid=22021) _safe_log /opt/stack/cinder/cinder/openstack/common/rpc/common.py:280
2014-03-11 12:11:05.123 DEBUG stevedore.extension [-] found extension EntryPoint.parse('default = taskflow.engines.action_engine.engine:SingleThreadedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:05.123 DEBUG stevedore.extension [-] found extension EntryPoint.parse('serial = taskflow.engines.action_engine.engine:SingleThreadedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:05.123 DEBUG stevedore.extension [-] found extension EntryPoint.parse('worker-based = taskflow.engines.worker_based.engine:WorkerBasedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:05.124 DEBUG stevedore.extension [-] found extension EntryPoint.parse('parallel = taskflow.engines.action_engine.engine:MultiThreadedActionEngine') from (pid=22021) _load_plugins /opt/stack/stevedore/stevedore/extension.py:156
2014-03-11 12:11:05.124 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.124 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.125 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'taskflow.patterns.linear_flow.Flow: volume_create_manager; 6' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.125 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.125 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.126 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.126 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.126 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.126 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.126 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.126 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.127 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.128 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.129 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.130 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.130 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.130 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.130 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.130 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.130 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.131 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.132 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Starting to flatten 'cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0' from (pid=22021) _pre_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:152
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Translated 'cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Name: cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.133 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Nodes: 1 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Edges: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Density: 0.000 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Finished flattening 'taskflow.patterns.linear_flow.Flow: volume_create_manager; 6' from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:157
2014-03-11 12:11:05.134 DEBUG taskflow.utils.flow_utils [-] Translated 'taskflow.patterns.linear_flow.Flow: volume_create_manager; 6' into a graph: from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:162
2014-03-11 12:11:05.135 DEBUG taskflow.utils.flow_utils [-] Name: volume_create_manager from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.135 DEBUG taskflow.utils.flow_utils [-] Type: DiGraph from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.135 DEBUG taskflow.utils.flow_utils [-] Frozen: False from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.135 DEBUG taskflow.utils.flow_utils [-] Nodes: 6 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.135 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] - cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] Edges: 5 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.ExtractVolumeRefTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.CreateVolumeOnFinishTask;volume:create, create.end==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.136 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.OnFailureRescheduleTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.137 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 -> cinder.volume.flows.manager.create_volume.CreateVolumeFromSpecTask;volume:create==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.137 DEBUG taskflow.utils.flow_utils [-] cinder.volume.flows.manager.create_volume.ExtractVolumeSpecTask;volume:create==1.0 -> cinder.volume.flows.manager.create_volume.NotifyVolumeActionTask;volume:create, create.start==1.0 ({'flatten': True}) from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.137 DEBUG taskflow.utils.flow_utils [-] Density: 0.167 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.137 DEBUG taskflow.utils.flow_utils [-] Cycles: 0 from (pid=22021) _post_item_flatten /opt/stack/taskflow/taskflow/utils/flow_utils.py:165
2014-03-11 12:11:05.137 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.137 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.138 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.138 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.138 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.138 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.save_both' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.139 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_flow_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.139 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.139 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.161 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.161 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.161 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.161 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.162 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.162 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.162 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.162 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.162 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.163 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.163 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.163 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.163 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.164 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.164 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.164 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:05.164 INFO cinder.volume.flows.manager.create_volume [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Volume b9b9a66a-c679-46e8-bc68-81f79e9114ea: being created using CreateVolumeFromSpecTask._create_raw_volume with specification: {'status': u'creating', 'volume_size': 3, 'volume_name': u'volume-b9b9a66a-c679-46e8-bc68-81f79e9114ea'}
2014-03-11 12:11:07.544 DEBUG cinder.volume.driver [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] get_evs: out
FS ID FS Label FS Permanent ID EVS ID EVS Label
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:11:07.545 DEBUG cinder.openstack.common.processutils [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-lu add -e volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a fs-01 /.cinder/volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a.iscsi 1024M from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:11:11.122 DEBUG cinder.volume.driver [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] create_lu: LUN volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a HDP: fs-01 size: 1024 MB, is successfully created from (pid=22021) create_lu /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:265
2014-03-11 12:11:11.123 INFO cinder.volume.drivers.hds.iscsi [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] create_volume: create_lu returns LUN volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a HDP: fs-01 size: 1024 MB, is successfully created
2014-03-11 12:11:11.124 INFO cinder.volume.drivers.hds.iscsi [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] LUN 95-25-E4-3D-FD-EC.volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a of size 1024 MB is created.
2014-03-11 12:11:11.124 DEBUG cinder.openstack.common.lockutils [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Released file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:239
2014-03-11 12:11:11.184 DEBUG cinder.openstack.common.lockutils [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Got semaphore "hds_hnas" for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:191
2014-03-11 12:11:11.185 DEBUG cinder.openstack.common.lockutils [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Attempting to grab file lock "hds_hnas" for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:202
2014-03-11 12:11:11.186 DEBUG cinder.openstack.common.lockutils [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Got file lock "hds_hnas" at /opt/stack/data/cinder/cinder-hds_hnas for method "create_volume"... from (pid=22021) inner /opt/stack/cinder/cinder/openstack/common/lockutils.py:232
2014-03-11 12:11:11.187 DEBUG cinder.openstack.common.processutils [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 evsfs list from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
2014-03-11 12:11:11.196 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:11.197 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:11.198 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:11.198 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:11.245 INFO cinder.volume.flows.manager.create_volume [req-f50d28bc-4ff0-4422-8e8c-e9e4a9299956 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Volume volume-4e33c20d-1a1f-4023-a4c8-c024829eee7a (4e33c20d-1a1f-4023-a4c8-c024829eee7a): created successfully
2014-03-11 12:11:11.246 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:11.246 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_task_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:11.246 DEBUG taskflow.storage [-] No backend provided, not calling functor 'taskflow.storage.SingleThreadedStorage._save_flow_detail' from (pid=22021) _with_connection /opt/stack/taskflow/taskflow/storage.py:86
2014-03-11 12:11:12.616 DEBUG cinder.volume.driver [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] get_evs: out
FS ID FS Label FS Permanent ID EVS ID EVS Label
----- --------- ------------------ ------ ------------
1024 fs-01 0x3df57eae671f8fbb 1 EVS01-Source
1025 HN1 0x3decd9c8de9b0cea 2 EVS02
1026 HNdemo 0x3dec82f1477d88d3 2 EVS02
1027 FSNew 0x3dd63670c3fa1a8c 2 EVS02
1028 FSNewDupe 0x3dd6366211712138 2 EVS02
1029 TestFS 0x3dd19415d328d849 4 EVS04-Target
1030 TestNewFS 0x3dd1948967076f8b 4 EVS04-Target
1031 Amazon 0x3dd1955f0f067ed6 4 EVS04-Target
1032 Liz_test 0x3dd1951e664d70c1 4 EVS04-Target
from (pid=22021) _get_evs /opt/stack/cinder/cinder/volume/drivers/hds/hnas_backend.py:146
2014-03-11 12:11:12.617 DEBUG cinder.openstack.common.processutils [req-27c92014-2859-4eda-a5fc-34a70d394f3f 55c36448461f411c9beedb46588c9e75 720120a0418f4cd987bcde8dbb7aeae6] Running cmd (subprocess): ssc -u supervisor -p supervisor 10.0.80.47 console-context --evs 1 iscsi-lu add -e volume-b9b9a66a-c679-46e8-bc68-81f79e9114ea fs-01 /.cinder/volume-b9b9a66a-c679-46e8-bc68-81f79e9114ea.iscsi 3072M from (pid=22021) execute /opt/stack/cinder/cinder/openstack/common/processutils.py:142
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment