This job view page is being replaced by Spyglass soon. Check out the new job view.
ResultFAILURE
Tests 0 failed / 244 succeeded
Started2022-09-14 23:12
Elapsed3m31s
Revisionmain

No Test Failures!


Show 244 Passed Tests

Error lines from build-log.txt

... skipping 216 lines ...
I0914 23:14:55.206220   18760 server.go:148] controller-runtime/webhook "msg"="Registering webhook" "path"="/validate-infrastructure-cluster-x-k8s-io-v1beta1-awsclustercontrolleridentity"
Starting the manager
I0914 23:14:55.206651   18760 server.go:216] controller-runtime/webhook/webhooks "msg"="Starting webhook server" 
I0914 23:14:55.206998   18760 logr.go:261] controller-runtime/certwatcher "msg"="Updated current TLS certificate" 
I0914 23:14:55.207185   18760 logr.go:261] controller-runtime/webhook "msg"="Serving webhook server" "host"="" "port"=46751
I0914 23:14:55.207389   18760 logr.go:261] controller-runtime/certwatcher "msg"="Starting certificate watcher" 
2022/09/14 23:14:56 http: TLS handshake error from 127.0.0.1:51476: EOF
I0914 23:14:56.643071   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
I0914 23:14:56.681433   18760 subnets.go:49]  "msg"="Reconciling subnets" 
I0914 23:14:56.681786   18760 securitygroups.go:386]  "msg"="Created security group for role" "role"="bastion" "security-group"="sg-bastion"
I0914 23:14:56.681874   18760 securitygroups.go:386]  "msg"="Created security group for role" "role"="apiserver-lb" "security-group"="sg-apiserver-lb"
I0914 23:14:56.681975   18760 securitygroups.go:386]  "msg"="Created security group for role" "role"="lb" "security-group"="sg-lb"
I0914 23:14:56.682030   18760 securitygroups.go:386]  "msg"="Created security group for role" "role"="controlplane" "security-group"="sg-controlplane"
I0914 23:14:56.682090   18760 securitygroups.go:386]  "msg"="Created security group for role" "role"="node" "security-group"="sg-node"
I0914 23:14:56.682481   18760 awscluster_controller.go:323]  "msg"="Waiting on API server ELB DNS name" 
I0914 23:14:56.747263   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
E0914 23:14:56.794409   18760 awscluster_controller.go:281]  "msg"="failed to reconcile network" "error"="failed to create new vpc: failed to create vpc: The maximum number of VPCs has been reached" 
I0914 23:14:56.851417   18760 awscluster_controller.go:209]  "msg"="Reconciling AWSCluster delete" 
I0914 23:14:56.875744   18760 loadbalancer.go:589]  "msg"="Deleted AWS cloud provider load balancers" 
I0914 23:14:56.875820   18760 loadbalancer.go:186]  "msg"="Deleted control plane load balancer" "name"="test-cluster-apiserver"
I0914 23:14:56.912234   18760 bastion.go:131]  "msg"="Deleted bastion host" "id"="id-1"
I0914 23:14:56.960495   18760 routetables.go:243]  "msg"="Deleted route table" "route-table-id"="rt-12345"
I0914 23:14:57.001924   18760 eips.go:142]  "msg"="released ElasticIP" "allocation-id"="1234" "eip"="1.2.3.4"
I0914 23:14:57.085808   18760 gateways.go:116]  "msg"="Deleted Internet gateway in VPC" "internet-gateway-id"="ig-12345" "vpc-id"="vpc-exists"
I0914 23:14:57.197700   18760 subnets.go:484]  "msg"="Deleted subnet" "subnet-id"="subnet-1" "vpc-id"="vpc-exists"
I0914 23:14:57.238873   18760 vpc.go:278]  "msg"="Deleted VPC" "vpc-id"="vpc-exists"
I0914 23:14:57.521126   18760 awscluster_controller.go:151]  "msg"="Cluster Controller has not yet set OwnerRef" 
I0914 23:14:57.602577   18760 awscluster_controller.go:156]  "msg"="AWSCluster or linked Cluster is marked as paused. Won't reconcile" 
I0914 23:14:57.631781   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
I0914 23:14:57.660813   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
E0914 23:14:57.661683   18760 awscluster_controller.go:281]  "msg"="failed to reconcile network" "error"="failed to get resource" 
I0914 23:14:57.661928   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
E0914 23:14:57.662654   18760 awscluster_controller.go:291]  "msg"="failed to reconcile security groups" "error"="failed to get resource" 
I0914 23:14:57.662975   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
E0914 23:14:57.663708   18760 awscluster_controller.go:298]  "msg"="failed to reconcile bastion host" "error"="failed to get resource" 
I0914 23:14:57.663955   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
E0914 23:14:57.664629   18760 awscluster_controller.go:311]  "msg"="failed to reconcile load balancer" "error"="failed to get resource" 
I0914 23:14:57.664886   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
I0914 23:14:57.665572   18760 awscluster_controller.go:323]  "msg"="Waiting on API server ELB DNS name" 
I0914 23:14:57.665935   18760 awscluster_controller.go:263]  "msg"="Reconciling AWSCluster" 
I0914 23:14:57.834469   18760 awscluster_controller.go:329]  "msg"="Waiting on API server ELB DNS name to resolve" 
I0914 23:14:57.834934   18760 awscluster_controller.go:209]  "msg"="Reconciling AWSCluster delete" 
I0914 23:14:57.835262   18760 awscluster_controller.go:209]  "msg"="Reconciling AWSCluster delete" 
E0914 23:14:57.835328   18760 awscluster_controller.go:226]  "msg"="error deleting load balancer" "error"="failed to get resource" 
I0914 23:14:57.835489   18760 awscluster_controller.go:209]  "msg"="Reconciling AWSCluster delete" 
E0914 23:14:57.835552   18760 awscluster_controller.go:231]  "msg"="error deleting bastion" "error"="failed to get resource" 
I0914 23:14:57.835717   18760 awscluster_controller.go:209]  "msg"="Reconciling AWSCluster delete" 
E0914 23:14:57.835778   18760 awscluster_controller.go:236]  "msg"="error deleting security groups" "error"="failed to get resource" 
I0914 23:14:57.835933   18760 awscluster_controller.go:209]  "msg"="Reconciling AWSCluster delete" 
E0914 23:14:57.836001   18760 awscluster_controller.go:248]  "msg"="error deleting network" "error"="failed to get resource" 
E0914 23:14:58.023017   18760 awscluster_controller.go:428]  "msg"="Failed to get AWS cluster" "error"="AWSCluster.infrastructure.cluster.x-k8s.io \"aws-test\" not found" "cluster"="capi-test" "namespace"="namespace-ljmka-2qgz7" "objectMapper"="clusterToAWSCluster"
I0914 23:14:58.369710   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.510103   18760 instances.go:887]  "msg"="Updating security groups" "groups"=["3","1","2"]
I0914 23:14:58.554952   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:58.555189   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="id-1"
I0914 23:14:58.597979   18760 awsmachine_controller.go:390]  "msg"="EC2 instance successfully terminated" "instance-id"="id-1"
I0914 23:14:58.683183   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
E0914 23:14:58.717517   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"two\" with load balancer - error determining registration status: expected 1 ELB description for \"test-cluster-apiserver\", got 0" 
I0914 23:14:58.780106   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:58.780332   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="id-1"
E0914 23:14:58.809633   18760 awsmachine_controller.go:354]  "msg"="failed to terminate instance" "error"="failed to terminate instance with id \"id-1\": Failed to delete instance" 
E0914 23:14:58.827088   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827088   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827088   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827088   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827802   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827802   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827802   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.827802   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:58.828451   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="no connection available " 
E0914 23:14:58.828451   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="no connection available " 
E0914 23:14:58.828451   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="no connection available " 
E0914 23:14:58.828451   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="no connection available " 
I0914 23:14:58.831355   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
E0914 23:14:58.833428   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to create AWSMachine instance: Invalid instance" 
E0914 23:14:58.833428   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to create AWSMachine instance: Invalid instance" 
E0914 23:14:58.833428   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to create AWSMachine instance: Invalid instance" 
E0914 23:14:58.833428   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to create AWSMachine instance: Invalid instance" 
E0914 23:14:58.834214   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" 
E0914 23:14:58.834214   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" 
E0914 23:14:58.834214   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" 
E0914 23:14:58.834214   18760 awsmachine_controller.go:465]  "msg"="unable to find instance" "error"="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" 
E0914 23:14:58.838764   18760 awsmachine_controller.go:585]  "msg"="unable to get instance security groups" "error"="stop here" 
E0914 23:14:58.856795   18760 awsmachine_controller.go:585]  "msg"="unable to get instance security groups" "error"="stop here" 
E0914 23:14:58.864328   18760 awsmachine_controller.go:585]  "msg"="unable to get instance security groups" "error"="stop here" 
I0914 23:14:58.873655   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.876212   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:58.879388   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.881842   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:58.890662   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.893034   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
... skipping 6 lines ...
I0914 23:14:58.971663   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.971918   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:58.974197   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.976596   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:58.976726   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="test"
I0914 23:14:58.976805   18760 awsmachine_controller.go:623]  "msg"="Unable to delete entries from AWS Secret containing encrypted userdata" "secretPrefix"="test"
E0914 23:14:58.976868   18760 awsmachine_controller.go:552]  "msg"="unable to delete secrets" "error"="failed to delete entries from AWS Secret" 
E0914 23:14:58.976868   18760 awsmachine_controller.go:552]  "msg"="unable to delete secrets" "error"="failed to delete entries from AWS Secret" 
E0914 23:14:58.976868   18760 awsmachine_controller.go:552]  "msg"="unable to delete secrets" "error"="failed to delete entries from AWS Secret" 
E0914 23:14:58.976868   18760 awsmachine_controller.go:552]  "msg"="unable to delete secrets" "error"="failed to delete entries from AWS Secret" 
I0914 23:14:58.979141   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
E0914 23:14:58.982707   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: failed to generate init script" 
E0914 23:14:58.982707   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: failed to generate init script" 
E0914 23:14:58.982707   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: failed to generate init script" 
E0914 23:14:58.982707   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: failed to generate init script" 
I0914 23:14:58.985509   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.987551   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:58.987756   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" 
E0914 23:14:58.987756   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" 
E0914 23:14:58.987756   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" 
E0914 23:14:58.987756   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" 
I0914 23:14:58.996571   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:58.999452   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:58.999596   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" 
E0914 23:14:58.999596   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" 
E0914 23:14:58.999596   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" 
E0914 23:14:58.999596   18760 awsmachine_controller.go:574]  "msg"="failed to reconcile LB attachment" "error"="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" 
I0914 23:14:59.000141   18760 awsmachine_controller.go:436]  "msg"="Error state detected, skipping reconciliation" 
E0914 23:14:59.000185   18760 awsmachine_controller.go:440]  "msg"="unable to reconcile machine" "error"="secretPrefix present, but secretCount is not set" 
E0914 23:14:59.000185   18760 awsmachine_controller.go:440]  "msg"="unable to reconcile machine" "error"="secretPrefix present, but secretCount is not set" 
E0914 23:14:59.000185   18760 awsmachine_controller.go:440]  "msg"="unable to reconcile machine" "error"="secretPrefix present, but secretCount is not set" 
E0914 23:14:59.000185   18760 awsmachine_controller.go:440]  "msg"="unable to reconcile machine" "error"="secretPrefix present, but secretCount is not set" 
I0914 23:14:59.003816   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.006660   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:59.018145   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
E0914 23:14:59.018145   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
E0914 23:14:59.018145   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
E0914 23:14:59.018145   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
I0914 23:14:59.021579   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.023855   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:59.024044   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="failed to update resource tag" 
E0914 23:14:59.024044   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="failed to update resource tag" 
E0914 23:14:59.024044   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="failed to update resource tag" 
E0914 23:14:59.024044   18760 awsmachine_controller.go:565]  "msg"="failed to ensure tags" "error"="failed to update resource tag" 
I0914 23:14:59.026721   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.028819   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:59.029012   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
E0914 23:14:59.029012   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
E0914 23:14:59.029012   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
E0914 23:14:59.029012   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="json: cannot unmarshal number into Go value of type map[string]interface {}" 
I0914 23:14:59.032253   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.035124   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:59.035354   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to get core security groups" 
E0914 23:14:59.035354   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to get core security groups" 
E0914 23:14:59.035354   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to get core security groups" 
E0914 23:14:59.035354   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to get core security groups" 
I0914 23:14:59.038916   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.041538   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:59.044824   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.047115   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
E0914 23:14:59.047364   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to update security groups" 
E0914 23:14:59.047364   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to update security groups" 
E0914 23:14:59.047364   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to update security groups" 
E0914 23:14:59.047364   18760 awsmachine_controller.go:593]  "msg"="unable to ensure security groups" "error"="failed to update security groups" 
I0914 23:14:59.050379   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.052746   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:59.053974   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="running"
I0914 23:14:59.054083   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="secret"
I0914 23:14:59.055208   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="terminated"
I0914 23:14:59.055308   18760 awsmachine_controller.go:538]  "msg"="Unexpected EC2 instance termination" "instance-id"="myMachine" "state"="terminated"
... skipping 19 lines ...
I0914 23:14:59.066403   18760 awsmachine_controller.go:390]  "msg"="EC2 instance successfully terminated" "instance-id"="myMachine"
I0914 23:14:59.066821   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.066899   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="secret"
I0914 23:14:59.067039   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="myMachine"
I0914 23:14:59.068440   18760 awsmachine_controller.go:390]  "msg"="EC2 instance successfully terminated" "instance-id"="myMachine"
I0914 23:14:59.071224   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
E0914 23:14:59.073048   18760 awsmachine_controller.go:692]  "msg"="Failed to create AWS Secret entry" "error"="connection error" "secretPrefix"="test/secret"
E0914 23:14:59.073048   18760 awsmachine_controller.go:692]  "msg"="Failed to create AWS Secret entry" "error"="connection error" "secretPrefix"="test/secret"
E0914 23:14:59.073048   18760 awsmachine_controller.go:692]  "msg"="Failed to create AWS Secret entry" "error"="connection error" "secretPrefix"="test/secret"
E0914 23:14:59.073048   18760 awsmachine_controller.go:692]  "msg"="Failed to create AWS Secret entry" "error"="connection error" "secretPrefix"="test/secret"
E0914 23:14:59.073253   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: connection error" 
E0914 23:14:59.073253   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: connection error" 
E0914 23:14:59.073253   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: connection error" 
E0914 23:14:59.073253   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: connection error" 
I0914 23:14:59.075691   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.084517   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:59.095434   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
I0914 23:14:59.096392   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="pending"
I0914 23:14:59.098161   18760 awsmachine_controller.go:523]  "msg"="EC2 instance state changed" "instance-id"="myMachine" "state"="running"
I0914 23:14:59.098308   18760 awsmachine_controller.go:773]  "msg"="Deleting unneeded entry from AWS S3" "secretPrefix"=""
... skipping 18 lines ...
I0914 23:14:59.121552   18760 awsmachine_controller.go:390]  "msg"="EC2 instance successfully terminated" "instance-id"="myMachine"
I0914 23:14:59.122025   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.122241   18760 awsmachine_controller.go:773]  "msg"="Deleting unneeded entry from AWS S3" "secretPrefix"=""
I0914 23:14:59.122325   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="myMachine"
I0914 23:14:59.123744   18760 awsmachine_controller.go:390]  "msg"="EC2 instance successfully terminated" "instance-id"="myMachine"
I0914 23:14:59.126001   18760 awsmachine_controller.go:636]  "msg"="Creating EC2 instance" 
E0914 23:14:59.126227   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: creating userdata object: connection error" 
E0914 23:14:59.126227   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: creating userdata object: connection error" 
E0914 23:14:59.126227   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: creating userdata object: connection error" 
E0914 23:14:59.126227   18760 awsmachine_controller.go:497]  "msg"="unable to create instance" "error"="failed to resolve userdata: creating userdata object: connection error" 
I0914 23:14:59.126821   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.126908   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="prefix"
E0914 23:14:59.127057   18760 awsmachine_controller.go:298]  "msg"="query to find instance failed" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:59.127057   18760 awsmachine_controller.go:298]  "msg"="query to find instance failed" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:59.127057   18760 awsmachine_controller.go:298]  "msg"="query to find instance failed" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:59.127057   18760 awsmachine_controller.go:298]  "msg"="query to find instance failed" "error"="failed to query AWSMachine instance by tags: no connection available " 
E0914 23:14:59.140756   18760 awsmachine_controller.go:354]  "msg"="failed to terminate instance" "error"="can't reach AWS to terminate machine" 
I0914 23:14:59.151726   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.151799   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="prefix"
I0914 23:14:59.151943   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="aws:////myid"
E0914 23:14:59.153526   18760 awsmachine_controller.go:365]  "msg"="failed to get core security groups to detach from instance's network interfaces" "error"="can't reach AWS to list security groups" 
E0914 23:14:59.153526   18760 awsmachine_controller.go:365]  "msg"="failed to get core security groups to detach from instance's network interfaces" "error"="can't reach AWS to list security groups" 
E0914 23:14:59.153526   18760 awsmachine_controller.go:365]  "msg"="failed to get core security groups to detach from instance's network interfaces" "error"="can't reach AWS to list security groups" 
E0914 23:14:59.153526   18760 awsmachine_controller.go:365]  "msg"="failed to get core security groups to detach from instance's network interfaces" "error"="can't reach AWS to list security groups" 
I0914 23:14:59.153980   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.154020   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="prefix"
I0914 23:14:59.154120   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="aws:////myid"
E0914 23:14:59.157137   18760 awsmachine_controller.go:382]  "msg"="failed to detach security groups from instance's network interfaces" "error"="can't reach AWS to detach security group" 
E0914 23:14:59.157137   18760 awsmachine_controller.go:382]  "msg"="failed to detach security groups from instance's network interfaces" "error"="can't reach AWS to detach security group" 
E0914 23:14:59.157137   18760 awsmachine_controller.go:382]  "msg"="failed to detach security groups from instance's network interfaces" "error"="can't reach AWS to detach security group" 
E0914 23:14:59.157137   18760 awsmachine_controller.go:382]  "msg"="failed to detach security groups from instance's network interfaces" "error"="can't reach AWS to detach security group" 
I0914 23:14:59.157561   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.157600   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="prefix"
I0914 23:14:59.157694   18760 awsmachine_controller.go:344]  "msg"="Terminating EC2 instance" "instance-id"="aws:////myid"
I0914 23:14:59.161291   18760 awsmachine_controller.go:390]  "msg"="EC2 instance successfully terminated" "instance-id"="aws:////myid"
I0914 23:14:59.161734   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.161775   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="prefix"
... skipping 3 lines ...
I0914 23:14:59.164225   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.164287   18760 awsmachine_controller.go:342]  "msg"="EC2 instance is shutting down or already terminated" "instance-id"=""
I0914 23:14:59.164604   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.164658   18760 awsmachine_controller.go:342]  "msg"="EC2 instance is shutting down or already terminated" "instance-id"=""
I0914 23:14:59.164956   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.165315   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
E0914 23:14:59.165356   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="secretPrefix present, but secretCount is not set" 
E0914 23:14:59.165356   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="secretPrefix present, but secretCount is not set" 
E0914 23:14:59.165356   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="secretPrefix present, but secretCount is not set" 
E0914 23:14:59.165356   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="secretPrefix present, but secretCount is not set" 
I0914 23:14:59.165584   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
E0914 23:14:59.165622   18760 awsmachine_controller.go:605]  "msg"="unable to get secret service backend" "error"="invalid secret backend" 
E0914 23:14:59.165622   18760 awsmachine_controller.go:605]  "msg"="unable to get secret service backend" "error"="invalid secret backend" 
E0914 23:14:59.165622   18760 awsmachine_controller.go:605]  "msg"="unable to get secret service backend" "error"="invalid secret backend" 
E0914 23:14:59.165622   18760 awsmachine_controller.go:605]  "msg"="unable to get secret service backend" "error"="invalid secret backend" 
E0914 23:14:59.165650   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="invalid secret backend" 
E0914 23:14:59.165650   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="invalid secret backend" 
E0914 23:14:59.165650   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="invalid secret backend" 
E0914 23:14:59.165650   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="invalid secret backend" 
I0914 23:14:59.165910   18760 awsmachine_controller.go:287]  "msg"="Handling deleted AWSMachine" 
I0914 23:14:59.165942   18760 awsmachine_controller.go:621]  "msg"="Deleting unneeded entry from AWS Secret" "secretPrefix"="test"
I0914 23:14:59.165967   18760 awsmachine_controller.go:623]  "msg"="Unable to delete entries from AWS Secret containing encrypted userdata" "secretPrefix"="test"
E0914 23:14:59.165993   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="Hierarchy Type Mismatch Exception" 
E0914 23:14:59.165993   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="Hierarchy Type Mismatch Exception" 
E0914 23:14:59.165993   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="Hierarchy Type Mismatch Exception" 
E0914 23:14:59.165993   18760 awsmachine_controller.go:292]  "msg"="unable to delete machine" "error"="Hierarchy Type Mismatch Exception" 
E0914 23:14:59.850631   18760 awsmachine_controller.go:975]  "msg"="expected an AWSMachine" "error"="incorrect type" "type"="*v1beta1.Machine"
E0914 23:14:59.850631   18760 awsmachine_controller.go:975]  "msg"="expected an AWSMachine" "error"="incorrect type" "type"="*v1beta1.Machine"
E0914 23:14:59.850631   18760 awsmachine_controller.go:975]  "msg"="expected an AWSMachine" "error"="incorrect type" "type"="*v1beta1.Machine"
E0914 23:14:59.850631   18760 awsmachine_controller.go:975]  "msg"="expected an AWSMachine" "error"="incorrect type" "type"="*v1beta1.Machine"
I0914 23:14:59.875105   18760 http.go:96] controller-runtime/webhook/webhooks "msg"="received request" "UID"="781253e2-ceb1-4b25-b4a7-2989adf3cd92" "kind"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","kind":"AWSMachine"} "resource"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","resource":"awsmachines"} "webhook"="/mutate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:14:59.875432   18760 http.go:143] controller-runtime/webhook/webhooks "msg"="wrote response" "UID"="781253e2-ceb1-4b25-b4a7-2989adf3cd92" "allowed"=true "code"=200 "reason"="" "webhook"="/mutate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:14:59.879343   18760 http.go:96] controller-runtime/webhook/webhooks "msg"="received request" "UID"="e3666bea-b3fe-466a-86fb-d79d09d36063" "kind"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","kind":"AWSMachine"} "resource"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","resource":"awsmachines"} "webhook"="/validate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:14:59.879612   18760 http.go:143] controller-runtime/webhook/webhooks "msg"="wrote response" "UID"="e3666bea-b3fe-466a-86fb-d79d09d36063" "allowed"=true "code"=200 "reason"="" "webhook"="/validate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:14:59.886105   18760 awsmachine_controller.go:160]  "msg"="Machine Controller has not yet set OwnerRef" 
I0914 23:14:59.907558   18760 http.go:96] controller-runtime/webhook/webhooks "msg"="received request" "UID"="5ea13ab0-9ef2-4e28-9746-fae223cdb185" "kind"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","kind":"AWSMachine"} "resource"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","resource":"awsmachines"} "webhook"="/mutate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
... skipping 26 lines ...
I0914 23:15:00.205033   18760 http.go:143] controller-runtime/webhook/webhooks "msg"="wrote response" "UID"="bd444b0a-00d8-430d-a464-8162c19c2f78" "allowed"=true "code"=200 "reason"="" "webhook"="/validate-infrastructure-cluster-x-k8s-io-v1beta1-awscluster"
I0914 23:15:00.215748   18760 http.go:96] controller-runtime/webhook/webhooks "msg"="received request" "UID"="cf474d43-e10b-4f93-8227-de60dbb9d1f3" "kind"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","kind":"AWSMachine"} "resource"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","resource":"awsmachines"} "webhook"="/mutate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:15:00.216104   18760 http.go:143] controller-runtime/webhook/webhooks "msg"="wrote response" "UID"="cf474d43-e10b-4f93-8227-de60dbb9d1f3" "allowed"=true "code"=200 "reason"="" "webhook"="/mutate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:15:00.218428   18760 http.go:96] controller-runtime/webhook/webhooks "msg"="received request" "UID"="33a9ccbc-55a3-410a-b114-bc1e24b56ead" "kind"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","kind":"AWSMachine"} "resource"={"group":"infrastructure.cluster.x-k8s.io","version":"v1beta1","resource":"awsmachines"} "webhook"="/validate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:15:00.218629   18760 http.go:143] controller-runtime/webhook/webhooks "msg"="wrote response" "UID"="33a9ccbc-55a3-410a-b114-bc1e24b56ead" "allowed"=true "code"=200 "reason"="" "webhook"="/validate-infrastructure-cluster-x-k8s-io-v1beta1-awsmachine"
I0914 23:15:00.222710   18760 awsmachine_controller.go:185]  "msg"="AWSCluster or AWSManagedControlPlane is not ready yet" "cluster"="capi-test-1" "machine"="capi-test-machine"
--- FAIL: TestAWSMachineReconciler_Reconcile (0.40s)
    --- FAIL: TestAWSMachineReconciler_Reconcile/Should_fail_to_reconcile_while_fetching_infra_cluster (0.06s)
        awsmachine_controller_unit_test.go:2344: 
            Expected
                <nil>: nil
            not to be nil
FAIL
coverage: 79.9% of statements
I0914 23:15:00.254598   18760 logr.go:261]  "msg"="Stopping and waiting for non leader election runnables" 
I0914 23:15:00.254647   18760 logr.go:261]  "msg"="Stopping and waiting for leader election runnables" 
I0914 23:15:00.254666   18760 logr.go:261]  "msg"="Stopping and waiting for caches" 
I0914 23:15:00.254911   18760 logr.go:261]  "msg"="Stopping and waiting for webhooks" 
I0914 23:15:00.255138   18760 logr.go:261] controller-runtime/webhook "msg"="shutting down webhook server" 
I0914 23:15:00.255207   18760 logr.go:261] controller-runtime/certwatcher "msg"="certificate event" "event"={"Name":"/tmp/envtest-serving-certs-1592632511/tls.key","Op":4}
E0914 23:15:00.255246   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-watching file" "error"="inotify instance already closed" 
E0914 23:15:00.255246   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-watching file" "error"="inotify instance already closed" 
E0914 23:15:00.255246   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-watching file" "error"="inotify instance already closed" 
E0914 23:15:00.255246   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-watching file" "error"="inotify instance already closed" 
E0914 23:15:00.255542   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-reading certificate" "error"="open /tmp/envtest-serving-certs-1592632511/tls.key: no such file or directory" 
E0914 23:15:00.255542   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-reading certificate" "error"="open /tmp/envtest-serving-certs-1592632511/tls.key: no such file or directory" 
E0914 23:15:00.255542   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-reading certificate" "error"="open /tmp/envtest-serving-certs-1592632511/tls.key: no such file or directory" 
E0914 23:15:00.255542   18760 logr.go:279] controller-runtime/certwatcher "msg"="error re-reading certificate" "error"="open /tmp/envtest-serving-certs-1592632511/tls.key: no such file or directory" 
I0914 23:15:00.256454   18760 logr.go:261]  "msg"="Wait completed, proceeding to shutdown the manager" 
FAIL	sigs.k8s.io/cluster-api-provider-aws/controllers	16.775s
ok  	sigs.k8s.io/cluster-api-provider-aws/controlplane/eks/api/v1alpha3	55.560s	coverage: 28.4% of statements
ok  	sigs.k8s.io/cluster-api-provider-aws/controlplane/eks/api/v1alpha4	54.038s	coverage: 26.8% of statements
ok  	sigs.k8s.io/cluster-api-provider-aws/controlplane/eks/api/v1beta1	14.635s	coverage: 35.8% of statements
ok  	sigs.k8s.io/cluster-api-provider-aws/controlplane/eks/controllers	0.093s	coverage: 3.4% of statements
?   	sigs.k8s.io/cluster-api-provider-aws/docs/book/cmd/amilist	[no test files]
?   	sigs.k8s.io/cluster-api-provider-aws/docs/book/cmd/clusterawsadmdocs	[no test files]
... skipping 64 lines ...
?   	sigs.k8s.io/cluster-api-provider-aws/test/helpers	[no test files]
?   	sigs.k8s.io/cluster-api-provider-aws/test/helpers/external	[no test files]
?   	sigs.k8s.io/cluster-api-provider-aws/test/mocks	[no test files]
?   	sigs.k8s.io/cluster-api-provider-aws/util/conditions	[no test files]
ok  	sigs.k8s.io/cluster-api-provider-aws/util/system	0.007s	coverage: 80.0% of statements
?   	sigs.k8s.io/cluster-api-provider-aws/version	[no test files]
FAIL
make: *** [Makefile:442: test-cover] Error 1
go: downloading github.com/spf13/cobra v1.4.0
go: downloading golang.org/x/tools v0.1.11
+ EXIT_VALUE=2
+ set +o xtrace