SuccessConsole Output

Skipping 726 KB.. Full Log
changed: [40.117.134.83] => (item={'gpgcheck': False, 'enabled': True, 'name': u'install_repo', 'baseurl': u'https://storage.googleapis.com/origin-ci-test/logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/rpms'})

TASK [openshift_repos : include_tasks] *****************************************
Wednesday 21 November 2018  03:51:59 +0000 (0:00:00.622)       0:03:24.849 **** 
skipping: [40.117.134.83]

TASK [openshift_repos : include_tasks] *****************************************
Wednesday 21 November 2018  03:51:59 +0000 (0:00:00.027)       0:03:24.876 **** 
skipping: [40.117.134.83]

TASK [openshift_repos : Ensure clean repo cache in the event repos have been changed manually] ***
Wednesday 21 November 2018  03:51:59 +0000 (0:00:00.027)       0:03:24.904 **** 
skipping: [40.117.134.83]

TASK [openshift_repos : Record that openshift_repos already ran] ***************
Wednesday 21 November 2018  03:51:59 +0000 (0:00:00.027)       0:03:24.932 **** 
skipping: [40.117.134.83]

RUNNING HANDLER [openshift_repos : refresh cache] ******************************
Wednesday 21 November 2018  03:51:59 +0000 (0:00:00.020)       0:03:24.953 **** 
changed: [40.117.134.83]

TASK [os_update_latest : Update all packages] **********************************
Wednesday 21 November 2018  03:52:00 +0000 (0:00:00.519)       0:03:25.472 **** 
ok: [40.117.134.83]

TASK [os_update_latest : command] **********************************************
Wednesday 21 November 2018  03:52:06 +0000 (0:00:06.797)       0:03:32.270 **** 
skipping: [40.117.134.83]

TASK [os_update_latest : wait_for_connection] **********************************
Wednesday 21 November 2018  03:52:06 +0000 (0:00:00.023)       0:03:32.294 **** 
skipping: [40.117.134.83]

PLAY [Fail openshift_kubelet_name_override for new hosts] **********************

TASK [Fail when openshift_kubelet_name_override is defined] ********************
Wednesday 21 November 2018  03:52:06 +0000 (0:00:00.033)       0:03:32.328 **** 
skipping: [40.117.134.83]

PLAY [Initialization Checkpoint Start] *****************************************

TASK [Set install initialization 'In Progress'] ********************************
Wednesday 21 November 2018  03:52:06 +0000 (0:00:00.028)       0:03:32.356 **** 
ok: [{}]

PLAY [Populate config host groups] *********************************************

TASK [Load group name mapping variables] ***************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.054)       0:03:32.410 **** 
ok: [localhost]

TASK [Evaluate groups - g_etcd_hosts or g_new_etcd_hosts required] *************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.030)       0:03:32.441 **** 
skipping: [localhost]

TASK [Evaluate groups - g_master_hosts or g_new_master_hosts required] *********
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.025)       0:03:32.467 **** 
skipping: [localhost]

TASK [Evaluate groups - g_node_hosts or g_new_node_hosts required] *************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.026)       0:03:32.493 **** 
skipping: [localhost]

TASK [Evaluate groups - g_lb_hosts required] ***********************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.026)       0:03:32.519 **** 
skipping: [localhost]

TASK [Evaluate groups - g_nfs_hosts required] **********************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.023)       0:03:32.543 **** 
skipping: [localhost]

TASK [Evaluate groups - g_nfs_hosts is single host] ****************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.023)       0:03:32.567 **** 
skipping: [localhost]

TASK [Evaluate groups - g_glusterfs_hosts required] ****************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.023)       0:03:32.590 **** 
skipping: [localhost]

TASK [Evaluate oo_all_hosts] ***************************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.023)       0:03:32.614 **** 
ok: [localhost] => (item=40.117.134.83)

TASK [Evaluate oo_masters] *****************************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.053)       0:03:32.667 **** 

TASK [Evaluate oo_first_master] ************************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.023)       0:03:32.690 **** 
skipping: [localhost]

TASK [Evaluate oo_new_etcd_to_config] ******************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.025)       0:03:32.716 **** 

TASK [Evaluate oo_masters_to_config] *******************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.022)       0:03:32.738 **** 

TASK [Evaluate oo_etcd_to_config] **********************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.026)       0:03:32.765 **** 

TASK [Evaluate oo_first_etcd] **************************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.021)       0:03:32.787 **** 
skipping: [localhost]

TASK [Evaluate oo_etcd_hosts_to_upgrade] ***************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.024)       0:03:32.811 **** 

TASK [Evaluate oo_etcd_hosts_to_backup] ****************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.020)       0:03:32.832 **** 

TASK [Evaluate oo_nodes_to_config] *********************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.021)       0:03:32.854 **** 
ok: [localhost] => (item=40.117.134.83)

TASK [Evaluate oo_lb_to_config] ************************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.039)       0:03:32.894 **** 

TASK [Evaluate oo_nfs_to_config] ***********************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.021)       0:03:32.915 **** 

TASK [Evaluate oo_glusterfs_to_config] *****************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.021)       0:03:32.937 **** 

TASK [Evaluate oo_etcd_to_migrate] *********************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.024)       0:03:32.961 **** 
 [WARNING]: Could not match supplied host pattern, ignoring:
oo_masters_to_config
 [WARNING]: Could not match supplied host pattern, ignoring: oo_etcd_to_config
 [WARNING]: Could not match supplied host pattern, ignoring: oo_lb_to_config
 [WARNING]: Could not match supplied host pattern, ignoring: oo_nfs_to_config

PLAY [Ensure that all non-node hosts are accessible] ***************************
skipping: no hosts matched

PLAY [Initialize basic host facts] *********************************************

TASK [openshift_sanitize_inventory : include_tasks] ****************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.040)       0:03:33.002 **** 
included: /usr/share/ansible/openshift-ansible/roles/openshift_sanitize_inventory/tasks/deprecations.yml for 40.117.134.83

TASK [openshift_sanitize_inventory : Check for usage of deprecated variables] ***
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.054)       0:03:33.057 **** 
ok: [40.117.134.83]

TASK [openshift_sanitize_inventory : debug] ************************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.131)       0:03:33.189 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : set_stats] ********************************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.024)       0:03:33.213 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Assign deprecated variables to correct counterparts] ***
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.024)       0:03:33.237 **** 
included: /usr/share/ansible/openshift-ansible/roles/openshift_sanitize_inventory/tasks/__deprecations_logging.yml for 40.117.134.83
included: /usr/share/ansible/openshift-ansible/roles/openshift_sanitize_inventory/tasks/__deprecations_metrics.yml for 40.117.134.83

TASK [openshift_sanitize_inventory : conditional_set_fact] *********************
Wednesday 21 November 2018  03:52:07 +0000 (0:00:00.083)       0:03:33.321 **** 
ok: [40.117.134.83]

TASK [openshift_sanitize_inventory : set_fact] *********************************
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.133)       0:03:33.455 **** 
ok: [40.117.134.83]

TASK [openshift_sanitize_inventory : conditional_set_fact] *********************
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.111)       0:03:33.566 **** 
ok: [40.117.134.83]

TASK [openshift_sanitize_inventory : Standardize on latest variable names] *****
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.172)       0:03:33.739 **** 
ok: [40.117.134.83]

TASK [openshift_sanitize_inventory : Normalize openshift_release] **************
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.046)       0:03:33.785 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Abort when openshift_release is invalid] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.023)       0:03:33.809 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : include_tasks] ****************************
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.024)       0:03:33.833 **** 
included: /usr/share/ansible/openshift-ansible/roles/openshift_sanitize_inventory/tasks/unsupported.yml for 40.117.134.83

TASK [openshift_sanitize_inventory : Ensure that openshift_use_dnsmasq is true] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.060)       0:03:33.894 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure that openshift_node_dnsmasq_install_network_manager_hook is true] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.025)       0:03:33.920 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : set_fact] *********************************
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.024)       0:03:33.944 **** 

TASK [openshift_sanitize_inventory : Ensure that dynamic provisioning is set if using dynamic storage] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.032)       0:03:33.977 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure the hosted registry's GlusterFS storage is configured correctly] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.031)       0:03:34.008 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure the hosted registry's GlusterFS storage is configured correctly] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.023)       0:03:34.032 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure clusterid is set along with the cloudprovider] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.024)       0:03:34.057 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure ansible_service_broker_remove and ansible_service_broker_install are mutually exclusive] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.027)       0:03:34.084 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure template_service_broker_remove and template_service_broker_install are mutually exclusive] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.024)       0:03:34.108 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure that all requires vsphere configuration variables are set] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.023)       0:03:34.132 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : ensure provider configuration variables are defined] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.023)       0:03:34.155 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure removed web console extension variables are not set] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.023)       0:03:34.179 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : Ensure that web console port matches API server port] ***
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.023)       0:03:34.203 **** 
skipping: [40.117.134.83]

TASK [openshift_sanitize_inventory : At least one master is schedulable] *******
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.022)       0:03:34.226 **** 
skipping: [40.117.134.83]

TASK [Detecting Operating System from ostree_booted] ***************************
Wednesday 21 November 2018  03:52:08 +0000 (0:00:00.030)       0:03:34.256 **** 
ok: [40.117.134.83]

TASK [set openshift_deployment_type if unset] **********************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.207)       0:03:34.464 **** 
skipping: [40.117.134.83]

TASK [check for node already bootstrapped] *************************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.025)       0:03:34.490 **** 
ok: [40.117.134.83]

TASK [initialize_facts set fact openshift_is_bootstrapped] *********************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.213)       0:03:34.703 **** 
ok: [40.117.134.83]

TASK [initialize_facts set fact openshift_is_atomic and openshift_is_containerized] ***
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.043)       0:03:34.746 **** 
ok: [40.117.134.83]

TASK [Determine Atomic Host Docker Version] ************************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.046)       0:03:34.793 **** 
skipping: [40.117.134.83]

TASK [assert atomic host docker version is 1.12 or later] **********************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:34.816 **** 
skipping: [40.117.134.83]

PLAY [Retrieve existing master configs and validate] ***************************
skipping: no hosts matched
 [WARNING]: Could not match supplied host pattern, ignoring: oo_first_master

PLAY [Initialize special first-master variables] *******************************
skipping: no hosts matched

PLAY [Disable web console if required] *****************************************
skipping: no hosts matched

PLAY [Setup yum repositories for all hosts] ************************************

TASK [rhel_subscribe : fail] ***************************************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.043)       0:03:34.860 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Install Red Hat Subscription manager] *******************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.025)       0:03:34.886 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Is host already registered?] ****************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:34.909 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Register host using user/password] **********************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:34.933 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Register host using activation key] *********************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:34.956 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : fail] ***************************************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:34.980 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Determine if OpenShift Pool Already Attached] ***********
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:35.004 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Attach to OpenShift Pool] *******************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.022)       0:03:35.026 **** 
skipping: [40.117.134.83]

TASK [rhel_subscribe : Satellite preparation] **********************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:35.050 **** 
skipping: [40.117.134.83]

TASK [openshift_repos : openshift_repos detect ostree] *************************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.023)       0:03:35.074 **** 
ok: [40.117.134.83]

TASK [openshift_repos : Ensure libselinux-python is installed] *****************
Wednesday 21 November 2018  03:52:09 +0000 (0:00:00.211)       0:03:35.285 **** 
ok: [40.117.134.83]

TASK [openshift_repos : Remove openshift_additional.repo file] *****************
Wednesday 21 November 2018  03:52:10 +0000 (0:00:00.421)       0:03:35.706 **** 
ok: [40.117.134.83]

TASK [openshift_repos : Create any additional repos that are defined] **********
Wednesday 21 November 2018  03:52:10 +0000 (0:00:00.219)       0:03:35.926 **** 
ok: [40.117.134.83] => (item={u'gpgkey': u'file:///etc/pki/rpm-gpg/RPM-GPG-KEY-CentOS-SIG-PaaS', u'enabled': True, u'name': u'openshift-origin310', u'baseurl': u'http://mirror.centos.org/centos/7/paas/x86_64/openshift-origin310/'})
ok: [40.117.134.83] => (item={'gpgcheck': False, 'enabled': True, 'name': u'install_repo', 'baseurl': u'https://storage.googleapis.com/origin-ci-test/logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/rpms'})

TASK [openshift_repos : include_tasks] *****************************************
Wednesday 21 November 2018  03:52:10 +0000 (0:00:00.473)       0:03:36.399 **** 
skipping: [40.117.134.83]

TASK [openshift_repos : include_tasks] *****************************************
Wednesday 21 November 2018  03:52:11 +0000 (0:00:00.036)       0:03:36.436 **** 
skipping: [40.117.134.83]

TASK [openshift_repos : Ensure clean repo cache in the event repos have been changed manually] ***
Wednesday 21 November 2018  03:52:11 +0000 (0:00:00.042)       0:03:36.478 **** 
changed: [40.117.134.83] => {
    "msg": "First run of openshift_repos"
}

TASK [openshift_repos : Record that openshift_repos already ran] ***************
Wednesday 21 November 2018  03:52:11 +0000 (0:00:00.055)       0:03:36.534 **** 
ok: [40.117.134.83]

RUNNING HANDLER [openshift_repos : refresh cache] ******************************
Wednesday 21 November 2018  03:52:11 +0000 (0:00:00.046)       0:03:36.581 **** 
changed: [40.117.134.83]

PLAY [Install packages necessary for installer] ********************************

TASK [Determine if chrony is installed] ****************************************
Wednesday 21 November 2018  03:52:11 +0000 (0:00:00.456)       0:03:37.038 **** 
changed: [40.117.134.83]
 [WARNING]: Consider using yum, dnf or zypper module rather than running rpm

TASK [Install ntp package] *****************************************************
Wednesday 21 November 2018  03:52:12 +0000 (0:00:00.386)       0:03:37.425 **** 
skipping: [40.117.134.83]

TASK [Start and enable ntpd/chronyd] *******************************************
Wednesday 21 November 2018  03:52:12 +0000 (0:00:00.028)       0:03:37.454 **** 
changed: [40.117.134.83]

TASK [Ensure openshift-ansible installer package deps are installed] ***********
Wednesday 21 November 2018  03:52:12 +0000 (0:00:00.389)       0:03:37.843 **** 
ok: [40.117.134.83] => (item=iproute)
ok: [40.117.134.83] => (item=dbus-python)
changed: [40.117.134.83] => (item=PyYAML)
changed: [40.117.134.83] => (item=python-ipaddress)
changed: [40.117.134.83] => (item=libsemanage-python)
changed: [40.117.134.83] => (item=yum-utils)
changed: [40.117.134.83] => (item=python-docker)

PLAY [Initialize cluster facts] ************************************************

TASK [get openshift_current_version] *******************************************
Wednesday 21 November 2018  03:52:35 +0000 (0:00:22.942)       0:04:00.785 **** 
ok: [40.117.134.83]

TASK [set_fact openshift_portal_net if present on masters] *********************
Wednesday 21 November 2018  03:52:35 +0000 (0:00:00.388)       0:04:01.174 **** 
skipping: [40.117.134.83]

TASK [Gather Cluster facts] ****************************************************
Wednesday 21 November 2018  03:52:35 +0000 (0:00:00.023)       0:04:01.197 **** 
changed: [40.117.134.83]

TASK [Set fact of no_proxy_internal_hostnames] *********************************
Wednesday 21 November 2018  03:52:36 +0000 (0:00:01.065)       0:04:02.262 **** 
skipping: [40.117.134.83]

TASK [Initialize openshift.node.sdn_mtu] ***************************************
Wednesday 21 November 2018  03:52:36 +0000 (0:00:00.024)       0:04:02.287 **** 
changed: [40.117.134.83]

TASK [set_fact l_kubelet_node_name] ********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.496)       0:04:02.784 **** 
ok: [40.117.134.83]

TASK [Read node config] ********************************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.043)       0:04:02.828 **** 
skipping: [40.117.134.83]

TASK [Fail when nodeName is wrong] *********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.024)       0:04:02.852 **** 
skipping: [40.117.134.83]

PLAY [Initialize etcd host variables] ******************************************
skipping: no hosts matched

PLAY [Determine openshift_version to configure on first master] ****************

TASK [include_role] ************************************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.036)       0:04:02.889 **** 

TASK [openshift_version : Use openshift_current_version fact as version to configure if already installed] ***
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.062)       0:04:02.951 **** 
skipping: [40.117.134.83]

TASK [openshift_version : Set openshift_version to openshift_release if undefined] ***
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.025)       0:04:02.977 **** 
ok: [40.117.134.83]

TASK [openshift_version : debug] ***********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.025 **** 
ok: [40.117.134.83] => {
    "msg": "openshift_pkg_version was not defined. Falling back to -3.10"
}

TASK [openshift_version : set_fact] ********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.072 **** 
ok: [40.117.134.83]

TASK [openshift_version : debug] ***********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.120 **** 
ok: [40.117.134.83] => {
    "msg": "openshift_image_tag was not defined. Falling back to v3.10"
}

TASK [openshift_version : set_fact] ********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.168 **** 
ok: [40.117.134.83]

TASK [openshift_version : assert openshift_release in openshift_image_tag] *****
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.215 **** 
ok: [40.117.134.83] => {
    "changed": false, 
    "msg": "All assertions passed"
}

TASK [openshift_version : assert openshift_release in openshift_pkg_version] ***
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.263 **** 
ok: [40.117.134.83] => {
    "changed": false, 
    "msg": "All assertions passed"
}

TASK [openshift_version : debug] ***********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.047)       0:04:03.311 **** 
ok: [40.117.134.83] => {
    "openshift_release": "3.10"
}

TASK [openshift_version : debug] ***********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.044)       0:04:03.355 **** 
ok: [40.117.134.83] => {
    "openshift_image_tag": "v3.10"
}

TASK [openshift_version : debug] ***********************************************
Wednesday 21 November 2018  03:52:37 +0000 (0:00:00.044)       0:04:03.400 **** 
ok: [40.117.134.83] => {
    "openshift_pkg_version": "-3.10*"
}

TASK [openshift_version : debug] ***********************************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.046)       0:04:03.447 **** 
ok: [40.117.134.83] => {
    "openshift_version": "3.10"
}

TASK [set openshift_version booleans (first master)] ***************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.044)       0:04:03.491 **** 
ok: [40.117.134.83]

PLAY [Set openshift_version for etcd, node, and master hosts] ******************
skipping: no hosts matched

PLAY [Verify Requirements] *****************************************************
skipping: no hosts matched

PLAY [Initialization Checkpoint End] *******************************************

TASK [Set install initialization 'Complete'] ***********************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.058)       0:04:03.550 **** 
ok: [{}]

PLAY [Validate node hostnames] *************************************************

TASK [Query DNS for IP address of vm.yhmeoscmqr0etekpmdoyel2qkb.bx.internal.cloudapp.net] ***
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.060)       0:04:03.610 **** 
skipping: [40.117.134.83]

TASK [Validate openshift_kubelet_name_override when defined] *******************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.024)       0:04:03.634 **** 
skipping: [40.117.134.83]

TASK [Validate openshift_ip exists on node when defined] ***********************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.025)       0:04:03.660 **** 
skipping: [40.117.134.83]

PLAY [Configure os_firewall] ***************************************************

TASK [os_firewall : Detecting Atomic Host Operating System] ********************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.040)       0:04:03.700 **** 
ok: [40.117.134.83]

TASK [os_firewall : Set fact r_os_firewall_is_atomic] **************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.213)       0:04:03.914 **** 
ok: [40.117.134.83]

TASK [os_firewall : Fail - Firewalld is not supported on Atomic Host] **********
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.045)       0:04:03.960 **** 
skipping: [40.117.134.83]

TASK [os_firewall : Install firewalld packages] ********************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.026)       0:04:03.987 **** 
skipping: [40.117.134.83]

TASK [os_firewall : Ensure iptables services are not enabled] ******************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.026)       0:04:04.013 **** 
skipping: [40.117.134.83] => (item=iptables) 
skipping: [40.117.134.83] => (item=ip6tables) 

TASK [os_firewall : Wait 10 seconds after disabling iptables] ******************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.042)       0:04:04.056 **** 
skipping: [40.117.134.83]

TASK [os_firewall : Start and enable firewalld service] ************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.029)       0:04:04.085 **** 
skipping: [40.117.134.83]

TASK [os_firewall : need to pause here, otherwise the firewalld service starting can sometimes cause ssh to fail] ***
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.026)       0:04:04.111 **** 
skipping: [40.117.134.83]

TASK [os_firewall : Restart polkitd] *******************************************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.027)       0:04:04.138 **** 
skipping: [40.117.134.83]

TASK [os_firewall : Wait for polkit action to have been created] ***************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.027)       0:04:04.166 **** 
skipping: [40.117.134.83]

TASK [os_firewall : Ensure firewalld service is not enabled] *******************
Wednesday 21 November 2018  03:52:38 +0000 (0:00:00.027)       0:04:04.194 **** 
changed: [40.117.134.83]

TASK [os_firewall : Wait 10 seconds after disabling firewalld] *****************
Wednesday 21 November 2018  03:52:40 +0000 (0:00:01.383)       0:04:05.577 **** 
Pausing for 10 seconds
(ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
ok: [40.117.134.83]

TASK [os_firewall : Install iptables packages] *********************************
Wednesday 21 November 2018  03:52:50 +0000 (0:00:10.057)       0:04:15.634 **** 
ok: [40.117.134.83] => (item=iptables)
changed: [40.117.134.83] => (item=iptables-services)

TASK [os_firewall : Start and enable iptables service] *************************
Wednesday 21 November 2018  03:52:56 +0000 (0:00:05.917)       0:04:21.551 **** 
changed: [40.117.134.83 -> 40.117.134.83] => (item=40.117.134.83)

TASK [os_firewall : need to pause here, otherwise the iptables service starting can sometimes cause ssh to fail] ***
Wednesday 21 November 2018  03:52:56 +0000 (0:00:00.569)       0:04:22.120 **** 
Pausing for 10 seconds
(ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
ok: [40.117.134.83]
 [WARNING]: Could not match supplied host pattern, ignoring:
oo_hosts_containerized_managed_true

PLAY [oo_nodes_to_config:oo_hosts_containerized_managed_true] ******************

TASK [container_runtime : Setup the docker-storage for overlay] ****************
Wednesday 21 November 2018  03:53:06 +0000 (0:00:10.072)       0:04:32.193 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create file system on extra volume device] ***********
Wednesday 21 November 2018  03:53:06 +0000 (0:00:00.026)       0:04:32.219 **** 

TASK [container_runtime : Create mount entry for extra volume] *****************
Wednesday 21 November 2018  03:53:06 +0000 (0:00:00.023)       0:04:32.243 **** 

PLAY [oo_nodes_to_config] ******************************************************

TASK [openshift_excluder : Install docker excluder - yum] **********************
Wednesday 21 November 2018  03:53:06 +0000 (0:00:00.043)       0:04:32.286 **** 
changed: [40.117.134.83]

TASK [openshift_excluder : Install docker excluder - dnf] **********************
Wednesday 21 November 2018  03:53:09 +0000 (0:00:03.035)       0:04:35.322 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install openshift excluder - yum] *******************
Wednesday 21 November 2018  03:53:09 +0000 (0:00:00.043)       0:04:35.365 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install openshift excluder - dnf] *******************
Wednesday 21 November 2018  03:53:09 +0000 (0:00:00.037)       0:04:35.403 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : set_fact] *******************************************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.036)       0:04:35.439 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Check for docker-excluder] **************************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.055)       0:04:35.495 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Enable docker excluder] *****************************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.221)       0:04:35.717 **** 
changed: [40.117.134.83]

TASK [openshift_excluder : Check for openshift excluder] ***********************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.263)       0:04:35.980 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Enable openshift excluder] **************************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.223)       0:04:36.204 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Getting current systemd-udevd exec command] **********
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.026)       0:04:36.230 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Assure systemd-udevd.service.d directory exists] *****
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.029)       0:04:36.260 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create systemd-udevd override file] ******************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.029)       0:04:36.290 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add enterprise registry, if necessary] ***************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.029)       0:04:36.320 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add http_proxy to /etc/atomic.conf] ******************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.028)       0:04:36.349 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add https_proxy to /etc/atomic.conf] *****************
Wednesday 21 November 2018  03:53:10 +0000 (0:00:00.028)       0:04:36.377 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add no_proxy to /etc/atomic.conf] ********************
Wednesday 21 November 2018  03:53:11 +0000 (0:00:00.027)       0:04:36.404 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Get current installed Docker version] ****************
Wednesday 21 November 2018  03:53:11 +0000 (0:00:00.025)       0:04:36.430 **** 
ok: [40.117.134.83]

TASK [container_runtime : Error out if Docker pre-installed but too old] *******
Wednesday 21 November 2018  03:53:11 +0000 (0:00:00.452)       0:04:36.882 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Error out if requested Docker is too old] ************
Wednesday 21 November 2018  03:53:11 +0000 (0:00:00.031)       0:04:36.914 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Install Docker] **************************************
Wednesday 21 November 2018  03:53:11 +0000 (0:00:00.028)       0:04:36.942 **** 
changed: [40.117.134.83]

TASK [container_runtime : Ensure docker.service.d directory exists] ************
Wednesday 21 November 2018  03:54:10 +0000 (0:00:59.329)       0:05:36.272 **** 
changed: [40.117.134.83]

TASK [container_runtime : Configure Docker service unit file] ******************
Wednesday 21 November 2018  03:54:11 +0000 (0:00:00.250)       0:05:36.523 **** 
changed: [40.117.134.83]

TASK [container_runtime : stat] ************************************************
Wednesday 21 November 2018  03:54:12 +0000 (0:00:01.315)       0:05:37.839 **** 
ok: [40.117.134.83]

TASK [container_runtime : Set registry params] *********************************
Wednesday 21 November 2018  03:54:12 +0000 (0:00:00.320)       0:05:38.160 **** 
skipping: [40.117.134.83] => (item={u'reg_conf_var': u'ADD_REGISTRY', u'reg_flag': u'--add-registry', u'reg_fact_val': []}) 
skipping: [40.117.134.83] => (item={u'reg_conf_var': u'BLOCK_REGISTRY', u'reg_flag': u'--block-registry', u'reg_fact_val': []}) 
skipping: [40.117.134.83] => (item={u'reg_conf_var': u'INSECURE_REGISTRY', u'reg_flag': u'--insecure-registry', u'reg_fact_val': []}) 

TASK [container_runtime : Place additional/blocked/insecure registries in /etc/containers/registries.conf] ***
Wednesday 21 November 2018  03:54:12 +0000 (0:00:00.068)       0:05:38.228 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Set Proxy Settings] **********************************
Wednesday 21 November 2018  03:54:12 +0000 (0:00:00.027)       0:05:38.256 **** 
skipping: [40.117.134.83] => (item={u'reg_conf_var': u'HTTP_PROXY', u'reg_fact_val': u''}) 
skipping: [40.117.134.83] => (item={u'reg_conf_var': u'HTTPS_PROXY', u'reg_fact_val': u''}) 
skipping: [40.117.134.83] => (item={u'reg_conf_var': u'NO_PROXY', u'reg_fact_val': u''}) 

TASK [container_runtime : Set various Docker options] **************************
Wednesday 21 November 2018  03:54:12 +0000 (0:00:00.076)       0:05:38.333 **** 
changed: [40.117.134.83]

TASK [container_runtime : stat] ************************************************
Wednesday 21 November 2018  03:54:13 +0000 (0:00:00.429)       0:05:38.762 **** 
ok: [40.117.134.83]

TASK [container_runtime : Configure Docker Network OPTIONS] ********************
Wednesday 21 November 2018  03:54:13 +0000 (0:00:00.222)       0:05:38.985 **** 
changed: [40.117.134.83]

TASK [container_runtime : Detect if docker is already started] *****************
Wednesday 21 November 2018  03:54:13 +0000 (0:00:00.235)       0:05:39.220 **** 
ok: [40.117.134.83]

TASK [container_runtime : Start the Docker service] ****************************
Wednesday 21 November 2018  03:54:14 +0000 (0:00:00.237)       0:05:39.457 **** 
changed: [40.117.134.83]

TASK [container_runtime : set_fact] ********************************************
Wednesday 21 November 2018  03:54:16 +0000 (0:00:02.669)       0:05:42.127 **** 
ok: [40.117.134.83]

TASK [container_runtime : Check for docker_storage_path/overlay2] **************
Wednesday 21 November 2018  03:54:16 +0000 (0:00:00.049)       0:05:42.176 **** 
ok: [40.117.134.83]

TASK [container_runtime : Fixup SELinux permissions for docker] ****************
Wednesday 21 November 2018  03:54:17 +0000 (0:00:00.248)       0:05:42.424 **** 
changed: [40.117.134.83]

TASK [container_runtime : Ensure /var/lib/containers exists] *******************
Wednesday 21 November 2018  03:54:17 +0000 (0:00:00.853)       0:05:43.278 **** 
changed: [40.117.134.83]

TASK [container_runtime : Fix SELinux Permissions on /var/lib/containers] ******
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.230)       0:05:43.509 **** 
ok: [40.117.134.83]

RUNNING HANDLER [container_runtime : restart container runtime] ****************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.219)       0:05:43.729 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Check for credentials file for registry auth] ********
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.033)       0:05:43.762 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create credentials for docker cli registry auth] *****
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.028)       0:05:43.790 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create credentials for docker cli registry auth (alternative)] ***
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.030)       0:05:43.820 **** 
skipping: [40.117.134.83]

TASK [container_runtime : stat the docker data dir] ****************************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.028)       0:05:43.849 **** 
ok: [40.117.134.83]

TASK [container_runtime : stop the current running docker] *********************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.233)       0:05:44.083 **** 
skipping: [40.117.134.83]

TASK [container_runtime : copy "/var/lib/docker" to "/var/lib/containers/docker"] ***
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.030)       0:05:44.113 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Set the selinux context on /var/lib/containers/docker] ***
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.029)       0:05:44.142 **** 
skipping: [40.117.134.83]

TASK [container_runtime : restorecon the /var/lib/containers/docker] ***********
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.029)       0:05:44.172 **** 
skipping: [40.117.134.83]

TASK [container_runtime : ensure the unmount of top level mount point] *********
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.028)       0:05:44.200 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Remove the old docker location] **********************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.027)       0:05:44.228 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Setup the link] **************************************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.026)       0:05:44.254 **** 
skipping: [40.117.134.83]

TASK [container_runtime : start docker] ****************************************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.028)       0:05:44.283 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Fail if Atomic Host since this is an rpm request] ****
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.028)       0:05:44.311 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Getting current systemd-udevd exec command] **********
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.026)       0:05:44.338 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Assure systemd-udevd.service.d directory exists] *****
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.026)       0:05:44.364 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create systemd-udevd override file] ******************
Wednesday 21 November 2018  03:54:18 +0000 (0:00:00.024)       0:05:44.389 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add enterprise registry, if necessary] ***************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.027)       0:05:44.417 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Check that overlay is in the kernel] *****************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.442 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add overlay to modprobe.d] ***************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.467 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Manually modprobe overlay into the kernel] ***********
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.024)       0:05:44.492 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Enable and start systemd-modules-load] ***************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.024)       0:05:44.516 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Install cri-o] ***************************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.542 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Remove CRI-O default configuration files] ************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.568 **** 
skipping: [40.117.134.83] => (item=/etc/cni/net.d/200-loopback.conf) 
skipping: [40.117.134.83] => (item=/etc/cni/net.d/100-crio-bridge.conf) 

TASK [container_runtime : Create the CRI-O configuration] **********************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.033)       0:05:44.602 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Ensure CNI configuration directory exists] ***********
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.627 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Add iptables allow rules] ****************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.024)       0:05:44.651 **** 
skipping: [40.117.134.83] => (item={u'port': u'10010/tcp', u'service': u'crio'}) 

TASK [container_runtime : Remove iptables rules] *******************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.032)       0:05:44.683 **** 

TASK [container_runtime : Add firewalld allow rules] ***************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.024)       0:05:44.708 **** 
skipping: [40.117.134.83] => (item={u'port': u'10010/tcp', u'service': u'crio'}) 

TASK [container_runtime : Remove firewalld allow rules] ************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.032)       0:05:44.741 **** 

TASK [container_runtime : Configure the CNI network] ***************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.023)       0:05:44.764 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create /etc/sysconfig/crio-network] ******************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.790 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Start the CRI-O service] *****************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.815 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Ensure /var/lib/containers exists] *******************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.024)       0:05:44.840 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Fix SELinux Permissions on /var/lib/containers] ******
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.024)       0:05:44.864 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Check for credentials file for registry auth] ********
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.026)       0:05:44.891 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create credentials for docker cli registry auth] *****
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.026)       0:05:44.918 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Create credentials for docker cli registry auth (alternative)] ***
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.026)       0:05:44.944 **** 
skipping: [40.117.134.83]

TASK [container_runtime : stat the docker data dir] ****************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.970 **** 
skipping: [40.117.134.83]

TASK [container_runtime : stop the current running docker] *********************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:44.996 **** 
skipping: [40.117.134.83]

TASK [container_runtime : copy "/var/lib/docker" to "/var/lib/containers/docker"] ***
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.028)       0:05:45.024 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Set the selinux context on /var/lib/containers/docker] ***
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.029)       0:05:45.053 **** 
skipping: [40.117.134.83]

TASK [container_runtime : restorecon the /var/lib/containers/docker] ***********
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.027)       0:05:45.081 **** 
skipping: [40.117.134.83]

TASK [container_runtime : ensure the unmount of top level mount point] *********
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.027)       0:05:45.108 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Remove the old docker location] **********************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.027)       0:05:45.136 **** 
skipping: [40.117.134.83]

TASK [container_runtime : Setup the link] **************************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:45.162 **** 
skipping: [40.117.134.83]

TASK [container_runtime : start docker] ****************************************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.025)       0:05:45.188 **** 
skipping: [40.117.134.83]

PLAY [Disable excluders] *******************************************************

TASK [openshift_excluder : Detecting Atomic Host Operating System] *************
Wednesday 21 November 2018  03:54:19 +0000 (0:00:00.033)       0:05:45.222 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Debug r_openshift_excluder_enable_docker_excluder] ***
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.225)       0:05:45.448 **** 
ok: [40.117.134.83] => {
    "r_openshift_excluder_enable_docker_excluder": true
}

TASK [openshift_excluder : Debug r_openshift_excluder_enable_openshift_excluder] ***
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.051)       0:05:45.499 **** 
ok: [40.117.134.83] => {
    "r_openshift_excluder_enable_openshift_excluder": true
}

TASK [openshift_excluder : Fail if invalid openshift_excluder_action provided] ***
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.050)       0:05:45.549 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Fail if r_openshift_excluder_upgrade_target is not defined] ***
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.027)       0:05:45.576 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Include main action task file] **********************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.027)       0:05:45.603 **** 
included: /usr/share/ansible/openshift-ansible/roles/openshift_excluder/tasks/disable.yml for 40.117.134.83

TASK [openshift_excluder : Get available excluder version] *********************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.126)       0:05:45.730 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Fail when excluder package is not found] ************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.023)       0:05:45.753 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Set fact excluder_version] **************************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.021)       0:05:45.775 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : origin-docker-excluder version detected] ************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.026)       0:05:45.801 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Printing upgrade target version] ********************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.021)       0:05:45.823 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check the available origin-docker-excluder version is at most of the upgrade target version] ***
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.024)       0:05:45.847 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Get available excluder version] *********************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.023)       0:05:45.871 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Fail when excluder package is not found] ************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.022)       0:05:45.893 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Set fact excluder_version] **************************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.021)       0:05:45.914 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : origin-excluder version detected] *******************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.025)       0:05:45.940 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Printing upgrade target version] ********************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.023)       0:05:45.963 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check the available origin-excluder version is at most of the upgrade target version] ***
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.027)       0:05:45.990 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check for docker-excluder] **************************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.022)       0:05:46.013 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : disable docker excluder] ****************************
Wednesday 21 November 2018  03:54:20 +0000 (0:00:00.234)       0:05:46.247 **** 
changed: [40.117.134.83]

TASK [openshift_excluder : Check for openshift excluder] ***********************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.274)       0:05:46.521 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : disable openshift excluder] *************************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.297)       0:05:46.819 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install docker excluder - yum] **********************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.034)       0:05:46.853 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install docker excluder - dnf] **********************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.028)       0:05:46.882 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install openshift excluder - yum] *******************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.027)       0:05:46.909 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install openshift excluder - dnf] *******************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.027)       0:05:46.937 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : set_fact] *******************************************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.027)       0:05:46.965 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check for docker-excluder] **************************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.027)       0:05:46.993 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Enable docker excluder] *****************************
Wednesday 21 November 2018  03:54:21 +0000 (0:00:00.238)       0:05:47.231 **** 
changed: [40.117.134.83]

TASK [openshift_excluder : Check for openshift excluder] ***********************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.281)       0:05:47.513 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Enable openshift excluder] **************************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.223)       0:05:47.737 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check for docker-excluder] **************************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.032)       0:05:47.770 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : disable docker excluder] ****************************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.229)       0:05:48.000 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check for openshift excluder] ***********************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.024)       0:05:48.025 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : disable openshift excluder] *************************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.229)       0:05:48.254 **** 
skipping: [40.117.134.83]

PLAY [Configure nodes] *********************************************************

TASK [openshift_cloud_provider : Set cloud provider facts] *********************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.071)       0:05:48.325 **** 
skipping: [40.117.134.83]

TASK [openshift_cloud_provider : Create cloudprovider config dir] **************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.025)       0:05:48.351 **** 
skipping: [40.117.134.83]

TASK [openshift_cloud_provider : include the defined cloud provider files] *****
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.024)       0:05:48.375 **** 
skipping: [40.117.134.83]

TASK [openshift_node : fail] ***************************************************
Wednesday 21 November 2018  03:54:22 +0000 (0:00:00.024)       0:05:48.400 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Check that node image is present] ***********************
Wednesday 21 November 2018  03:54:23 +0000 (0:00:00.026)       0:05:48.426 **** 
changed: [40.117.134.83]

TASK [openshift_node : Pre-pull node image] ************************************
Wednesday 21 November 2018  03:54:23 +0000 (0:00:00.326)       0:05:48.753 **** 
changed: [40.117.134.83]

TASK [openshift_node : Check that pod image is present] ************************
Wednesday 21 November 2018  03:54:25 +0000 (0:00:01.752)       0:05:50.506 **** 
changed: [40.117.134.83]

TASK [openshift_node : pre-pull pod image] *************************************
Wednesday 21 November 2018  03:54:25 +0000 (0:00:00.455)       0:05:50.961 **** 
changed: [40.117.134.83]

TASK [openshift_node : Check for NetworkManager service] ***********************
Wednesday 21 November 2018  03:54:27 +0000 (0:00:02.032)       0:05:52.994 **** 
ok: [40.117.134.83]

TASK [openshift_node : Set fact using_network_manager] *************************
Wednesday 21 November 2018  03:54:28 +0000 (0:00:00.520)       0:05:53.514 **** 
ok: [40.117.134.83]

TASK [openshift_node : Install dnsmasq] ****************************************
Wednesday 21 November 2018  03:54:28 +0000 (0:00:00.046)       0:05:53.560 **** 
changed: [40.117.134.83]

TASK [openshift_node : ensure origin/node directory exists] ********************
Wednesday 21 November 2018  03:54:34 +0000 (0:00:05.999)       0:05:59.559 **** 
changed: [40.117.134.83] => (item=/etc/origin)
changed: [40.117.134.83] => (item=/etc/origin/node)

TASK [openshift_node : Install NetworkManager during node_bootstrap provisioning] ***
Wednesday 21 November 2018  03:54:34 +0000 (0:00:00.728)       0:06:00.288 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Install network manager dispatch script] ****************
Wednesday 21 November 2018  03:54:34 +0000 (0:00:00.028)       0:06:00.316 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Add network manager configuration] **********************
Wednesday 21 November 2018  03:54:34 +0000 (0:00:00.028)       0:06:00.344 **** 
skipping: [40.117.134.83] => (item={u'key': u'dns', u'value': u'none', u'present': False}) 

TASK [openshift_node : Install dnsmasq configuration] **************************
Wednesday 21 November 2018  03:54:34 +0000 (0:00:00.043)       0:06:00.387 **** 
changed: [40.117.134.83]

TASK [openshift_node : Deploy additional dnsmasq.conf] *************************
Wednesday 21 November 2018  03:54:35 +0000 (0:00:00.958)       0:06:01.346 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Create override dnsmasq systemd unit directory] *********
Wednesday 21 November 2018  03:54:35 +0000 (0:00:00.027)       0:06:01.373 **** 
changed: [40.117.134.83]

TASK [openshift_node : Copy systemd dnsmasq unit override file] ****************
Wednesday 21 November 2018  03:54:36 +0000 (0:00:00.347)       0:06:01.720 **** 
changed: [40.117.134.83]

TASK [openshift_node : Enable dnsmasq] *****************************************
Wednesday 21 November 2018  03:54:36 +0000 (0:00:00.647)       0:06:02.367 **** 
changed: [40.117.134.83]

TASK [openshift_node : Install network manager dispatch script] ****************
Wednesday 21 November 2018  03:54:37 +0000 (0:00:00.394)       0:06:02.762 **** 
changed: [40.117.134.83]

TASK [openshift_node : Add network manager configuration] **********************
Wednesday 21 November 2018  03:54:37 +0000 (0:00:00.581)       0:06:03.344 **** 
ok: [40.117.134.83] => (item={u'key': u'dns', u'value': u'none', u'present': False})

RUNNING HANDLER [openshift_node : reload systemd units] ************************
Wednesday 21 November 2018  03:54:38 +0000 (0:00:00.406)       0:06:03.751 **** 
skipping: [40.117.134.83]

RUNNING HANDLER [openshift_node : restart NetworkManager] **********************
Wednesday 21 November 2018  03:54:38 +0000 (0:00:00.025)       0:06:03.777 **** 
skipping: [40.117.134.83]

RUNNING HANDLER [openshift_node : restart dnsmasq] *****************************
Wednesday 21 November 2018  03:54:38 +0000 (0:00:00.025)       0:06:03.802 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Add iptables allow rules] *******************************
Wednesday 21 November 2018  03:54:38 +0000 (0:00:00.034)       0:06:03.837 **** 
changed: [40.117.134.83] => (item={u'port': u'10250/tcp', u'service': u'Kubernetes kubelet'})
changed: [40.117.134.83] => (item={u'port': u'10256/tcp', u'service': u'Kubernetes kube-proxy health check for service load balancers'})
changed: [40.117.134.83] => (item={u'port': u'80/tcp', u'service': u'http'})
changed: [40.117.134.83] => (item={u'port': u'443/tcp', u'service': u'https'})
changed: [40.117.134.83] => (item={u'cond': u'openshift_use_openshift_sdn | bool', u'port': u'4789/udp', u'service': u'OpenShift OVS sdn'})
skipping: [40.117.134.83] => (item={u'cond': False, u'port': u'179/tcp', u'service': u'Calico BGP Port'}) 
skipping: [40.117.134.83] => (item={u'cond': False, u'port': u'/tcp', u'service': u'Kubernetes service NodePort TCP'}) 
skipping: [40.117.134.83] => (item={u'cond': False, u'port': u'/udp', u'service': u'Kubernetes service NodePort UDP'}) 
changed: [40.117.134.83] => (item={u'port': u'9000-10000/tcp', u'service': u'Prometheus monitoring'})

TASK [openshift_node : Remove iptables rules] **********************************
Wednesday 21 November 2018  03:54:42 +0000 (0:00:03.920)       0:06:07.757 **** 

TASK [openshift_node : Add firewalld allow rules] ******************************
Wednesday 21 November 2018  03:54:42 +0000 (0:00:00.024)       0:06:07.782 **** 
skipping: [40.117.134.83] => (item={u'port': u'10250/tcp', u'service': u'Kubernetes kubelet'}) 
skipping: [40.117.134.83] => (item={u'port': u'10256/tcp', u'service': u'Kubernetes kube-proxy health check for service load balancers'}) 
skipping: [40.117.134.83] => (item={u'port': u'80/tcp', u'service': u'http'}) 
skipping: [40.117.134.83] => (item={u'port': u'443/tcp', u'service': u'https'}) 
skipping: [40.117.134.83] => (item={u'cond': u'openshift_use_openshift_sdn | bool', u'port': u'4789/udp', u'service': u'OpenShift OVS sdn'}) 
skipping: [40.117.134.83] => (item={u'cond': False, u'port': u'179/tcp', u'service': u'Calico BGP Port'}) 
skipping: [40.117.134.83] => (item={u'cond': False, u'port': u'/tcp', u'service': u'Kubernetes service NodePort TCP'}) 
skipping: [40.117.134.83] => (item={u'cond': False, u'port': u'/udp', u'service': u'Kubernetes service NodePort UDP'}) 
skipping: [40.117.134.83] => (item={u'port': u'9000-10000/tcp', u'service': u'Prometheus monitoring'}) 

TASK [openshift_node : Remove firewalld allow rules] ***************************
Wednesday 21 November 2018  03:54:42 +0000 (0:00:00.132)       0:06:07.915 **** 

TASK [openshift_node : Checking for journald.conf] *****************************
Wednesday 21 November 2018  03:54:42 +0000 (0:00:00.024)       0:06:07.939 **** 
ok: [40.117.134.83]

TASK [openshift_node : Create journald persistence directories] ****************
Wednesday 21 November 2018  03:54:42 +0000 (0:00:00.377)       0:06:08.317 **** 
changed: [40.117.134.83]

TASK [openshift_node : Update journald setup] **********************************
Wednesday 21 November 2018  03:54:43 +0000 (0:00:00.355)       0:06:08.673 **** 
changed: [40.117.134.83] => (item={u'var': u'Storage', u'val': u'persistent'})
changed: [40.117.134.83] => (item={u'var': u'Compress', u'val': True})
changed: [40.117.134.83] => (item={u'var': u'SyncIntervalSec', u'val': u'1s'})
changed: [40.117.134.83] => (item={u'var': u'RateLimitInterval', u'val': u'1s'})
changed: [40.117.134.83] => (item={u'var': u'RateLimitBurst', u'val': 10000})
changed: [40.117.134.83] => (item={u'var': u'SystemMaxUse', u'val': u'8G'})
ok: [40.117.134.83] => (item={u'var': u'SystemKeepFree', u'val': u'20%'})
changed: [40.117.134.83] => (item={u'var': u'SystemMaxFileSize', u'val': u'10M'})
changed: [40.117.134.83] => (item={u'var': u'MaxRetentionSec', u'val': u'1month'})
ok: [40.117.134.83] => (item={u'var': u'MaxFileSec', u'val': u'1day'})
changed: [40.117.134.83] => (item={u'var': u'ForwardToSyslog', u'val': False})
changed: [40.117.134.83] => (item={u'var': u'ForwardToWall', u'val': False})

TASK [openshift_node : Restart journald] ***************************************
Wednesday 21 November 2018  03:54:46 +0000 (0:00:03.011)       0:06:11.684 **** 
changed: [40.117.134.83]

TASK [openshift_node : Disable swap] *******************************************
Wednesday 21 November 2018  03:54:47 +0000 (0:00:00.792)       0:06:12.477 **** 
ok: [40.117.134.83]

TASK [openshift_node : Install node, clients, and conntrack packages] **********
Wednesday 21 November 2018  03:54:47 +0000 (0:00:00.507)       0:06:12.985 **** 
changed: [40.117.134.83] => (item={u'name': u'origin-node-3.10*'})
ok: [40.117.134.83] => (item={u'name': u'origin-clients-3.10*'})
ok: [40.117.134.83] => (item={u'name': u'conntrack-tools'})

TASK [openshift_node : Restart cri-o] ******************************************
Wednesday 21 November 2018  03:57:09 +0000 (0:02:21.704)       0:08:34.689 **** 
skipping: [40.117.134.83]

TASK [openshift_node : restart NetworkManager to ensure resolv.conf is present] ***
Wednesday 21 November 2018  03:57:09 +0000 (0:00:00.025)       0:08:34.715 **** 
changed: [40.117.134.83]

TASK [openshift_node : sysctl] *************************************************
Wednesday 21 November 2018  03:57:09 +0000 (0:00:00.473)       0:08:35.188 **** 
changed: [40.117.134.83]

TASK [openshift_node : Setting sebool container_manage_cgroup] *****************
Wednesday 21 November 2018  03:57:10 +0000 (0:00:00.461)       0:08:35.650 **** 
changed: [40.117.134.83]

TASK [openshift_node : Check for credentials file for registry auth] ***********
Wednesday 21 November 2018  03:57:11 +0000 (0:00:01.207)       0:08:36.858 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Create credentials for registry auth] *******************
Wednesday 21 November 2018  03:57:11 +0000 (0:00:00.026)       0:08:36.884 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Create credentials for registry auth (alternative)] *****
Wednesday 21 November 2018  03:57:11 +0000 (0:00:00.028)       0:08:36.913 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Setup ro mount of /root/.docker for containerized hosts] ***
Wednesday 21 November 2018  03:57:11 +0000 (0:00:00.026)       0:08:36.940 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Check status of node image pre-pull] ********************
Wednesday 21 November 2018  03:57:11 +0000 (0:00:00.024)       0:08:36.964 **** 
changed: [40.117.134.83]

TASK [openshift_node : Check status of node pod image pre-pull] ****************
Wednesday 21 November 2018  03:57:11 +0000 (0:00:00.403)       0:08:37.368 **** 
changed: [40.117.134.83]

TASK [openshift_node : Copy node container image to ostree storage] ************
Wednesday 21 November 2018  03:57:12 +0000 (0:00:00.296)       0:08:37.664 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Copy node script to the node] ***************************
Wednesday 21 November 2018  03:57:12 +0000 (0:00:00.025)       0:08:37.690 **** 
changed: [40.117.134.83]

TASK [openshift_node : Install Node service file] ******************************
Wednesday 21 November 2018  03:57:12 +0000 (0:00:00.632)       0:08:38.323 **** 
changed: [40.117.134.83]

TASK [openshift_node : Ensure old system path is set] **************************
Wednesday 21 November 2018  03:57:13 +0000 (0:00:00.736)       0:08:39.059 **** 
skipping: [40.117.134.83] => (item=/etc/origin/openvswitch) 
skipping: [40.117.134.83] => (item=/var/lib/kubelet) 
skipping: [40.117.134.83] => (item=/opt/cni/bin) 

TASK [openshift_node : Install or Update node system container] ****************
Wednesday 21 November 2018  03:57:13 +0000 (0:00:00.071)       0:08:39.130 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Restart network manager to ensure networking configuration is in place] ***
Wednesday 21 November 2018  03:57:13 +0000 (0:00:00.032)       0:08:39.163 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Configure Node settings] ********************************
Wednesday 21 November 2018  03:57:13 +0000 (0:00:00.029)       0:08:39.192 **** 
changed: [40.117.134.83] => (item={u'regex': u'^OPTIONS=', u'line': u'OPTIONS='})
changed: [40.117.134.83] => (item={u'regex': u'^DEBUG_LOGLEVEL=', u'line': u'DEBUG_LOGLEVEL=2'})
changed: [40.117.134.83] => (item={u'regex': u'^IMAGE_VERSION=', u'line': u'IMAGE_VERSION=v3.10'})

TASK [openshift_node : openshift_facts] ****************************************
Wednesday 21 November 2018  03:57:14 +0000 (0:00:00.676)       0:08:39.868 **** 
changed: [40.117.134.83]

TASK [openshift_node : Configure Proxy Settings] *******************************
Wednesday 21 November 2018  03:57:16 +0000 (0:00:02.504)       0:08:42.372 **** 
skipping: [40.117.134.83] => (item={u'regex': u'^HTTP_PROXY=', u'line': u'HTTP_PROXY='}) 
skipping: [40.117.134.83] => (item={u'regex': u'^HTTPS_PROXY=', u'line': u'HTTPS_PROXY='}) 
skipping: [40.117.134.83] => (item={u'regex': u'^NO_PROXY=', u'line': u'NO_PROXY=[],172.30.0.0/16,10.128.0.0/14'}) 

TASK [openshift_node : file] ***************************************************
Wednesday 21 November 2018  03:57:17 +0000 (0:00:00.080)       0:08:42.453 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Create the Node config] *********************************
Wednesday 21 November 2018  03:57:17 +0000 (0:00:00.036)       0:08:42.490 **** 
changed: [40.117.134.83]

TASK [openshift_node : Configure Node Environment Variables] *******************
Wednesday 21 November 2018  03:57:17 +0000 (0:00:00.707)       0:08:43.197 **** 

TASK [openshift_node : Ensure the node static pod directory exists] ************
Wednesday 21 November 2018  03:57:17 +0000 (0:00:00.024)       0:08:43.222 **** 
changed: [40.117.134.83]

TASK [openshift_node : Create flexvolume directory when running on atomic] *****
Wednesday 21 November 2018  03:57:18 +0000 (0:00:00.231)       0:08:43.453 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Configure AWS Cloud Provider Settings] ******************
Wednesday 21 November 2018  03:57:18 +0000 (0:00:00.027)       0:08:43.480 **** 
skipping: [40.117.134.83] => (item=None) 
skipping: [40.117.134.83] => (item=None) 

TASK [openshift_node : Install NFS storage plugin dependencies] ****************
Wednesday 21 November 2018  03:57:18 +0000 (0:00:00.050)       0:08:43.531 **** 
ok: [40.117.134.83]

TASK [openshift_node : Check for existence of nfs sebooleans] ******************
Wednesday 21 November 2018  03:57:18 +0000 (0:00:00.494)       0:08:44.026 **** 
ok: [40.117.134.83] => (item=virt_use_nfs)
ok: [40.117.134.83] => (item=virt_sandbox_use_nfs)

TASK [openshift_node : Set seboolean to allow nfs storage plugin access from containers] ***
Wednesday 21 November 2018  03:57:19 +0000 (0:00:00.534)       0:08:44.560 **** 
ok: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:57:18.921522', '_ansible_no_log': False, u'stdout': u'virt_use_nfs --> on', u'cmd': [u'getsebool', u'virt_use_nfs'], u'rc': 0, 'item': u'virt_use_nfs', u'delta': u'0:00:00.104104', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_use_nfs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_use_nfs --> on'], 'failed_when_result': False, u'start': u'2018-11-21 03:57:18.817418', '_ansible_ignore_errors': None, 'failed': False})
skipping: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:57:19.124224', '_ansible_no_log': False, u'stdout': u'virt_use_nfs --> on', u'cmd': [u'getsebool', u'virt_sandbox_use_nfs'], u'rc': 0, 'item': u'virt_sandbox_use_nfs', u'delta': u'0:00:00.004163', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_sandbox_use_nfs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_use_nfs --> on'], 'failed_when_result': False, u'start': u'2018-11-21 03:57:19.120061', '_ansible_ignore_errors': None, 'failed': False}) 

TASK [openshift_node : Set seboolean to allow nfs storage plugin access from containers (python 3)] ***
Wednesday 21 November 2018  03:57:19 +0000 (0:00:00.278)       0:08:44.839 **** 
skipping: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:57:18.921522', '_ansible_no_log': False, u'stdout': u'virt_use_nfs --> on', u'cmd': [u'getsebool', u'virt_use_nfs'], u'rc': 0, 'item': u'virt_use_nfs', u'delta': u'0:00:00.104104', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_use_nfs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_use_nfs --> on'], 'failed_when_result': False, u'start': u'2018-11-21 03:57:18.817418', '_ansible_ignore_errors': None, 'failed': False}) 
skipping: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:57:19.124224', '_ansible_no_log': False, u'stdout': u'virt_use_nfs --> on', u'cmd': [u'getsebool', u'virt_sandbox_use_nfs'], u'rc': 0, 'item': u'virt_sandbox_use_nfs', u'delta': u'0:00:00.004163', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_sandbox_use_nfs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_use_nfs --> on'], 'failed_when_result': False, u'start': u'2018-11-21 03:57:19.120061', '_ansible_ignore_errors': None, 'failed': False}) 

TASK [openshift_node : Install GlusterFS storage plugin dependencies] **********
Wednesday 21 November 2018  03:57:19 +0000 (0:00:00.060)       0:08:44.900 **** 
changed: [40.117.134.83]

TASK [openshift_node : Check for existence of fusefs sebooleans] ***************
Wednesday 21 November 2018  03:58:09 +0000 (0:00:50.327)       0:09:35.228 **** 
ok: [40.117.134.83] => (item=virt_use_fusefs)
ok: [40.117.134.83] => (item=virt_sandbox_use_fusefs)

TASK [openshift_node : Set seboolean to allow gluster storage plugin access from containers] ***
Wednesday 21 November 2018  03:58:10 +0000 (0:00:00.483)       0:09:35.712 **** 
changed: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:58:10.038673', '_ansible_no_log': False, u'stdout': u'virt_use_fusefs --> off', u'cmd': [u'getsebool', u'virt_use_fusefs'], u'rc': 0, 'item': u'virt_use_fusefs', u'delta': u'0:00:00.004284', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_use_fusefs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_use_fusefs --> off'], 'failed_when_result': False, u'start': u'2018-11-21 03:58:10.034389', '_ansible_ignore_errors': None, 'failed': False})
changed: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:58:10.273179', '_ansible_no_log': False, u'stdout': u'virt_sandbox_use_fusefs --> off', u'cmd': [u'getsebool', u'virt_sandbox_use_fusefs'], u'rc': 0, 'item': u'virt_sandbox_use_fusefs', u'delta': u'0:00:00.004163', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_sandbox_use_fusefs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_sandbox_use_fusefs --> off'], 'failed_when_result': False, u'start': u'2018-11-21 03:58:10.269016', '_ansible_ignore_errors': None, 'failed': False})

TASK [openshift_node : Set seboolean to allow gluster storage plugin access from containers (python 3)] ***
Wednesday 21 November 2018  03:58:12 +0000 (0:00:02.184)       0:09:37.897 **** 
skipping: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:58:10.038673', '_ansible_no_log': False, u'stdout': u'virt_use_fusefs --> off', u'cmd': [u'getsebool', u'virt_use_fusefs'], u'rc': 0, 'item': u'virt_use_fusefs', u'delta': u'0:00:00.004284', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_use_fusefs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_use_fusefs --> off'], 'failed_when_result': False, u'start': u'2018-11-21 03:58:10.034389', '_ansible_ignore_errors': None, 'failed': False}) 
skipping: [40.117.134.83] => (item={'_ansible_parsed': True, 'stderr_lines': [], '_ansible_item_result': True, u'end': u'2018-11-21 03:58:10.273179', '_ansible_no_log': False, u'stdout': u'virt_sandbox_use_fusefs --> off', u'cmd': [u'getsebool', u'virt_sandbox_use_fusefs'], u'rc': 0, 'item': u'virt_sandbox_use_fusefs', u'delta': u'0:00:00.004163', u'stderr': u'', u'changed': False, u'invocation': {u'module_args': {u'creates': None, u'executable': None, u'_uses_shell': False, u'_raw_params': u'getsebool virt_sandbox_use_fusefs', u'removes': None, u'warn': True, u'chdir': None, u'stdin': None}}, 'stdout_lines': [u'virt_sandbox_use_fusefs --> off'], 'failed_when_result': False, u'start': u'2018-11-21 03:58:10.269016', '_ansible_ignore_errors': None, 'failed': False}) 

TASK [openshift_node : Install Ceph storage plugin dependencies] ***************
Wednesday 21 November 2018  03:58:12 +0000 (0:00:00.074)       0:09:37.971 **** 
changed: [40.117.134.83]

TASK [openshift_node : Install iSCSI storage plugin dependencies] **************
Wednesday 21 November 2018  03:58:25 +0000 (0:00:13.159)       0:09:51.130 **** 
changed: [40.117.134.83] => (item=iscsi-initiator-utils)
changed: [40.117.134.83] => (item=device-mapper-multipath)

TASK [openshift_node : restart services] ***************************************
Wednesday 21 November 2018  03:58:33 +0000 (0:00:07.763)       0:09:58.894 **** 
changed: [40.117.134.83] => (item=multipathd)
changed: [40.117.134.83] => (item=rpcbind)
changed: [40.117.134.83] => (item=iscsid)

TASK [openshift_node : Template multipath configuration] ***********************
Wednesday 21 November 2018  03:58:34 +0000 (0:00:01.057)       0:09:59.952 **** 
changed: [40.117.134.83]

TASK [openshift_node : Enable and start multipath] *****************************
Wednesday 21 November 2018  03:58:35 +0000 (0:00:00.694)       0:10:00.647 **** 
changed: [40.117.134.83]

TASK [tuned : Check for tuned package] *****************************************
Wednesday 21 November 2018  03:58:35 +0000 (0:00:00.561)       0:10:01.208 **** 
ok: [40.117.134.83]

TASK [tuned : Set tuned OpenShift variables] ***********************************
Wednesday 21 November 2018  03:58:36 +0000 (0:00:00.271)       0:10:01.479 **** 
ok: [40.117.134.83]

TASK [tuned : Ensure directory structure exists] *******************************
Wednesday 21 November 2018  03:58:36 +0000 (0:00:00.052)       0:10:01.532 **** 
changed: [40.117.134.83] => (item={'group': u'root', 'uid': 0, 'ctime': 1542771839.7013464, 'state': 'directory', 'gid': 0, 'mode': '02777', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 24})
changed: [40.117.134.83] => (item={'group': u'root', 'uid': 0, 'ctime': 1542771839.7013464, 'state': 'directory', 'gid': 0, 'mode': '02777', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift-control-plane', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 24})
changed: [40.117.134.83] => (item={'group': u'root', 'uid': 0, 'ctime': 1542771839.7013464, 'state': 'directory', 'gid': 0, 'mode': '02777', 'mtime': 1542770121.0, 'owner': 'root', 'path': u'openshift-node', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 24})
skipping: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/recommend.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '02666', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'recommend.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 290}) 
skipping: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/openshift/tuned.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '02666', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift/tuned.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 594}) 
skipping: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/openshift-control-plane/tuned.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '02666', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift-control-plane/tuned.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 744}) 
skipping: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/openshift-node/tuned.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '0666', 'mtime': 1542770121.0, 'owner': 'root', 'path': u'openshift-node/tuned.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 283}) 

TASK [tuned : Ensure files are populated from templates] ***********************
Wednesday 21 November 2018  03:58:36 +0000 (0:00:00.765)       0:10:02.298 **** 
skipping: [40.117.134.83] => (item={'group': u'root', 'uid': 0, 'ctime': 1542771839.7013464, 'state': 'directory', 'gid': 0, 'mode': '02777', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 24}) 
skipping: [40.117.134.83] => (item={'group': u'root', 'uid': 0, 'ctime': 1542771839.7013464, 'state': 'directory', 'gid': 0, 'mode': '02777', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift-control-plane', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 24}) 
skipping: [40.117.134.83] => (item={'group': u'root', 'uid': 0, 'ctime': 1542771839.7013464, 'state': 'directory', 'gid': 0, 'mode': '02777', 'mtime': 1542770121.0, 'owner': 'root', 'path': u'openshift-node', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 24}) 
changed: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/recommend.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '02666', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'recommend.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 290})
changed: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/openshift/tuned.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '02666', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift/tuned.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 594})
changed: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/openshift-control-plane/tuned.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '02666', 'mtime': 1530051323.0, 'owner': 'root', 'path': u'openshift-control-plane/tuned.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 744})
changed: [40.117.134.83] => (item={'src': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates/openshift-node/tuned.conf', 'group': u'root', 'uid': 0, 'ctime': 1542771839.630349, 'state': 'file', 'gid': 0, 'mode': '0666', 'mtime': 1542770121.0, 'owner': 'root', 'path': u'openshift-node/tuned.conf', 'root': u'/usr/share/ansible/openshift-ansible/roles/tuned/templates', 'size': 283})

TASK [tuned : Make tuned use the recommended tuned profile on restart] *********
Wednesday 21 November 2018  03:58:39 +0000 (0:00:02.365)       0:10:04.664 **** 
changed: [40.117.134.83] => (item=/etc/tuned/active_profile)
changed: [40.117.134.83] => (item=/etc/tuned/profile_mode)

TASK [tuned : Restart tuned service] *******************************************
Wednesday 21 November 2018  03:58:39 +0000 (0:00:00.436)       0:10:05.100 **** 
changed: [40.117.134.83]

TASK [nickhammond.logrotate : nickhammond.logrotate | Install logrotate] *******
Wednesday 21 November 2018  03:58:41 +0000 (0:00:01.822)       0:10:06.922 **** 
ok: [40.117.134.83]

TASK [nickhammond.logrotate : nickhammond.logrotate | Setup logrotate.d scripts] ***
Wednesday 21 November 2018  03:58:42 +0000 (0:00:00.495)       0:10:07.418 **** 

TASK [openshift_storage_glusterfs : Ensure device mapper modules loaded] *******
Wednesday 21 November 2018  03:58:42 +0000 (0:00:00.025)       0:10:07.443 **** 
changed: [40.117.134.83]

TASK [openshift_storage_glusterfs : Install kernel modules package on Fedora] ***
Wednesday 21 November 2018  03:58:42 +0000 (0:00:00.598)       0:10:08.041 **** 
skipping: [40.117.134.83]

TASK [openshift_storage_glusterfs : load kernel modules] ***********************
Wednesday 21 November 2018  03:58:42 +0000 (0:00:00.029)       0:10:08.071 **** 
changed: [40.117.134.83]

RUNNING HANDLER [openshift_node : reload systemd units] ************************
Wednesday 21 November 2018  03:58:43 +0000 (0:00:00.606)       0:10:08.678 **** 
skipping: [40.117.134.83]

PLAY [node bootstrap config] ***************************************************

TASK [openshift_node : install needed rpm(s)] **********************************
Wednesday 21 November 2018  03:58:43 +0000 (0:00:00.045)       0:10:08.723 **** 
changed: [40.117.134.83]

TASK [openshift_node : create the directory for node] **************************
Wednesday 21 November 2018  03:59:19 +0000 (0:00:36.504)       0:10:45.228 **** 
skipping: [40.117.134.83]

TASK [openshift_node : laydown systemd override] *******************************
Wednesday 21 November 2018  03:59:19 +0000 (0:00:00.032)       0:10:45.260 **** 
skipping: [40.117.134.83]

TASK [openshift_node : update the sysconfig to have necessary variables] *******
Wednesday 21 November 2018  03:59:19 +0000 (0:00:00.030)       0:10:45.291 **** 
changed: [40.117.134.83] => (item={u'regexp': u'^KUBECONFIG=.*', u'line': u'KUBECONFIG=/etc/origin/node/bootstrap.kubeconfig'})

TASK [openshift_node : Configure AWS Cloud Provider Settings] ******************
Wednesday 21 November 2018  03:59:20 +0000 (0:00:00.238)       0:10:45.530 **** 
skipping: [40.117.134.83] => (item=None) 
skipping: [40.117.134.83] => (item=None) 

TASK [openshift_node : disable origin-node service] ****************************
Wednesday 21 November 2018  03:59:20 +0000 (0:00:00.052)       0:10:45.582 **** 
ok: [40.117.134.83] => (item=origin-node.service)

TASK [openshift_node : Check for RPM generated config marker file .config_managed] ***
Wednesday 21 November 2018  03:59:20 +0000 (0:00:00.255)       0:10:45.838 **** 
ok: [40.117.134.83]

TASK [openshift_node : create directories for bootstrapping] *******************
Wednesday 21 November 2018  03:59:20 +0000 (0:00:00.223)       0:10:46.061 **** 
changed: [40.117.134.83] => (item=/root/openshift_bootstrap)
changed: [40.117.134.83] => (item=/var/lib/origin/openshift.local.config)
changed: [40.117.134.83] => (item=/var/lib/origin/openshift.local.config/node)
changed: [40.117.134.83] => (item=/etc/docker/certs.d/docker-registry.default.svc:5000)

TASK [openshift_node : laydown the bootstrap.yml file for on boot configuration] ***
Wednesday 21 November 2018  03:59:21 +0000 (0:00:00.912)       0:10:46.973 **** 
changed: [40.117.134.83]

TASK [openshift_node : Create a symlink to the node client CA for the docker registry] ***
Wednesday 21 November 2018  03:59:22 +0000 (0:00:00.588)       0:10:47.562 **** 
changed: [40.117.134.83]

TASK [openshift_node : Remove RPM generated config files if present] ***********
Wednesday 21 November 2018  03:59:22 +0000 (0:00:00.234)       0:10:47.797 **** 
skipping: [40.117.134.83] => (item=master) 
skipping: [40.117.134.83] => (item=.config_managed) 

TASK [openshift_node : find all files in /etc/origin/node so we can remove them] ***
Wednesday 21 November 2018  03:59:22 +0000 (0:00:00.034)       0:10:47.832 **** 
skipping: [40.117.134.83]

TASK [openshift_node : Remove everything except the resolv.conf required for node] ***
Wednesday 21 November 2018  03:59:22 +0000 (0:00:00.026)       0:10:47.858 **** 
skipping: [40.117.134.83]

TASK [openshift_node_group : create node config template] **********************
Wednesday 21 November 2018  03:59:22 +0000 (0:00:00.029)       0:10:47.887 **** 
changed: [40.117.134.83]

TASK [openshift_node_group : remove existing node config] **********************
Wednesday 21 November 2018  03:59:23 +0000 (0:00:00.661)       0:10:48.549 **** 
changed: [40.117.134.83]

TASK [etcd : set etcd host and ip facts] ***************************************
Wednesday 21 November 2018  03:59:23 +0000 (0:00:00.229)       0:10:48.779 **** 
ok: [40.117.134.83]

TASK [etcd : Check that etcd image is present] *********************************
Wednesday 21 November 2018  03:59:23 +0000 (0:00:00.054)       0:10:48.833 **** 
changed: [40.117.134.83]

TASK [etcd : Pre-pull etcd image] **********************************************
Wednesday 21 November 2018  03:59:23 +0000 (0:00:00.264)       0:10:49.098 **** 
changed: [40.117.134.83]

TASK [etcd : Configure etcd profile.d aliases] *********************************
Wednesday 21 November 2018  03:59:25 +0000 (0:00:01.395)       0:10:50.494 **** 
changed: [40.117.134.83]

TASK [etcd : Add iptables allow rules] *****************************************
Wednesday 21 November 2018  03:59:25 +0000 (0:00:00.845)       0:10:51.340 **** 
changed: [40.117.134.83] => (item={u'port': u'2379/tcp', u'service': u'etcd'})
changed: [40.117.134.83] => (item={u'port': u'2380/tcp', u'service': u'etcd peering'})

TASK [etcd : Remove iptables rules] ********************************************
Wednesday 21 November 2018  03:59:28 +0000 (0:00:02.756)       0:10:54.097 **** 

TASK [etcd : Add firewalld allow rules] ****************************************
Wednesday 21 November 2018  03:59:28 +0000 (0:00:00.025)       0:10:54.122 **** 
skipping: [40.117.134.83] => (item={u'port': u'2379/tcp', u'service': u'etcd'}) 
skipping: [40.117.134.83] => (item={u'port': u'2380/tcp', u'service': u'etcd peering'}) 

TASK [etcd : Remove firewalld allow rules] *************************************
Wednesday 21 November 2018  03:59:28 +0000 (0:00:00.055)       0:10:54.177 **** 

TASK [etcd : Ensure etcd datadir exists] ***************************************
Wednesday 21 November 2018  03:59:28 +0000 (0:00:00.024)       0:10:54.202 **** 
changed: [40.117.134.83]

TASK [etcd : Validate permissions on the config dir] ***************************
Wednesday 21 November 2018  03:59:32 +0000 (0:00:03.595)       0:10:57.798 **** 
changed: [40.117.134.83]

TASK [etcd : Validate permissions on the static pods dir] **********************
Wednesday 21 November 2018  03:59:32 +0000 (0:00:00.310)       0:10:58.109 **** 
changed: [40.117.134.83]

TASK [etcd : Write etcd global config file] ************************************
Wednesday 21 November 2018  03:59:33 +0000 (0:00:00.667)       0:10:58.776 **** 
changed: [40.117.134.83]

TASK [etcd : Create temp directory for static pods] ****************************
Wednesday 21 November 2018  03:59:34 +0000 (0:00:00.635)       0:10:59.412 **** 
ok: [40.117.134.83]

TASK [etcd : Prepare etcd static pod] ******************************************
Wednesday 21 November 2018  03:59:34 +0000 (0:00:00.233)       0:10:59.646 **** 
changed: [40.117.134.83] => (item=etcd.yaml)

TASK [etcd : Update etcd static pod] *******************************************
Wednesday 21 November 2018  03:59:35 +0000 (0:00:00.927)       0:11:00.573 **** 
changed: [40.117.134.83] => (item=etcd.yaml)

TASK [etcd : Set etcd host as a probe target host] *****************************
Wednesday 21 November 2018  03:59:35 +0000 (0:00:00.480)       0:11:01.053 **** 
changed: [40.117.134.83] => (item=etcd.yaml)

TASK [etcd : Deploy etcd static pod] *******************************************
Wednesday 21 November 2018  03:59:35 +0000 (0:00:00.300)       0:11:01.354 **** 
changed: [40.117.134.83] => (item=etcd.yaml)

TASK [etcd : Remove temp directory] ********************************************
Wednesday 21 November 2018  03:59:36 +0000 (0:00:00.247)       0:11:01.601 **** 
ok: [40.117.134.83]

TASK [openshift_control_plane : Copy static master scripts] ********************
Wednesday 21 November 2018  03:59:36 +0000 (0:00:00.233)       0:11:01.834 **** 
changed: [40.117.134.83] => (item=scripts/docker/master-exec)
changed: [40.117.134.83] => (item=scripts/docker/master-logs)
changed: [40.117.134.83] => (item=scripts/docker/master-restart)

TASK [openshift_control_plane : Ensure cri-tools installed] ********************
Wednesday 21 November 2018  03:59:38 +0000 (0:00:01.712)       0:11:03.547 **** 
skipping: [40.117.134.83]

TASK [openshift_control_plane : Enable bootstrapping in the master config] *****
Wednesday 21 November 2018  03:59:38 +0000 (0:00:00.029)       0:11:03.576 **** 
skipping: [40.117.134.83]

TASK [openshift_control_plane : Create temp directory for static pods] *********
Wednesday 21 November 2018  03:59:38 +0000 (0:00:00.028)       0:11:03.605 **** 
ok: [40.117.134.83]

TASK [openshift_control_plane : Prepare master static pods] ********************
Wednesday 21 November 2018  03:59:38 +0000 (0:00:00.233)       0:11:03.839 **** 
changed: [40.117.134.83] => (item=apiserver.yaml)
changed: [40.117.134.83] => (item=controller.yaml)

TASK [openshift_control_plane : Update master static pods] *********************
Wednesday 21 November 2018  03:59:43 +0000 (0:00:04.863)       0:11:08.702 **** 
changed: [40.117.134.83] => (item=apiserver.yaml)
changed: [40.117.134.83] => (item=controller.yaml)

TASK [openshift_control_plane : Update master static pod (api)] ****************
Wednesday 21 November 2018  03:59:43 +0000 (0:00:00.578)       0:11:09.280 **** 
ok: [40.117.134.83]

TASK [openshift_control_plane : ensure kubelet plugins dir exists] *************
Wednesday 21 November 2018  03:59:44 +0000 (0:00:00.297)       0:11:09.578 **** 
skipping: [40.117.134.83]

TASK [openshift_control_plane : Update controller-manager static pod on atomic host] ***
Wednesday 21 November 2018  03:59:44 +0000 (0:00:00.029)       0:11:09.607 **** 
skipping: [40.117.134.83]

TASK [openshift_control_plane : ensure pod location exists] ********************
Wednesday 21 November 2018  03:59:44 +0000 (0:00:00.028)       0:11:09.636 **** 
changed: [40.117.134.83]

TASK [openshift_control_plane : Update master static pods] *********************
Wednesday 21 November 2018  03:59:44 +0000 (0:00:00.235)       0:11:09.871 **** 
changed: [40.117.134.83] => (item=apiserver.yaml)
changed: [40.117.134.83] => (item=controller.yaml)

TASK [openshift_control_plane : Remove old files in /etc/sysconfig] ************
Wednesday 21 November 2018  03:59:44 +0000 (0:00:00.447)       0:11:10.319 **** 
ok: [40.117.134.83] => (item=/etc/sysconfig/origin-master-api)
ok: [40.117.134.83] => (item=/etc/sysconfig/origin-master-controllers)

TASK [openshift_control_plane : Remove temporary directory] ********************
Wednesday 21 November 2018  03:59:45 +0000 (0:00:00.459)       0:11:10.778 **** 
ok: [40.117.134.83]

PLAY [Re-enable excluder if it was previously enabled] *************************

TASK [openshift_excluder : Detecting Atomic Host Operating System] *************
Wednesday 21 November 2018  03:59:45 +0000 (0:00:00.257)       0:11:11.036 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Debug r_openshift_excluder_enable_docker_excluder] ***
Wednesday 21 November 2018  03:59:45 +0000 (0:00:00.221)       0:11:11.257 **** 
ok: [40.117.134.83] => {
    "r_openshift_excluder_enable_docker_excluder": true
}

TASK [openshift_excluder : Debug r_openshift_excluder_enable_openshift_excluder] ***
Wednesday 21 November 2018  03:59:45 +0000 (0:00:00.054)       0:11:11.312 **** 
ok: [40.117.134.83] => {
    "r_openshift_excluder_enable_openshift_excluder": true
}

TASK [openshift_excluder : Fail if invalid openshift_excluder_action provided] ***
Wednesday 21 November 2018  03:59:45 +0000 (0:00:00.055)       0:11:11.368 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Fail if r_openshift_excluder_upgrade_target is not defined] ***
Wednesday 21 November 2018  03:59:45 +0000 (0:00:00.029)       0:11:11.398 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Include main action task file] **********************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.028)       0:11:11.426 **** 
included: /usr/share/ansible/openshift-ansible/roles/openshift_excluder/tasks/enable.yml for 40.117.134.83

TASK [openshift_excluder : Install docker excluder - yum] **********************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.062)       0:11:11.489 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install docker excluder - dnf] **********************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.031)       0:11:11.521 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install openshift excluder - yum] *******************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.032)       0:11:11.554 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Install openshift excluder - dnf] *******************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.028)       0:11:11.582 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : set_fact] *******************************************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.029)       0:11:11.612 **** 
skipping: [40.117.134.83]

TASK [openshift_excluder : Check for docker-excluder] **************************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.029)       0:11:11.641 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Enable docker excluder] *****************************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.229)       0:11:11.871 **** 
changed: [40.117.134.83]

TASK [openshift_excluder : Check for openshift excluder] ***********************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.298)       0:11:12.170 **** 
ok: [40.117.134.83]

TASK [openshift_excluder : Enable openshift excluder] **************************
Wednesday 21 November 2018  03:59:46 +0000 (0:00:00.223)       0:11:12.394 **** 
skipping: [40.117.134.83]

PLAY [Configure nodes] *********************************************************

TASK [Remove any ansible facts created during AMI creation] ********************
Wednesday 21 November 2018  03:59:47 +0000 (0:00:00.040)       0:11:12.434 **** 
changed: [40.117.134.83] => (item=openshift.fact)

PLAY [nodes] *******************************************************************

TASK [remove yum client certificate] *******************************************
Wednesday 21 November 2018  03:59:47 +0000 (0:00:00.248)       0:11:12.682 **** 
skipping: [40.117.134.83] => (item={u'name': u'client-cert.pem'}) 
skipping: [40.117.134.83] => (item={u'name': u'client-key.pem'}) 

TASK [remove yum repositories] *************************************************
Wednesday 21 November 2018  03:59:47 +0000 (0:00:00.034)       0:11:12.716 **** 
changed: [40.117.134.83] => (item={u'gpgkey': u'file:///etc/pki/rpm-gpg/RPM-GPG-KEY-CentOS-SIG-PaaS', u'enabled': True, u'name': u'openshift-origin310', u'baseurl': u'http://mirror.centos.org/centos/7/paas/x86_64/openshift-origin310/'})
changed: [40.117.134.83] => (item={'gpgcheck': False, 'enabled': True, 'name': u'install_repo', 'baseurl': u'https://storage.googleapis.com/origin-ci-test/logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/rpms'})

TASK [add get-node-logs script] ************************************************
Wednesday 21 November 2018  03:59:47 +0000 (0:00:00.434)       0:11:13.151 **** 
changed: [40.117.134.83]

TASK [record installed rpms] ***************************************************
Wednesday 21 November 2018  03:59:48 +0000 (0:00:00.597)       0:11:13.749 **** 
ok: [40.117.134.83]

TASK [disable waagent data disk management] ************************************
Wednesday 21 November 2018  03:59:49 +0000 (0:00:01.332)       0:11:15.081 **** 
changed: [40.117.134.83] => (item={u'regexp': u'^ResourceDisk\\.Format=', u'line': u'ResourceDisk.Format=n'})

TASK [persist oreg_url] ********************************************************
Wednesday 21 November 2018  03:59:49 +0000 (0:00:00.238)       0:11:15.319 **** 
changed: [40.117.134.83]

TASK [create a file with image name] *******************************************
Wednesday 21 November 2018  03:59:50 +0000 (0:00:00.616)       0:11:15.936 **** 
changed: [40.117.134.83]

TASK [run waagent deprovision] *************************************************
Wednesday 21 November 2018  03:59:51 +0000 (0:00:00.629)       0:11:16.565 **** 
changed: [40.117.134.83]

PLAY [localhost] ***************************************************************

TASK [set_fact] ****************************************************************
Wednesday 21 November 2018  03:59:52 +0000 (0:00:01.383)       0:11:17.949 **** 
ok: [localhost]

TASK [deallocate vm] ***********************************************************
Wednesday 21 November 2018  03:59:52 +0000 (0:00:00.059)       0:11:18.009 **** 
changed: [localhost]

TASK [generalize vm] ***********************************************************
Wednesday 21 November 2018  04:00:41 +0000 (0:00:49.174)       0:12:07.183 **** 
changed: [localhost]

TASK [get vm details] **********************************************************
Wednesday 21 November 2018  04:00:43 +0000 (0:00:01.672)       0:12:08.856 **** 
changed: [localhost]

TASK [create image resource group] *********************************************
Wednesday 21 November 2018  04:00:45 +0000 (0:00:01.682)       0:12:10.538 **** 
ok: [localhost]

TASK [create image] ************************************************************
Wednesday 21 November 2018  04:00:45 +0000 (0:00:00.751)       0:12:11.289 **** 
changed: [localhost]

TASK [get input image tags] ****************************************************
Wednesday 21 November 2018  04:01:20 +0000 (0:00:34.297)       0:12:45.587 **** 
changed: [localhost]

TASK [remove valid tag from input image tags] **********************************
Wednesday 21 November 2018  04:01:21 +0000 (0:00:01.694)       0:12:47.282 **** 
ok: [localhost] => (item={'value': u'3.10.0-693.el7.x86_64', 'key': u'kernel'})
skipping: [localhost] => (item={'value': u'true', 'key': u'valid'}) 
ok: [localhost] => (item={'value': u'centos7-root-201804191712', 'key': u'root_image'})

TASK [calculate final tags] ****************************************************
Wednesday 21 November 2018  04:01:21 +0000 (0:00:00.059)       0:12:47.341 **** 
ok: [localhost]

TASK [tag image] ***************************************************************
Wednesday 21 November 2018  04:01:21 +0000 (0:00:00.061)       0:12:47.402 **** 
changed: [localhost]

TASK [log image resource group and name] ***************************************
Wednesday 21 November 2018  04:01:55 +0000 (0:00:33.819)       0:13:21.222 **** 
ok: [localhost] => (item=Resource group: ci-test_branch_origin_extended_c_azure_310-60) => {
    "changed": false, 
    "item": "Resource group: ci-test_branch_origin_extended_c_azure_310-60", 
    "msg": "Resource group: ci-test_branch_origin_extended_c_azure_310-60"
}
ok: [localhost] => (item=Image name: centos7-origin) => {
    "changed": false, 
    "item": "Image name: centos7-origin", 
    "msg": "Image name: centos7-origin"
}

TASK [log image tag information] ***********************************************
Wednesday 21 November 2018  04:01:55 +0000 (0:00:00.042)       0:13:21.265 **** 
ok: [localhost] => (item={'value': u'3.10.0-862.14.4.el7.x86_64', 'key': u'kernel'}) => {
    "changed": false, 
    "item": {
        "key": "kernel", 
        "value": "3.10.0-862.14.4.el7.x86_64"
    }, 
    "msg": "kernel: 3.10.0-862.14.4.el7.x86_64"
}
ok: [localhost] => (item={'value': u'centos7-base-201811202209', 'key': u'base_image'}) => {
    "changed": false, 
    "item": {
        "key": "base_image", 
        "value": "centos7-base-201811202209"
    }, 
    "msg": "base_image: centos7-base-201811202209"
}
ok: [localhost] => (item={'value': u'centos7-root-201804191712', 'key': u'root_image'}) => {
    "changed": false, 
    "item": {
        "key": "root_image", 
        "value": "centos7-root-201804191712"
    }, 
    "msg": "root_image: centos7-root-201804191712"
}
ok: [localhost] => (item={'value': u'origin-3.10.0-1.83.9e57eff.x86_64', 'key': u'openshift'}) => {
    "changed": false, 
    "item": {
        "key": "openshift", 
        "value": "origin-3.10.0-1.83.9e57eff.x86_64"
    }, 
    "msg": "openshift: origin-3.10.0-1.83.9e57eff.x86_64"
}

TASK [get vm details] **********************************************************
Wednesday 21 November 2018  04:01:55 +0000 (0:00:00.055)       0:13:21.321 **** 
skipping: [localhost]

TASK [get storage account key] *************************************************
Wednesday 21 November 2018  04:01:55 +0000 (0:00:00.023)       0:13:21.345 **** 
skipping: [localhost]

TASK [get disk sas url] ********************************************************
Wednesday 21 November 2018  04:01:55 +0000 (0:00:00.024)       0:13:21.370 **** 
skipping: [localhost]

TASK [start copy] **************************************************************
Wednesday 21 November 2018  04:01:55 +0000 (0:00:00.024)       0:13:21.394 **** 
skipping: [localhost]

TASK [get copy status] *********************************************************
Wednesday 21 November 2018  04:01:56 +0000 (0:00:00.024)       0:13:21.419 **** 
skipping: [localhost]

TASK [revoke disk sas url] *****************************************************
Wednesday 21 November 2018  04:01:56 +0000 (0:00:00.023)       0:13:21.442 **** 
skipping: [localhost]

PLAY RECAP *********************************************************************
40.117.134.83              : ok=189  changed=97   unreachable=0    failed=0   
localhost                  : ok=21   changed=12   unreachable=0    failed=0   
{}                         : ok=2    changed=0    unreachable=0    failed=0   


INSTALLER STATUS ***************************************************************
Initialization  : Complete (0:00:32)
Wednesday 21 November 2018  04:01:56 +0000 (0:00:00.022)       0:13:21.464 **** 
=============================================================================== 
create vm ------------------------------------------------------------- 160.05s
openshift_node : Install node, clients, and conntrack packages -------- 141.70s
container_runtime : Install Docker ------------------------------------- 59.33s
openshift_node : Install GlusterFS storage plugin dependencies --------- 50.33s
deallocate vm ---------------------------------------------------------- 49.17s
openshift_node : install needed rpm(s) --------------------------------- 36.50s
create image ----------------------------------------------------------- 34.30s
tag image -------------------------------------------------------------- 33.82s
Ensure openshift-ansible installer package deps are installed ---------- 22.94s
install centos-release-paas-common rpm --------------------------------- 15.36s
create vnet ------------------------------------------------------------ 15.24s
openshift_node : Install Ceph storage plugin dependencies -------------- 13.16s
os_firewall : need to pause here, otherwise the iptables service starting can sometimes cause ssh to fail -- 10.07s
os_firewall : Wait 10 seconds after disabling firewalld ---------------- 10.06s
openshift_node : Install iSCSI storage plugin dependencies -------------- 7.76s
os_update_latest : Update all packages ---------------------------------- 6.80s
openshift_node : Install dnsmasq ---------------------------------------- 6.00s
os_firewall : Install iptables packages --------------------------------- 5.92s
create subnet ----------------------------------------------------------- 5.24s
openshift_control_plane : Prepare master static pods -------------------- 4.86s
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: BUILD NODE IMAGE [00h 13m 26s] ##########
[workspace] $ /bin/bash /tmp/jenkins2485253443958645937.sh
########## STARTING STAGE: PROVISION TEST CLUSTER ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ mktemp
+ script=/tmp/tmp.zam6U7AcA4
+ cat
+ chmod +x /tmp/tmp.zam6U7AcA4
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.zam6U7AcA4 openshiftdevel:/tmp/tmp.zam6U7AcA4
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 14400 /tmp/tmp.zam6U7AcA4"'
+ cd /data/src/github.com/openshift/release
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: PROVISION TEST CLUSTER [00h 00m 01s] ##########
[workspace] $ /bin/bash /tmp/jenkins119432556921622990.sh
########## STARTING STAGE: RUN THE ACS-ENGINE E2E TEST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ mktemp
+ script=/tmp/tmp.6iDaH32Evm
+ cat
+ chmod +x /tmp/tmp.6iDaH32Evm
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.6iDaH32Evm openshiftdevel:/tmp/tmp.6iDaH32Evm
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 14400 /tmp/tmp.6iDaH32Evm"'
+ cd /home/origin
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RUN THE ACS-ENGINE E2E TEST [00h 00m 00s] ##########
[workspace] $ /bin/bash /tmp/jenkins2994700835673248778.sh
########## STARTING STAGE: RUN TESTS ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ mktemp
+ script=/tmp/tmp.VkrBaelIIi
+ cat
+ chmod +x /tmp/tmp.VkrBaelIIi
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.VkrBaelIIi openshiftdevel:/tmp/tmp.VkrBaelIIi
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 900 /tmp/tmp.VkrBaelIIi"'
+ cd /home/origin
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: RUN TESTS [00h 00m 01s] ##########
[PostBuildScript] - Executing post build scripts.
[workspace] $ /bin/bash /tmp/jenkins8951989913549524204.sh
########## STARTING STAGE: DOWNLOAD ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/gathered
+ rm -rf /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/gathered
+ mkdir -p /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/gathered
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo stat /data/src/github.com/openshift/origin/_output/scripts
  File: ‘/data/src/github.com/openshift/origin/_output/scripts’
  Size: 64        	Blocks: 0          IO Block: 4096   directory
Device: ca02h/51714d	Inode: 96937044    Links: 5
Access: (2755/drwxr-sr-x)  Uid: ( 1001/  origin)   Gid: ( 1003/origin-git)
Context: unconfined_u:object_r:container_file_t:s0
Access: 2018-11-21 03:16:33.518536972 +0000
Modify: 2018-11-21 03:44:10.334963116 +0000
Change: 2018-11-21 03:44:10.334963116 +0000
 Birth: -
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo chmod -R o+rX /data/src/github.com/openshift/origin/_output/scripts
+ scp -r -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel:/data/src/github.com/openshift/origin/_output/scripts /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/gathered
+ tree /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/gathered
/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/gathered
└── scripts
    ├── build-base-images
    │   ├── artifacts
    │   ├── logs
    │   └── openshift.local.home
    ├── push-release
    │   ├── artifacts
    │   ├── logs
    │   │   └── scripts.log
    │   └── openshift.local.home
    └── shell
        ├── artifacts
        ├── logs
        │   ├── 0b2f99a4624c264f3bdf26b3cb39fd717cca6bf3ee58450cb890605f23de197d.json
        │   └── scripts.log
        └── openshift.local.home

13 directories, 3 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins7017985061629998204.sh
########## STARTING STAGE: GENERATE ARTIFACTS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/generated
+ rm -rf /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/generated
+ mkdir /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/generated
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo docker version && sudo docker info && sudo docker images && sudo docker ps -a 2>&1'
  WARNING: You're not using the default seccomp profile
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo cat /etc/sysconfig/docker /etc/sysconfig/docker-network /etc/sysconfig/docker-storage /etc/sysconfig/docker-storage-setup /etc/systemd/system/docker.service 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo find /var/lib/docker/containers -name *.log | sudo xargs tail -vn +1 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --server=https://$( uname --nodename ):10250 --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo ausearch -m AVC -m SELINUX_ERR -m USER_AVC 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'oc get --raw /metrics --config=/etc/origin/master/admin.kubeconfig 2>&1'
+ true
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo df -T -h && sudo pvs && sudo vgs && sudo lvs && sudo findmnt --all 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo yum list installed 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl --dmesg --no-pager --all --lines=all 2>&1'
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel 'sudo journalctl _PID=1 --no-pager --all --lines=all 2>&1'
+ tree /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/generated
/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/generated
├── avc_denials.log
├── containers.log
├── dmesg.log
├── docker.config
├── docker.info
├── filesystem.info
├── installed_packages.log
├── master-metrics.log
├── node-metrics.log
└── pid1.journal

0 directories, 10 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins7584530381890177651.sh
########## STARTING STAGE: FETCH SYSTEMD JOURNALS FROM THE REMOTE HOST ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ trap 'exit 0' EXIT
++ pwd
+ ARTIFACT_DIR=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/journals
+ rm -rf /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/journals
+ mkdir /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/journals
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit docker.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit dnsmasq.service --no-pager --all --lines=all
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config openshiftdevel sudo journalctl --unit systemd-journald.service --no-pager --all --lines=all
+ tree /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/journals
/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/artifacts/journals
├── dnsmasq.service
├── docker.service
└── systemd-journald.service

0 directories, 3 files
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins1962348740321114857.sh
########## STARTING STAGE: ASSEMBLE GCS OUTPUT ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ trap 'exit 0' EXIT
+ mkdir -p gcs/artifacts gcs/artifacts/generated gcs/artifacts/journals gcs/artifacts/gathered
++ python -c 'import json; import urllib; print json.load(urllib.urlopen('\''https://ci.openshift.redhat.com/jenkins/job/test_branch_origin_extended_conformance_azure_310/60/api/json'\''))['\''result'\'']'
+ result=SUCCESS
+ cat
++ date +%s
+ cat /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/builds/60/log
+ cp artifacts/generated/avc_denials.log artifacts/generated/containers.log artifacts/generated/dmesg.log artifacts/generated/docker.config artifacts/generated/docker.info artifacts/generated/filesystem.info artifacts/generated/installed_packages.log artifacts/generated/master-metrics.log artifacts/generated/node-metrics.log artifacts/generated/pid1.journal gcs/artifacts/generated/
+ cp artifacts/journals/dnsmasq.service artifacts/journals/docker.service artifacts/journals/systemd-journald.service gcs/artifacts/journals/
+ cp -r artifacts/gathered/scripts gcs/artifacts/
++ pwd
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config -r /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/gcs openshiftdevel:/data
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config /var/lib/jenkins/.config/gcloud/gcs-publisher-credentials.json openshiftdevel:/data/credentials.json
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins3716370866504774058.sh
########## STARTING STAGE: PUSH THE ARTIFACTS AND METADATA ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ mktemp
+ script=/tmp/tmp.ORekZXFgrz
+ cat
+ chmod +x /tmp/tmp.ORekZXFgrz
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.ORekZXFgrz openshiftdevel:/tmp/tmp.ORekZXFgrz
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 300 /tmp/tmp.ORekZXFgrz"'
+ cd /home/origin
+ trap 'exit 0' EXIT
+ [[ -n {"type":"postsubmit","job":"test_branch_origin_extended_conformance_azure_310","buildid":"1065080716796104705","prowjobid":"6090d8f8-ed3b-11e8-bbe0-0a58ac100efb","refs":{"org":"openshift","repo":"origin","base_ref":"release-3.10","base_sha":"9e57eff5132a9dd7a2d9d0c607c90b1d604be72f"}} ]]
++ jq --compact-output '.buildid |= "60"'
+ JOB_SPEC='{"type":"postsubmit","job":"test_branch_origin_extended_conformance_azure_310","buildid":"60","prowjobid":"6090d8f8-ed3b-11e8-bbe0-0a58ac100efb","refs":{"org":"openshift","repo":"origin","base_ref":"release-3.10","base_sha":"9e57eff5132a9dd7a2d9d0c607c90b1d604be72f"}}'
+ docker run -e 'JOB_SPEC={"type":"postsubmit","job":"test_branch_origin_extended_conformance_azure_310","buildid":"60","prowjobid":"6090d8f8-ed3b-11e8-bbe0-0a58ac100efb","refs":{"org":"openshift","repo":"origin","base_ref":"release-3.10","base_sha":"9e57eff5132a9dd7a2d9d0c607c90b1d604be72f"}}' -v /data:/data:z registry.svc.ci.openshift.org/ci/gcsupload:latest --dry-run=false --gcs-path=gs://origin-ci-test --gcs-credentials-file=/data/credentials.json --path-strategy=single --default-org=openshift --default-repo=origin /data/gcs/artifacts /data/gcs/build-log.txt /data/gcs/finished.json
Unable to find image 'registry.svc.ci.openshift.org/ci/gcsupload:latest' locally
Trying to pull repository registry.svc.ci.openshift.org/ci/gcsupload ... 
latest: Pulling from registry.svc.ci.openshift.org/ci/gcsupload
a073c86ecf9e: Already exists
cc3fc741b1a9: Already exists
c2227a877946: Pulling fs layer
c2227a877946: Verifying Checksum
c2227a877946: Download complete
c2227a877946: Pull complete
Digest: sha256:4c8023e31711340435b676f7f4486b101825100285e8b6d7c29677ba00233e10
Status: Downloaded newer image for registry.svc.ci.openshift.org/ci/gcsupload:latest
{"component":"gcsupload","level":"info","msg":"Gathering artifacts from artifact directory: /data/gcs/artifacts","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/avc_denials.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/avc_denials.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/containers.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/containers.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/dmesg.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/dmesg.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/docker.config in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/docker.config\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/docker.info in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/docker.info\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/filesystem.info in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/filesystem.info\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/installed_packages.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/installed_packages.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/master-metrics.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/master-metrics.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/node-metrics.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/node-metrics.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/generated/pid1.journal in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/pid1.journal\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/dnsmasq.service in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/dnsmasq.service\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/docker.service in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/docker.service\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/journals/systemd-journald.service in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/systemd-journald.service\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/push-release/logs/scripts.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/push-release/logs/scripts.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/shell/logs/0b2f99a4624c264f3bdf26b3cb39fd717cca6bf3ee58450cb890605f23de197d.json in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/shell/logs/0b2f99a4624c264f3bdf26b3cb39fd717cca6bf3ee58450cb890605f23de197d.json\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","level":"info","msg":"Found /data/gcs/artifacts/scripts/shell/logs/scripts.log in artifact directory. Uploading as logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/shell/logs/scripts.log\n","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/push-release/logs/scripts.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/shell/logs/0b2f99a4624c264f3bdf26b3cb39fd717cca6bf3ee58450cb890605f23de197d.json","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/build-log.txt","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/latest-build.txt","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/avc_denials.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/docker.info","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/installed_packages.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/systemd-journald.service","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/finished.json","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/dmesg.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/dnsmasq.service","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/shell/logs/scripts.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/containers.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/filesystem.info","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/master-metrics.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/node-metrics.log","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/pid1.journal","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/docker.config","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/docker.service","level":"info","msg":"Queued for upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/shell/logs/scripts.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/avc_denials.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/dnsmasq.service","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/master-metrics.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/shell/logs/0b2f99a4624c264f3bdf26b3cb39fd717cca6bf3ee58450cb890605f23de197d.json","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/scripts/push-release/logs/scripts.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:29Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/dmesg.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/pid1.journal","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/systemd-journald.service","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/finished.json","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/docker.info","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/docker.config","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/build-log.txt","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/node-metrics.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/containers.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/journals/docker.service","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/installed_packages.log","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/60/artifacts/generated/filesystem.info","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","dest":"logs/test_branch_origin_extended_conformance_azure_310/latest-build.txt","level":"info","msg":"Finished upload","time":"2018-11-21T04:02:30Z"}
{"component":"gcsupload","level":"info","msg":"Finished upload to GCS","time":"2018-11-21T04:02:30Z"}
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: PUSH THE ARTIFACTS AND METADATA [00h 00m 06s] ##########
[workspace] $ /bin/bash /tmp/jenkins550643222553054862.sh
########## STARTING STAGE: HOLD FAILED TEST CLUSTER FOR DEBUG ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: HOLD FAILED TEST CLUSTER FOR DEBUG [00h 00m 00s] ##########
[workspace] $ /bin/bash /tmp/jenkins7700790891631493053.sh
########## STARTING STAGE: DEPROVISION TEST CLUSTER ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ mktemp
+ script=/tmp/tmp.OYIjp5dxfc
+ cat
+ chmod +x /tmp/tmp.OYIjp5dxfc
+ scp -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config /tmp/tmp.OYIjp5dxfc openshiftdevel:/tmp/tmp.OYIjp5dxfc
+ ssh -F /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/.ssh_config -t openshiftdevel 'bash -l -c "timeout 14400 /tmp/tmp.OYIjp5dxfc"'
+ cd /data/src/github.com/openshift/release
+ trap 'exit 0' EXIT
+ cd cluster/test-deploy/azure
+ TYPE=azure
+ ../../bin/ansible.sh ansible-playbook -e openshift_azure_resource_group_name=ci-test_branch_origin_extended_c_azure_310-60 playbooks/azure/openshift-cluster/deprovision.yml

PLAY [localhost] ***************************************************************

TASK [delete resource group] ***************************************************
Wednesday 21 November 2018  04:02:33 +0000 (0:00:00.066)       0:00:00.066 **** 
changed: [localhost]

PLAY RECAP *********************************************************************
localhost                  : ok=1    changed=1    unreachable=0    failed=0   

Wednesday 21 November 2018  04:06:06 +0000 (0:03:33.379)       0:03:33.446 **** 
=============================================================================== 
delete resource group ------------------------------------------------- 213.38s
+ exit 0
+ set +o xtrace
########## FINISHED STAGE: SUCCESS: DEPROVISION TEST CLUSTER [00h 03m 36s] ##########
[workspace] $ /bin/bash /tmp/jenkins3683433459269994351.sh
########## STARTING STAGE: DELETE PR IMAGES ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ trap 'exit 0' EXIT
+ source ./INSTANCE_PREFIX
++ INSTANCE_PREFIX=prtest-81a7b0e-60
++ OS_TAG=9e57eff
++ OS_PUSH_BASE_REPO=ci-pr-images/prtest-81a7b0e-60-
+ export KUBECONFIG=/var/lib/jenkins/secrets/image-pr-push.kubeconfig
+ KUBECONFIG=/var/lib/jenkins/secrets/image-pr-push.kubeconfig
+ oc get is -o name -n ci-pr-images
+ grep prtest-81a7b0e-60
+ xargs -r oc delete
imagestream "prtest-81a7b0e-60-node" deleted
imagestream "prtest-81a7b0e-60-origin" deleted
imagestream "prtest-81a7b0e-60-origin-base" deleted
imagestream "prtest-81a7b0e-60-origin-cli" deleted
imagestream "prtest-81a7b0e-60-origin-control-plane" deleted
imagestream "prtest-81a7b0e-60-origin-deployer" deleted
imagestream "prtest-81a7b0e-60-origin-docker-builder" deleted
imagestream "prtest-81a7b0e-60-origin-egress-dns-proxy" deleted
imagestream "prtest-81a7b0e-60-origin-egress-http-proxy" deleted
imagestream "prtest-81a7b0e-60-origin-egress-router" deleted
imagestream "prtest-81a7b0e-60-origin-f5-router" deleted
imagestream "prtest-81a7b0e-60-origin-haproxy-router" deleted
imagestream "prtest-81a7b0e-60-origin-hyperkube" deleted
imagestream "prtest-81a7b0e-60-origin-hypershift" deleted
imagestream "prtest-81a7b0e-60-origin-keepalived-ipfailover" deleted
imagestream "prtest-81a7b0e-60-origin-node" deleted
imagestream "prtest-81a7b0e-60-origin-pod" deleted
imagestream "prtest-81a7b0e-60-origin-recycler" deleted
imagestream "prtest-81a7b0e-60-origin-template-service-broker" deleted
imagestream "prtest-81a7b0e-60-origin-tests" deleted
+ exit 0
[workspace] $ /bin/bash /tmp/jenkins1371369255848741981.sh
########## STARTING STAGE: DEPROVISION CLOUD RESOURCES ##########
+ [[ -s /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate ]]
+ source /var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/activate
++ export VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ VIRTUAL_ENV=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed
++ export PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ PATH=/var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/bin:/sbin:/usr/sbin:/bin:/usr/bin
++ unset PYTHON_HOME
++ export OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
++ OCT_CONFIG_HOME=/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config
+ oct deprovision

PLAYBOOK: main.yml *************************************************************
4 plays in /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml

PLAY [ensure we have the parameters necessary to deprovision virtual hosts] ****

TASK [ensure all required variables are set] ***********************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:9
skipping: [localhost] => (item=origin_ci_inventory_dir)  => {
    "changed": false, 
    "generated_timestamp": "2018-11-20 23:06:10.816745", 
    "item": "origin_ci_inventory_dir", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}
skipping: [localhost] => (item=origin_ci_aws_region)  => {
    "changed": false, 
    "generated_timestamp": "2018-11-20 23:06:10.820092", 
    "item": "origin_ci_aws_region", 
    "skip_reason": "Conditional check failed", 
    "skipped": true
}

PLAY [deprovision virtual hosts in EC2] ****************************************

TASK [Gathering Facts] *********************************************************
ok: [localhost]

TASK [deprovision a virtual EC2 host] ******************************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:28
included: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml for localhost

TASK [update the SSH configuration to remove AWS EC2 specifics] ****************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:2
ok: [localhost] => {
    "changed": false, 
    "generated_timestamp": "2018-11-20 23:06:11.592078", 
    "msg": ""
}

TASK [rename EC2 instance for termination reaper] ******************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:8
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-11-20 23:06:12.325289", 
    "msg": "Tags {'Name': 'oct-terminate'} created for resource i-070ab3a1518e91178."
}

TASK [tear down the EC2 instance] **********************************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:15
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-11-20 23:06:13.317836", 
    "instance_ids": [
        "i-070ab3a1518e91178"
    ], 
    "instances": [
        {
            "ami_launch_index": "0", 
            "architecture": "x86_64", 
            "block_device_mapping": {
                "/dev/sda1": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-0af39681a7f4e7190"
                }, 
                "/dev/sdb": {
                    "delete_on_termination": true, 
                    "status": "attached", 
                    "volume_id": "vol-073d08fc2443027bc"
                }
            }, 
            "dns_name": "ec2-52-200-103-139.compute-1.amazonaws.com", 
            "ebs_optimized": false, 
            "groups": {
                "sg-7e73221a": "default"
            }, 
            "hypervisor": "xen", 
            "id": "i-070ab3a1518e91178", 
            "image_id": "ami-0b77b87a37c3e662c", 
            "instance_type": "m4.xlarge", 
            "kernel": null, 
            "key_name": "libra", 
            "launch_time": "2018-11-21T03:13:42.000Z", 
            "placement": "us-east-1d", 
            "private_dns_name": "ip-172-18-9-14.ec2.internal", 
            "private_ip": "172.18.9.14", 
            "public_dns_name": "ec2-52-200-103-139.compute-1.amazonaws.com", 
            "public_ip": "52.200.103.139", 
            "ramdisk": null, 
            "region": "us-east-1", 
            "root_device_name": "/dev/sda1", 
            "root_device_type": "ebs", 
            "state": "running", 
            "state_code": 16, 
            "tags": {
                "Name": "oct-terminate", 
                "openshift_etcd": "", 
                "openshift_master": "", 
                "openshift_node": ""
            }, 
            "tenancy": "default", 
            "virtualization_type": "hvm"
        }
    ], 
    "tagged_instances": []
}

TASK [remove the serialized host variables] ************************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/roles/aws-down/tasks/main.yml:22
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-11-20 23:06:13.558075", 
    "path": "/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory/host_vars/172.18.9.14.yml", 
    "state": "absent"
}

PLAY [deprovision virtual hosts locally manged by Vagrant] *********************

TASK [Gathering Facts] *********************************************************
ok: [localhost]

PLAY [clean up local configuration for deprovisioned instances] ****************

TASK [remove inventory configuration directory] ********************************
task path: /var/lib/jenkins/origin-ci-tool/2b40f3e11aadb569dc9c0c9fb90e7273658ce6ed/lib/python2.7/site-packages/oct/ansible/oct/playbooks/deprovision/main.yml:61
changed: [localhost] => {
    "changed": true, 
    "generated_timestamp": "2018-11-20 23:06:14.007732", 
    "path": "/var/lib/jenkins/jobs/test_branch_origin_extended_conformance_azure_310/workspace/.config/origin-ci-tool/inventory", 
    "state": "absent"
}

PLAY RECAP *********************************************************************
localhost                  : ok=8    changed=4    unreachable=0    failed=0   

+ set +o xtrace
########## FINISHED STAGE: SUCCESS: DEPROVISION CLOUD RESOURCES [00h 00m 05s] ##########
Archiving artifacts
Recording test results
[WS-CLEANUP] Deleting project workspace...[WS-CLEANUP] done
Finished: SUCCESS