Skip to content

Instantly share code, notes, and snippets.

@nmagnezi

nmagnezi/retry.go Secret

Created June 22, 2021 09:37
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save nmagnezi/d2f2040ce3d391a823b1e6b3f6bfc888 to your computer and use it in GitHub Desktop.
Save nmagnezi/d2f2040ce3d391a823b1e6b3f6bfc888 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
retry.go
time="2021-06-22T09:28:39Z" level=debug msg="Request: {HEAD /api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw HTTP/1.1 1 1 map[Accept:[*/*] Accept-Encoding:[gzip, deflate] Forwarded:[for=192.168.111.20;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[python-requests/2.20.0] X-Forwarded-For:[192.168.111.20] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:53252 /api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc00341abb0 <nil> <nil> 0xc003417f80}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:28:39Z" level=debug msg="Authenticating cluster 648ecfeb-1d4a-4d77-9d18-31a5f22388ce JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:28:39Z" level=error msg="failed to get cluster 648ecfeb-1d4a-4d77-9d18-31a5f22388ce" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).DownloadClusterISOHeaders" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:772" cluster_id=648ecfeb-1d4a-4d77-9d18-31a5f22388ce error="record not found" go-id=3649433 pkg=Inventory request_id=8fae4560-acea-4eb8-a884-fa497a1ec2d1
time="2021-06-22T09:28:40Z" level=debug msg="Running ClusterMonitoring" func="github.com/openshift/assisted-service/internal/cluster.(*Manager).ClusterMonitoring" file="/go/src/github.com/openshift/origin/internal/cluster/cluster.go:500" pkg=cluster-state
time="2021-06-22T09:28:40Z" level=debug msg="ClusterMonitoring took : 944.073µs" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=cluster-state
time="2021-06-22T09:28:42Z" level=debug msg="Running HostMonitoring" func="github.com/openshift/assisted-service/internal/host.(*Manager).HostMonitoring" file="/go/src/github.com/openshift/origin/internal/host/monitor.go:50" pkg=host-state
time="2021-06-22T09:28:42Z" level=debug msg="HostMonitoring took : 765.35µs" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=host-state
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:113" cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=e5df8c40-03f7-40b2-aced-999803c00ec1
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=info msg="Creating a new cluster single-node demo-worker2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).createNewCluster" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:779" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=e5df8c40-03f7-40b2-aced-999803c00ec1
time="2021-06-22T09:28:47Z" level=info msg="Register cluster: test-cluster with id 8f57f26c-7721-4180-b322-620d58d37fea" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).RegisterClusterInternal" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:340" cluster_id=8f57f26c-7721-4180-b322-620d58d37fea go-id=706 pkg=Inventory request_id=e5df8c40-03f7-40b2-aced-999803c00ec1
time="2021-06-22T09:28:47Z" level=info msg="HA mode is None, setting UserManagedNetworking to true and VipDhcpAllocation to false" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).RegisterClusterInternal" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:383" cluster_id=8f57f26c-7721-4180-b322-620d58d37fea go-id=706 pkg=Inventory request_id=e5df8c40-03f7-40b2-aced-999803c00ec1
time="2021-06-22T09:28:47Z" level=debug msg="Pushing cluster event single-node demo-worker2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*controllerEventsWrapper).AddEvent" file="/go/src/github.com/openshift/origin/internal/controller/controllers/controller_event_wrapper.go:36"
time="2021-06-22T09:28:47Z" level=info msg="Successfully registered cluster test-cluster with id 8f57f26c-7721-4180-b322-620d58d37fea" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).RegisterClusterInternal.func1" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:347" cluster_id=8f57f26c-7721-4180-b322-620d58d37fea go-id=706 pkg=Inventory request_id=e5df8c40-03f7-40b2-aced-999803c00ec1
time="2021-06-22T09:28:47Z" level=debug msg="Pushing cluster event single-node demo-worker2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*controllerEventsWrapper).AddEvent" file="/go/src/github.com/openshift/origin/internal/controller/controllers/controller_event_wrapper.go:36"
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:110" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=e5df8c40-03f7-40b2-aced-999803c00ec1
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:113" cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=7f019d55-c9c3-4441-9d3e-ca8e1b6629f1
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=error msg="error setting owner reference" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:173" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 error="Operation cannot be fulfilled on agentclusterinstalls.extensions.hive.openshift.io \"test-agent-cluster-install\": the object has been modified; please apply your changes to the latest version and try again" go-id=706 request_id=7f019d55-c9c3-4441-9d3e-ca8e1b6629f1
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:110" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=7f019d55-c9c3-4441-9d3e-ca8e1b6629f1
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:113" cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=2b21908c-906c-44a9-b0b6-25f4981a2d29
time="2021-06-22T09:28:47Z" level=info msg="update cluster 8f57f26c-7721-4180-b322-620d58d37fea with params: &{AdditionalNtpSource:<nil> APIVip:<nil> APIVipDNSName:<nil> BaseDNSDomain:<nil> ClusterNetworkCidr:<nil> ClusterNetworkHostPrefix:<nil> DisksSelectedConfig:[] HostsMachineConfigPoolNames:[] HostsNames:[] HostsRoles:[] HTTPProxy:<nil> HTTPSProxy:<nil> Hyperthreading:<nil> IngressVip:<nil> MachineNetworkCidr:0xc0011a8420 Name:<nil> NoProxy:<nil> OlmOperators:[] PullSecret:<nil> ServiceNetworkCidr:<nil> SSHPublicKey:<nil> UserManagedNetworking:<nil> VipDhcpAllocation:<nil>}" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).updateClusterInternal" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:1710" go-id=706 pkg=Inventory request_id=2b21908c-906c-44a9-b0b6-25f4981a2d29
time="2021-06-22T09:28:47Z" level=debug msg="No DNS configuration for base domain 'hive.example.com'" func="github.com/openshift/assisted-service/internal/dns.(*handler).GetDNSDomain" file="/go/src/github.com/openshift/origin/internal/dns/dns.go:177"
time="2021-06-22T09:28:47Z" level=info msg="Updated clusterDeployment demo-worker2/single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).updateIfNeeded" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:656" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=2b21908c-906c-44a9-b0b6-25f4981a2d29
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:110" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=2b21908c-906c-44a9-b0b6-25f4981a2d29
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:113" cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=f1a9c37e-018d-4ecf-af26-2e0010e85d8c
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:110" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=f1a9c37e-018d-4ecf-af26-2e0010e85d8c
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=debug msg="Map ACI : demo-worker2 test-agent-cluster-install CD ref name single-node" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).SetupWithManager.func2" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:1026" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 go-id=622 request_id=
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:113" cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=62147f50-f0c6-413c-9e81-6faed957d440
time="2021-06-22T09:28:47Z" level=info msg="ClusterDeployment Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:110" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=62147f50-f0c6-413c-9e81-6faed957d440
time="2021-06-22T09:28:49Z" level=info msg="BareMetalHost Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:139" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:49Z" level=debug msg="Started BMH reconcile for demo-worker2/xxxostest-extraworker-2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:503" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:49Z" level=debug msg="BMH value &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5153729 2 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {assisted-service Update metal3.io/v1alpha1 2021-06-22 09:21:46 +0000 UTC FieldsV1 {\"f:spec\":{\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:28:49 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil 0xc001a2fe80 nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:28:49 +0000 UTC unknown 0xc002ff4870 {provisioned b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc002b87a20} 0xc001225b90 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {0001-01-01 00:00:00 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 1}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:504" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:49Z" level=debug msg="BMH label infraenvs.agent-install.openshift.io value myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:472" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:49Z" level=debug msg="Loading InfraEnv myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:479" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:49Z" level=info msg="Stopping reconcileBMH: Either the InfraEnv image is not ready or there is nothing to update." func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:550" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:49Z" level=info msg="BareMetalHost Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:136" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=75281124-e67b-49e7-a3a1-d8d18f5ecc75
time="2021-06-22T09:28:50Z" level=debug msg="Running ClusterMonitoring" func="github.com/openshift/assisted-service/internal/cluster.(*Manager).ClusterMonitoring" file="/go/src/github.com/openshift/origin/internal/cluster/cluster.go:500" pkg=cluster-state
time="2021-06-22T09:28:50Z" level=debug msg="Running HostMonitoring" func="github.com/openshift/assisted-service/internal/host.(*Manager).HostMonitoring" file="/go/src/github.com/openshift/origin/internal/host/monitor.go:50" pkg=host-state
time="2021-06-22T09:28:50Z" level=debug msg="HostMonitoring took : 1.791542ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=host-state
InfraEnv added annotation - workaround
time="2021-06-22T09:32:44Z" level=info msg="InfraEnv Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*InfraEnvReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/infraenv_controller.go:80" go-id=690 infra_env=myinfraenv infra_env_namespace=demo-worker2 request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:139" bare_metal_host= bare_metal_host_namespace= go-id=628 request_id=c1522318-bcf4-4b94-8d79-4482ec5f1869
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:136" bare_metal_host= bare_metal_host_namespace= go-id=628 request_id=c1522318-bcf4-4b94-8d79-4482ec5f1869
time="2021-06-22T09:32:44Z" level=info msg="prepare image for cluster 8f57f26c-7721-4180-b322-620d58d37fea" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).GenerateClusterISOInternal" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:861" go-id=690 pkg=Inventory request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="Successfully uploaded file 8f57f26c-7721-4180-b322-620d58d37fea/discovery.ign" func="github.com/openshift/assisted-service/pkg/s3wrapper.(*FSClient).Upload" file="/go/src/github.com/openshift/origin/pkg/s3wrapper/filesystem.go:76" go-id=690 request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="Creating minimal ISO for cluster 8f57f26c-7721-4180-b322-620d58d37fea" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).generateClusterMinimalISO.func1" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:1098" go-id=690 pkg=Inventory request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="Uploading minimal ISO for cluster 8f57f26c-7721-4180-b322-620d58d37fea" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).generateClusterMinimalISO" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:1114" go-id=690 pkg=Inventory request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="Successfully uploaded file discovery-image-8f57f26c-7721-4180-b322-620d58d37fea.iso" func="github.com/openshift/assisted-service/pkg/s3wrapper.(*FSClient).UploadStream" file="/go/src/github.com/openshift/origin/pkg/s3wrapper/filesystem.go:159" go-id=690 request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="Generated cluster <8f57f26c-7721-4180-b322-620d58d37fea> image with ignition config {\n \"ignition\": {\n \"version\": \"3.1.0\"\n },\n \"passwd\": {\n \"users\": [\n *****\n ]\n },\n \"systemd\": {\n \"units\": [{\n \"name\": \"agent.service\",\n \"enabled\": true,\n \"contents\": \"[Service]\\nType=simple\\nRestart=always\\nRestartSec=3\\nStartLimitInterval=0\\nEnvironment=HTTP_PROXY=\\nEnvironment=http_proxy=\\nEnvironment=HTTPS_PROXY=\\nEnvironment=https_proxy=\\nEnvironment=NO_PROXY=\\nEnvironment=no_proxy=\\nEnvironment=PULL_SECRET_TOKEN=*****\\nTimeoutStartSec=180\\nExecStartPre=/usr/local/bin/agent-fix-bz1964591 quay.io/ocpmetal/assisted-installer-agent:latest\\nExecStartPre=podman run --privileged --rm -v /usr/local/bin:/hostbin quay.io/ocpmetal/assisted-installer-agent:latest cp /usr/bin/agent /hostbin\\nExecStart=/usr/local/bin/agent --url https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org --cluster-id 8f57f26c-7721-4180-b322-620d58d37fea --agent-version quay.io/ocpmetal/assisted-installer-agent:latest --insecure=false --cacert /etc/assisted-service/service-ca-cert.crt\\n\\n[Unit]\\nWants=network-online.target\\nAfter=network-online.target\\n\\n[Install]\\nWantedBy=multi-user.target\"\n },\n {\n \"name\": \"selinux.service\",\n \"enabled\": true,\n \"contents\": \"[Service]\\nType=oneshot\\nExecStartPre=checkmodule -M -m -o /root/assisted.mod /root/assisted.te\\nExecStartPre=semodule_package -o /root/assisted.pp -m /root/assisted.mod\\nExecStart=semodule -i /root/assisted.pp\\n\\n[Install]\\nWantedBy=multi-user.target\"\n }\n ]\n },\n \"storage\": {\n \"files\": [{\n \"overwrite\": true,\n \"path\": \"/usr/local/bin/agent-fix-bz1964591\",\n \"mode\": 755,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:,%23%21%2Fusr%2Fbin%2Fsh%0A%0A%23%20This%20script%20is%20a%20workaround%20for%20bugzilla%201964591%20where%20symlinks%20inside%20%2Fvar%2Flib%2Fcontainers%2F%20get%0A%23%20corrupted%20under%20some%20circumstances.%0A%23%0A%23%20In%20order%20to%20let%20agent.service%20start%20correctly%20we%20are%20checking%20here%20whether%20the%20requested%0A%23%20container%20image%20exists%20and%20in%20case%20%22podman%20images%22%20returns%20an%20error%20we%20try%20removing%20the%20faulty%0A%23%20image.%0A%23%0A%23%20In%20such%20a%20scenario%20agent.service%20will%20detect%20the%20image%20is%20not%20present%20and%20pull%20it%20again.%20In%20case%0A%23%20the%20image%20is%20present%20and%20can%20be%20detected%20correctly%2C%20no%20any%20action%20is%20required.%0A%0AIMAGE=$%28echo%20$1%20%7C%20sed%20%27s%2F:.%2A%2F%2F%27%29%0Apodman%20images%20%7C%20grep%20$IMAGE%20%7C%7C%20podman%20rmi%20--force%20$1%20%7C%7C%20true%0A\" }\n },\n {\n \"overwrite\": true,\n \"path\": \"/etc/motd\",\n \"mode\": 420,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:,%0A%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%0AThis%20is%20a%20host%20being%20installed%20by%20the%20OpenShift%20Assisted%20Installer.%0AIt%20will%20be%20installed%20from%20scratch%20during%20the%20installation.%0AThe%20primary%20service%20is%20agent.service.%20%20To%20watch%20its%20status%20run%20e.g%0Asudo%20journalctl%20-u%20agent.service%0A%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%20%20%2A%2A%0A\" }\n },\n {\n \"overwrite\": true,\n \"path\": \"/etc/NetworkManager/conf.d/01-ipv6.conf\",\n \"mode\": 420,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:,%0A%5Bconnection%5D%0Aipv6.dhcp-iaid=mac%0Aipv6.dhcp-duid=ll%0A\" }\n },\n {\n \"overwrite\": true,\n \"path\": \"/root/.docker/config.json\",\n \"mode\": 420,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:,*****\" }\n },\n {\n \"overwrite\": true,\n \"path\": \"/root/assisted.te\",\n \"mode\": 420,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:text/plain;base64,Cm1vZHVsZSBhc3Npc3RlZCAxLjA7CnJlcXVpcmUgewogICAgICAgIHR5cGUgY2hyb255ZF90OwogICAgICAgIHR5cGUgY29udGFpbmVyX2ZpbGVfdDsKICAgICAgICB0eXBlIHNwY190OwogICAgICAgIGNsYXNzIHVuaXhfZGdyYW1fc29ja2V0IHNlbmR0bzsKICAgICAgICBjbGFzcyBkaXIgc2VhcmNoOwogICAgICAgIGNsYXNzIHNvY2tfZmlsZSB3cml0ZTsKfQojPT09PT09PT09PT09PSBjaHJvbnlkX3QgPT09PT09PT09PT09PT0KYWxsb3cgY2hyb255ZF90IGNvbnRhaW5lcl9maWxlX3Q6ZGlyIHNlYXJjaDsKYWxsb3cgY2hyb255ZF90IGNvbnRhaW5lcl9maWxlX3Q6c29ja19maWxlIHdyaXRlOwphbGxvdyBjaHJvbnlkX3Qgc3BjX3Q6dW5peF9kZ3JhbV9zb2NrZXQgc2VuZHRvOwo=\" }\n },\n {\n \"overwrite\": true,\n \"path\": \"/etc/pki/ca-trust/source/anchors/rh-it-root-ca.crt\",\n \"mode\": 420,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:,*****\" }\n },\n {\n \"path\": \"/etc/assisted-service/service-ca-cert.crt\",\n \"mode\": 420,\n \"overwrite\": true,\n \"user\": {\n \"name\": \"root\"\n },\n \"contents\": { \"source\": \"data:text/plain;charset=utf-8;base64,LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tCk1JSURkekNDQWwrZ0F3SUJBZ0lJTys0Q3NEZ3JCS2N3RFFZSktvWklodmNOQVFFTEJRQXdKakVrTUNJR0ExVUUKQXd3YmFXNW5jbVZ6Y3kxdmNHVnlZWFJ2Y2tBeE5qSXpOelUwT0RJMU1CNFhEVEl4TURZeE5URXhNRFV3TWxvWApEVEl6TURZeE5URXhNRFV3TTFvd0t6RXBNQ2NHQTFVRUF3d2dLaTVoY0hCekxtOXpkR1Z6ZEM1MFpYTjBMbTFsCmRHRnNhM1ZpWlM1dmNtY3dnZ0VpTUEwR0NTcUdTSWIzRFFFQkFRVUFBNElCRHdBd2dnRUtBb0lCQVFDNEh5ajkKMWVLaDF0ci9DS3liK3Vsd3d6Qmtua2ZkUlVuS0NMNklKZCtMcWIwVCtMUDhaMDVrNFB5RUhzV3o5RDFCM20vQwo1S2x4a3FDcTFqZitTSk50aWpLTmFFN1l2T2lFekc0d0JKRnRDY0JKby9FamVJTE1wTXc1OFJOMWJHMDJtM0xRClhXQzJqek5mZEZaV2J3VFhGTGw5U0R0SXkvMzdaQTFhZnFhNDZ5VEJrbDhVSFV1bEQ3Z3NaRTdldGQxNElMNzYKRExRaFJXUS9yUnZnWlpqYWpVVGQxTlBWaEllc0xGZEI2bHJjL0pJSHhTeENFOVRwN3QycmQxdW1hZkRBVVROYQpIUytUQWovdE8yNFRLZlBLQkVUeS85eXRwYTEwUXdkVWpXVXAvRlJIMVV4VG5MYis0dUc1eXlFV0xwTE9IeGM5Ckk0NkFDNzRnK1pLUDErS2hBZ01CQUFHamdhTXdnYUF3RGdZRFZSMFBBUUgvQkFRREFnV2dNQk1HQTFVZEpRUU0KTUFvR0NDc0dBUVVGQndNQk1Bd0dBMVVkRXdFQi93UUNNQUF3SFFZRFZSME9CQllFRlByOHBGdGJsYStJa0VJLwpmVjIvTFZ6U2RLWDhNQjhHQTFVZEl3UVlNQmFBRkdXME56Rm1yck5HMTZncEIzMlVmZkMweVlZL01Dc0dBMVVkCkVRUWtNQ0tDSUNvdVlYQndjeTV2YzNSbGMzUXVkR1Z6ZEM1dFpYUmhiR3QxWW1VdWIzSm5NQTBHQ1NxR1NJYjMKRFFFQkN3VUFBNElCQVFCVStPYzZ3SUh2NHE4THpsQ0pMNnMyMmJyVHNJVTI4MzhZMThKek1lZ3ZEMjkyVmVEawpJcmFGWjc1Z09lQnpYME92L1Mrb2dUT3NlRDkvT2tmbTcyaS9qTW1iNEhCRDFPMWZ5NzREbi9ndUJiSlhicE9OCnZFWTJTZFlhai9SMUZqbzhzSXFHRFZQVFMxd3R4QkZSN1BwVlIrRGFPQUJTUFdOM2lreHl2L21xSU5jWFJSTDQKYVluRjF3RGpDWktWbzM5dGIvOXhBdjJJRVRmcE1jbnRWZzZLSXh4Yk5yUXVVdmZERERiSUtPWWxWakIrVXg0aApUMW90Q3pmYXpCYTh2OHZSN29wYVdPZnUyRlBKQ1FTYlVwdDlad1hXUW9VMHVJR0g2em9leGJBa3BEZTdJZEhECm93OGZFTGJYVVhDeVY5WHE3bVZMSWVYUE1hUEtYaTR0bjN5VwotLS0tLUVORCBDRVJUSUZJQ0FURS0tLS0tCi0tLS0tQkVHSU4gQ0VSVElGSUNBVEUtLS0tLQpNSUlERERDQ0FmU2dBd0lCQWdJQkFUQU5CZ2txaGtpRzl3MEJBUXNGQURBbU1TUXdJZ1lEVlFRRERCdHBibWR5ClpYTnpMVzl3WlhKaGRHOXlRREUyTWpNM05UUTRNalV3SGhjTk1qRXdOakUxTVRFd01ESTBXaGNOTWpNd05qRTEKTVRFd01ESTFXakFtTVNRd0lnWURWUVFEREJ0cGJtZHlaWE56TFc5d1pYSmhkRzl5UURFMk1qTTNOVFE0TWpVdwpnZ0VpTUEwR0NTcUdTSWIzRFFFQkFRVUFBNElCRHdBd2dnRUtBb0lCQVFDK1F2Z2x5YWZOWk9wWXh6S2cxWFpuCktObUR2ZjU5M2d1WW5mYThhZDEvWEZNRjdoalZGZHBOdGh4ZXlEaXljalVYVXdDYk5INFg3RkgydXVOUUxsS20KZ0tORDU4SkViaEhkV3M0QXdMck0zUm5mdFAvZ0xxZWNTQ29ZOUdXK1hhZFc5MzZNTDR4NFFGUkI5K3JyQW5MNgpDS1VkZ3ByZzArZFBvVVdoK1NTemdWbzY0RGJWQzhBbVJtaFcxWERrZ3dDMEpOakNjWmM0bTlLSjFsTVBIOXl2Ck5VM0xtZGMxTzVrVXNrSU9lZGxNTy8zQWNmNjBaZGNKNm9rZ2xTU283SzI4ZmhnMHVNUUlhNXliOFhBZVpIVlYKdzBGL3ZSd0YrUFFleXQrSUlKZXMzaWhqQXVzVDRHd3ZVRTVaRDR0Ry9WWmpkb3hnYjFiL3JTMjEvUWtoNklyagpBZ01CQUFHalJUQkRNQTRHQTFVZER3RUIvd1FFQXdJQ3BEQVNCZ05WSFJNQkFmOEVDREFHQVFIL0FnRUFNQjBHCkExVWREZ1FXQkJSbHREY3hacTZ6UnRlb0tRZDlsSDN3dE1tR1B6QU5CZ2txaGtpRzl3MEJBUXNGQUFPQ0FRRUEKTnpxSWdON1ZiYVdTS3d1UVd2QmVuN2lGV1FwMzdzd0cya2svUVRSNFVUVC9oVEE5UHlyZ3VXbmkvTXRqVHdCWApxRHVPdVJOTS9jTWRtZGZYUjRNZlhHbzFpR2IzWlBGb1BESGxUb29hWXdRV3VPZDNxSWNxYys5cVRHOElKM2toCk5tOWhFdEN5bFl5dm5QMUxRTlhFTVVoZGJkNU5vcm9sUkwyWlJoVW5hcGxyazcxaXptZnFYN0ZyeFBub05KUDAKMFlnQTl0RzhtOHJvUElGQmd1b08xbnZSeGhXNnFmNEc0c1p6RXZtRGdueXF0RnhPMUlaL3pTR2R1RkVicUVoKwpmdDVsZ2JGcXlRSWNjQ2lRaUJEQVNUMURNV3cyZFpUbFpUWHl6T002WDNla1VxNWdub1UzVDdNS1RaYTVjSmN2ClNGK0c5QkFkYUtjT2Vnb3JhdGN4Q2c9PQotLS0tLUVORCBDRVJUSUZJQ0FURS0tLS0tCg==\" }\n }]\n }\n}" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).getIgnitionConfigForLogging" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:1060" go-id=690 pkg=Inventory request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=debug msg="Pushing cluster event single-node demo-worker2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*controllerEventsWrapper).AddEvent" file="/go/src/github.com/openshift/origin/internal/controller/controllers/controller_event_wrapper.go:36"
time="2021-06-22T09:32:44Z" level=info msg="ClusterDeployment Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:113" cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=454c76b3-7a77-493d-94b8-96fa837849d6
time="2021-06-22T09:32:44Z" level=info msg="ISODownloadURL changed from https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.fbu449REyECCDMhweeJc4iMp8YsXXWJCw9ki3ggDrvtvVyK_F813I1JXgC7E6Q3jEmi5PsvDrm86PoA3rlfjgA to https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.fbu449REyECCDMhweeJc4iMp8YsXXWJCw9ki3ggDrvtvVyK_F813I1JXgC7E6Q3jEmi5PsvDrm86PoA3rlfjgA" func="github.com/openshift/assisted-service/internal/controller/controllers.(*InfraEnvReconciler).updateEnsureISOSuccess" file="/go/src/github.com/openshift/origin/internal/controller/controllers/infraenv_controller.go:317" go-id=690 infra_env=myinfraenv infra_env_namespace=demo-worker2 request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="InfraEnv Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*InfraEnvReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/infraenv_controller.go:77" go-id=690 infra_env=myinfraenv infra_env_namespace=demo-worker2 request_id=7de3dca2-4b3f-4a1f-82dc-732087b623f5
time="2021-06-22T09:32:44Z" level=info msg="InfraEnv Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*InfraEnvReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/infraenv_controller.go:80" go-id=690 infra_env=myinfraenv infra_env_namespace=demo-worker2 request_id=d8923af1-7133-4c41-958b-90fd5956c11a
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:139" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=debug msg="Started BMH reconcile for demo-worker2/xxxostest-extraworker-2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:503" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=debug msg="BMH value &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5154619 2 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {assisted-service Update metal3.io/v1alpha1 2021-06-22 09:21:46 +0000 UTC FieldsV1 {\"f:spec\":{\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:30:37 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil 0xc002c51080 nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:30:37 +0000 UTC unknown 0xc0009a3c20 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc000fbcae0} 0xc001555290 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 2}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:504" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=debug msg="BMH label infraenvs.agent-install.openshift.io value myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:472" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=debug msg="Loading InfraEnv myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:479" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=debug msg="Updating dirty BMH &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5154619 2 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {assisted-service Update metal3.io/v1alpha1 2021-06-22 09:21:46 +0000 UTC FieldsV1 {\"f:spec\":{\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:30:37 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:30:37 +0000 UTC unknown 0xc0009a3c20 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc000fbcae0} 0xc001555290 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 2}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:152" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=info msg="prepare image for cluster 8f57f26c-7721-4180-b322-620d58d37fea" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).GenerateClusterISOInternal" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:861" go-id=690 pkg=Inventory request_id=d8923af1-7133-4c41-958b-90fd5956c11a
time="2021-06-22T09:32:44Z" level=error msg="request came too soon after previous request" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).GenerateClusterISOInternal" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:915" go-id=690 pkg=Inventory request_id=d8923af1-7133-4c41-958b-90fd5956c11a
time="2021-06-22T09:32:44Z" level=error msg="generate cluster ISO failed" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).GenerateClusterISOInternal.func1" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:886" go-id=690 pkg=Inventory request_id=d8923af1-7133-4c41-958b-90fd5956c11a
time="2021-06-22T09:32:44Z" level=info msg="Image myinfraenv being prepared for cluster " func="github.com/openshift/assisted-service/internal/controller/controllers.(*InfraEnvReconciler).handleEnsureISOErrors" file="/go/src/github.com/openshift/origin/internal/controller/controllers/infraenv_controller.go:344" go-id=690 infra_env=myinfraenv infra_env_namespace=demo-worker2 request_id=d8923af1-7133-4c41-958b-90fd5956c11a
time="2021-06-22T09:32:44Z" level=info msg="ClusterDeployment Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*ClusterDeploymentsReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/clusterdeployments_controller.go:110" agent_cluster_install=test-agent-cluster-install agent_cluster_install_namespace=demo-worker2 cluster_deployment=single-node cluster_deployment_namespace=demo-worker2 go-id=706 request_id=454c76b3-7a77-493d-94b8-96fa837849d6
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:136" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5b10d58d-4e67-4ee6-9991-cb7d34fe0e53
time="2021-06-22T09:32:44Z" level=info msg="InfraEnv Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*InfraEnvReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/infraenv_controller.go:77" go-id=690 infra_env=myinfraenv infra_env_namespace=demo-worker2 request_id=d8923af1-7133-4c41-958b-90fd5956c11a
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:139" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=debug msg="Started BMH reconcile for demo-worker2/xxxostest-extraworker-2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:503" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=debug msg="BMH value &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5155777 3 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:30:37 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:30:37 +0000 UTC unknown 0xc0026d81e0 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc0014a9ac0} 0xc000ec0090 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 2}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:504" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=debug msg="BMH label infraenvs.agent-install.openshift.io value myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:472" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=debug msg="Loading InfraEnv myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:479" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=debug msg="Updating dirty BMH &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5155777 3 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:30:37 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:30:37 +0000 UTC unknown 0xc0026d81e0 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc0014a9ac0} 0xc000ec0090 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 2}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:152" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:136" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=82578e92-6ec5-4245-ab53-df47b5970931
time="2021-06-22T09:32:44Z" level=debug msg="Request: {HEAD /api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw HTTP/1.1 1 1 map[Accept:[*/*] Accept-Encoding:[gzip, deflate] Forwarded:[for=192.168.111.20;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[python-requests/2.20.0] X-Forwarded-For:[192.168.111.20] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59196 /api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc0009a1290 <nil> <nil> 0xc0010d2240}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:32:44Z" level=debug msg="Authenticating cluster 648ecfeb-1d4a-4d77-9d18-31a5f22388ce JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:32:44Z" level=error msg="failed to get cluster 648ecfeb-1d4a-4d77-9d18-31a5f22388ce" func="github.com/openshift/assisted-service/internal/bminventory.(*bareMetalInventory).DownloadClusterISOHeaders" file="/go/src/github.com/openshift/origin/internal/bminventory/inventory.go:772" cluster_id=648ecfeb-1d4a-4d77-9d18-31a5f22388ce error="record not found" go-id=3651239 pkg=Inventory request_id=fef942d3-da48-4f04-9324-541e8e6ecc82
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:139" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:44Z" level=debug msg="Started BMH reconcile for demo-worker2/xxxostest-extraworker-2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:503" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:44Z" level=debug msg="BMH value &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5155779 3 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:32:44 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {OK 2021-06-22 09:32:44 +0000 UTC unknown 0xc001a08960 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc003491910} 0xc00337f050 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 2}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:504" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:44Z" level=debug msg="BMH label infraenvs.agent-install.openshift.io value myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:472" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:44Z" level=debug msg="Loading InfraEnv myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:479" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:44Z" level=debug msg="Updating dirty BMH &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5155779 3 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:32:44 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {OK 2021-06-22 09:32:44 +0000 UTC unknown 0xc001a08960 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc003491910} 0xc00337f050 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 2}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:152" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:44Z" level=info msg="BareMetalHost Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:136" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=61bf5ab9-446d-4818-a7c3-afe686b48cc2
time="2021-06-22T09:32:50Z" level=debug msg="Running ClusterMonitoring" func="github.com/openshift/assisted-service/internal/cluster.(*Manager).ClusterMonitoring" file="/go/src/github.com/openshift/origin/internal/cluster/cluster.go:500" pkg=cluster-state
time="2021-06-22T09:32:50Z" level=debug msg="Running HostMonitoring" func="github.com/openshift/assisted-service/internal/host.(*Manager).HostMonitoring" file="/go/src/github.com/openshift/origin/internal/host/monitor.go:50" pkg=host-state
time="2021-06-22T09:32:50Z" level=debug msg="HostMonitoring took : 1.65488ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=host-state
time="2021-06-22T09:32:50Z" level=debug msg="ClusterMonitoring took : 2.168157ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=cluster-state
time="2021-06-22T09:32:52Z" level=info msg="BareMetalHost Reconcile started" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:139" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:52Z" level=debug msg="Started BMH reconcile for demo-worker2/xxxostest-extraworker-2" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:503" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:52Z" level=debug msg="BMH value &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5155857 3 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:32:52 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:32:52 +0000 UTC unknown 0xc0004af860 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc0003c8e30} 0xc003580cf0 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 3}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).reconcileBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:504" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:52Z" level=debug msg="BMH label infraenvs.agent-install.openshift.io value myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:472" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:52Z" level=debug msg="Loading InfraEnv myinfraenv" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).findInfraEnvForBMH" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:479" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:52Z" level=debug msg="Updating dirty BMH &{{BareMetalHost metal3.io/v1alpha1} {xxxostest-extraworker-2 demo-worker2 c4c81d01-7f8b-4e90-906f-4477774a5f24 5155857 3 2021-06-22 09:17:47 +0000 UTC <nil> <nil> map[infraenvs.agent-install.openshift.io:myinfraenv] map[inspect.metal3.io:disabled kubectl.kubernetes.io/last-applied-configuration:{\"apiVersion\":\"metal3.io/v1alpha1\",\"kind\":\"BareMetalHost\",\"metadata\":{\"annotations\":{\"inspect.metal3.io\":\"disabled\"},\"labels\":{\"infraenvs.agent-install.openshift.io\":\"myinfraenv\"},\"name\":\"xxxostest-extraworker-2\",\"namespace\":\"demo-worker2\"},\"spec\":{\"automatedCleaningMode\":\"disabled\",\"bmc\":{\"address\":\"redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde\",\"credentialsName\":\"ostest-extraworker-2-bmc-secret\"},\"bootMACAddress\":\"00:ff:7e:29:1f:93\",\"online\":true}}\n] [] [baremetalhost.metal3.io] [{kubectl-client-side-apply Update metal3.io/v1alpha1 2021-06-22 09:17:47 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:annotations\":{\".\":{},\"f:inspect.metal3.io\":{},\"f:kubectl.kubernetes.io/last-applied-configuration\":{}},\"f:labels\":{\".\":{},\"f:infraenvs.agent-install.openshift.io\":{}}},\"f:spec\":{\".\":{},\"f:automatedCleaningMode\":{},\"f:bmc\":{\".\":{},\"f:address\":{},\"f:credentialsName\":{}},\"f:bootMACAddress\":{},\"f:online\":{}}}} {baremetal-operator Update metal3.io/v1alpha1 2021-06-22 09:32:52 +0000 UTC FieldsV1 {\"f:metadata\":{\"f:finalizers\":{\".\":{},\"v:\\\"baremetalhost.metal3.io\\\"\":{}}},\"f:status\":{\".\":{},\"f:errorCount\":{},\"f:errorMessage\":{},\"f:errorType\":{},\"f:goodCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}},\"f:hardware\":{\".\":{},\"f:cpu\":{\".\":{},\"f:arch\":{},\"f:clockMegahertz\":{},\"f:count\":{},\"f:flags\":{},\"f:model\":{}},\"f:firmware\":{\".\":{},\"f:bios\":{}},\"f:hostname\":{},\"f:nics\":{},\"f:ramMebibytes\":{},\"f:storage\":{},\"f:systemVendor\":{\".\":{},\"f:manufacturer\":{},\"f:productName\":{}}},\"f:hardwareProfile\":{},\"f:lastUpdated\":{},\"f:operationHistory\":{\".\":{},\"f:deprovision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:inspect\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:provision\":{\".\":{},\"f:end\":{},\"f:start\":{}},\"f:register\":{\".\":{},\"f:end\":{},\"f:start\":{}}},\"f:operationalStatus\":{},\"f:poweredOn\":{},\"f:provisioning\":{\".\":{},\"f:ID\":{},\"f:bootMode\":{},\"f:image\":{\".\":{},\"f:format\":{},\"f:url\":{}},\"f:rootDeviceHints\":{\".\":{},\"f:deviceName\":{}},\"f:state\":{}},\"f:triedCredentials\":{\".\":{},\"f:credentials\":{\".\":{},\"f:name\":{},\"f:namespace\":{}},\"f:credentialsVersion\":{}}}}}]} {[] {redfish-virtualmedia+http://192.168.111.1:8000/redfish/v1/Systems/6a97bd84-17f6-4b23-8c86-1944f1956cde ostest-extraworker-2-bmc-secret false} <nil> <nil> 00:ff:7e:29:1f:93 true nil <nil> nil nil nil false disabled} {error provisioned registration error 2021-06-22 09:32:52 +0000 UTC unknown 0xc0004af860 {deprovisioning b82809bd-270b-425f-9f7e-4f31a98b0409 {https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw 0xc0003c8e30} 0xc003580cf0 UEFI <nil>} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} {&SecretReference{Name:ostest-extraworker-2-bmc-secret,Namespace:demo-worker2,} 5147966} Host adoption failed: Error while attempting to adopt node b82809bd-270b-425f-9f7e-4f31a98b0409: Validation of image href https://assisted-service-assisted-installer.apps.ostest.test.metalkube.org/api/assisted-install/v1/clusters/648ecfeb-1d4a-4d77-9d18-31a5f22388ce/downloads/image?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiNjQ4ZWNmZWItMWQ0YS00ZDc3LTlkMTgtMzFhNWYyMjM4OGNlIn0.XWIkYHJuYSMdYAqn1ebKa1Ugm4mfGrAleGrOJ8VB2AxliWGd1tvize0dX6EA6HRyqbhrXgIoKjxkRB6vzhMPDw failed, reason: Got HTTP code 404 instead of 200 in response to HEAD request.. true {{2021-06-22 09:17:47 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:17:58 +0000 UTC} {2021-06-22 09:17:58 +0000 UTC 2021-06-22 09:18:09 +0000 UTC} {2021-06-22 09:30:27 +0000 UTC 0001-01-01 00:00:00 +0000 UTC}} 3}}" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:152" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:52Z" level=info msg="BareMetalHost Reconcile ended" func="github.com/openshift/assisted-service/internal/controller/controllers.(*BMACReconciler).Reconcile.func1" file="/go/src/github.com/openshift/origin/internal/controller/controllers/bmh_agent_controller.go:136" bare_metal_host=xxxostest-extraworker-2 bare_metal_host_namespace=demo-worker2 go-id=628 request_id=5045dd38-dead-40f8-9f6e-e9615feeffa1
time="2021-06-22T09:32:58Z" level=debug msg="Running HostMonitoring" func="github.com/openshift/assisted-service/internal/host.(*Manager).HostMonitoring" file="/go/src/github.com/openshift/origin/internal/host/monitor.go:50" pkg=host-state
time="2021-06-22T09:32:58Z" level=debug msg="HostMonitoring took : 1.927203ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=host-state
time="2021-06-22T09:32:58Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59528 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc002a60a50 <nil> <nil> 0xc001490db0}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:32:58Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:33:00Z" level=debug msg="Running ClusterMonitoring" func="github.com/openshift/assisted-service/internal/cluster.(*Manager).ClusterMonitoring" file="/go/src/github.com/openshift/origin/internal/cluster/cluster.go:500" pkg=cluster-state
time="2021-06-22T09:33:00Z" level=debug msg="ClusterMonitoring took : 2.796089ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=cluster-state
time="2021-06-22T09:33:00Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59574 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc00250eb00 <nil> <nil> 0xc003e30660}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:33:00Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:33:02Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59622 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc000d8fb80 <nil> <nil> 0xc002b83e60}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:33:02Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:33:04Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59686 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc0027c3130 <nil> <nil> 0xc000fd0c60}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:33:04Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:33:06Z" level=debug msg="Running HostMonitoring" func="github.com/openshift/assisted-service/internal/host.(*Manager).HostMonitoring" file="/go/src/github.com/openshift/origin/internal/host/monitor.go:50" pkg=host-state
time="2021-06-22T09:33:06Z" level=debug msg="HostMonitoring took : 1.50125ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=host-state
time="2021-06-22T09:33:06Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59724 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc002882e70 <nil> <nil> 0xc001097bf0}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:33:06Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:33:08Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59794 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc0027f4160 <nil> <nil> 0xc002c07260}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:33:08Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
time="2021-06-22T09:33:10Z" level=debug msg="Running ClusterMonitoring" func="github.com/openshift/assisted-service/internal/cluster.(*Manager).ClusterMonitoring" file="/go/src/github.com/openshift/origin/internal/cluster/cluster.go:500" pkg=cluster-state
time="2021-06-22T09:33:10Z" level=debug msg="ClusterMonitoring took : 2.676221ms" func=github.com/openshift/assisted-service/pkg/commonutils.MeasureOperation.func1 file="/go/src/github.com/openshift/origin/pkg/commonutils/common_utils.go:14" pkg=cluster-state
time="2021-06-22T09:33:10Z" level=debug msg="Request: {GET /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg HTTP/1.1 1 1 map[Accept:[*/*] Forwarded:[for=192.168.111.1;host=assisted-service-assisted-installer.apps.ostest.test.metalkube.org;proto=https] User-Agent:[curl/7.61.1] X-Forwarded-For:[192.168.111.1] X-Forwarded-Host:[assisted-service-assisted-installer.apps.ostest.test.metalkube.org] X-Forwarded-Port:[443] X-Forwarded-Proto:[https]] {} <nil> 0 [] false assisted-service-assisted-installer.apps.ostest.test.metalkube.org map[] map[] <nil> map[] 10.128.2.1:59834 /api/assisted-install/v1/clusters/8f57f26c-7721-4180-b322-620d58d37fea/events?api_key=eyJhbGciOiJFUzI1NiIsInR5cCI6IkpXVCJ9.eyJjbHVzdGVyX2lkIjoiOGY1N2YyNmMtNzcyMS00MTgwLWIzMjItNjIwZDU4ZDM3ZmVhIn0.4SnV42SZ96jdyVquoSC0a5fx-rVsdiNmkbc6GPZGQPa-MKyUQtt41zx01oDr_sqZL-m0On8FGPa77Y8kbmJ4gg 0xc0028416b0 <nil> <nil> 0xc0030a6bd0}" func=github.com/openshift/assisted-service/internal/metrics.Handler.func1 file="/go/src/github.com/openshift/origin/internal/metrics/reporter.go:20" pkg=matched-h
time="2021-06-22T09:33:10Z" level=debug msg="Authenticating cluster 8f57f26c-7721-4180-b322-620d58d37fea JWT" func="github.com/openshift/assisted-service/pkg/auth.(*LocalAuthenticator).AuthAgentAuth" file="/go/src/github.com/openshift/origin/pkg/auth/local_authenticator.go:82" pkg=auth
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment