kubernetes-sigs/cluster-api-provider-aws PR #3688 pull-cluster-api-provider-aws-test #1608528307249221632
Recent runs || View in Spyglass
Recent runs || View in Spyglass
| PR | luthermonson: Allow for Self-Managed VPC with a Secondary Subnet for Pods |
| Result | FAILURE |
| Tests | 0 failed / 0 succeeded |
| Started | |
| Elapsed | 6m35s |
| Revision | 4013e3dad6c3a593e3adb8c6cacc1d79fde8b02d |
| Refs |
3688 |
... skipping 331 lines ... --- PASS: TestAWSClusterDefault/for_AWSCluster (0.00s) --- PASS: TestAWSClusterDefault/for_AWSCluster/validate-on-create (0.00s) --- PASS: TestAWSClusterDefault/for_AWSCluster/validate-on-update (0.00s) --- PASS: TestAWSClusterDefault/for_AWSCluster/validate-on-delete (0.00s) === RUN TestAWSCluster_ValidateCreate === RUN TestAWSCluster_ValidateCreate/Supported_schemes_are_'internet-facing,_Internet-facing,_internal,_or_nil',_rest_will_be_rejected 2022/12/29 18:25:38 http: TLS handshake error from 127.0.0.1:47858: EOF === RUN TestAWSCluster_ValidateCreate/Invalid_tags_are_rejected === RUN TestAWSCluster_ValidateCreate/accepts_bucket_name_with_acceptable_characters === RUN TestAWSCluster_ValidateCreate/rejects_empty_bucket_name === RUN TestAWSCluster_ValidateCreate/rejects_bucket_name_shorter_than_3_characters === RUN TestAWSCluster_ValidateCreate/rejects_bucket_name_longer_than_63_characters === RUN TestAWSCluster_ValidateCreate/rejects_bucket_name_starting_with_not_letter_or_number ... skipping 267 lines ... ==================================================================================================== Random Seed: [1m1672338327[0m Will run [1m0[0m of [1m0[0m specs [38;5;10m[1mRan 0 of 0 Specs in 0.000 seconds[0m [38;5;10m[1mSUCCESS![0m -- [38;5;10m[1m0 Passed[0m | [38;5;9m[1m0 Failed[0m | [38;5;11m[1m0 Pending[0m | [38;5;14m[1m0 Skipped[0m --- PASS: TestAPIs (0.00s) === RUN TestTags_Merge === RUN TestTags_Merge/nil_other === RUN TestTags_Merge/empty_other === RUN TestTags_Merge/disjoint === RUN TestTags_Merge/overlapping,_other_wins ... skipping 41 lines ... --- PASS: TestTags_Validate/value_has_wrong_characters (0.00s) --- PASS: TestTags_Validate/value_and_key_both_has_wrong_characters (0.00s) PASS I1229 18:25:41.699645 21220 internal.go:571] "Stopping and waiting for non leader election runnables" I1229 18:25:41.699683 21220 internal.go:575] "Stopping and waiting for leader election runnables" I1229 18:25:41.699697 21220 internal.go:581] "Stopping and waiting for caches" E1229 18:25:41.699698 21220 certwatcher.go:147] "controller-runtime/certwatcher: error re-watching file" err="no such file or directory" E1229 18:25:41.699774 21220 certwatcher.go:152] "controller-runtime/certwatcher: error re-reading certificate" err="open /tmp/envtest-serving-certs-133663776/tls.crt: no such file or directory" E1229 18:25:41.699805 21220 certwatcher.go:147] "controller-runtime/certwatcher: error re-watching file" err="no such file or directory" E1229 18:25:41.699823 21220 certwatcher.go:152] "controller-runtime/certwatcher: error re-reading certificate" err="open /tmp/envtest-serving-certs-133663776/tls.crt: no such file or directory" I1229 18:25:41.699925 21220 internal.go:585] "Stopping and waiting for webhooks" I1229 18:25:41.700084 21220 server.go:277] "controller-runtime/webhook: shutting down webhook server" I1229 18:25:41.700212 21220 internal.go:589] "Wait completed, proceeding to shutdown the manager" ok sigs.k8s.io/cluster-api-provider-aws/v2/api/v1beta2 15.796s === RUN TestFuzzyConversion === RUN TestFuzzyConversion/for_EKSConfig ... skipping 714 lines ... I1229 18:26:01.542485 23908 server.go:148] "controller-runtime/webhook: Registering webhook" path="/validate-infrastructure-cluster-x-k8s-io-v1beta2-awsclustercontrolleridentity" Starting the manager I1229 18:26:01.542761 23908 server.go:216] "controller-runtime/webhook/webhooks: Starting webhook server" I1229 18:26:01.543234 23908 certwatcher.go:131] "controller-runtime/certwatcher: Updated current TLS certificate" I1229 18:26:01.543449 23908 certwatcher.go:85] "controller-runtime/certwatcher: Starting certificate watcher" I1229 18:26:01.543599 23908 server.go:270] "controller-runtime/webhook: Serving webhook server" host="" port=41639 2022/12/29 18:26:02 http: TLS handshake error from 127.0.0.1:48756: EOF === RUN TestAWSClusterReconciler_IntegrationTests === RUN TestAWSClusterReconciler_IntegrationTests/Should_successfully_reconcile_AWSCluster_creation_with_unmanaged_VPC I1229 18:26:03.210955 23908 logger.go:67] "Reconciling AWSCluster" I1229 18:26:03.304607 23908 logger.go:67] "Reconciling subnets" I1229 18:26:03.305230 23908 logger.go:67] "Created security group for role" security-group="sg-bastion" role=bastion I1229 18:26:03.305401 23908 logger.go:67] "Created security group for role" security-group="sg-apiserver-lb" role=apiserver-lb ... skipping 6 lines ... I1229 18:26:03.469036 23908 logger.go:67] "Reconciling subnets" I1229 18:26:03.469370 23908 logger.go:67] "Created security group for role" security-group="sg-bastion" role=bastion I1229 18:26:03.469450 23908 logger.go:67] "Created security group for role" security-group="sg-apiserver-lb" role=apiserver-lb I1229 18:26:03.469492 23908 logger.go:67] "Created security group for role" security-group="sg-lb" role=lb I1229 18:26:03.469528 23908 logger.go:67] "Created security group for role" security-group="sg-controlplane" role=controlplane I1229 18:26:03.469568 23908 logger.go:67] "Created security group for role" security-group="sg-node" role=node E1229 18:26:03.536438 23908 logger.go:83] "failed to get IP address for dns name" err="lookup dns on 10.63.240.10:53: no such host" dns="dns" I1229 18:26:03.536561 23908 logger.go:67] "Waiting on API server ELB DNS name to resolve" === RUN TestAWSClusterReconciler_IntegrationTests/Should_fail_on_AWSCluster_reconciliation_if_VPC_limit_exceeded I1229 18:26:03.629510 23908 logger.go:67] "Reconciling AWSCluster" E1229 18:26:03.696822 23908 logger.go:83] "failed to reconcile network" err="failed to create new vpc: failed to create vpc: The maximum number of VPCs has been reached" I1229 18:26:03.696886 23908 logger.go:67] "Reconciling AWSCluster delete" I1229 18:26:03.744974 23908 logger.go:67] "Deleted AWS cloud provider load balancers" I1229 18:26:03.745041 23908 logger.go:67] "Deleted control plane load balancer" name="test-cluster-apiserver" I1229 18:26:03.812881 23908 logger.go:67] "Successfully deleted all associated ClassicELBListeners" I1229 18:26:03.812939 23908 logger.go:67] "Successfully deleted all associated Target Groups" I1229 18:26:03.812966 23908 logger.go:67] "Deleted AWS cloud provider load balancers" I1229 18:26:03.813003 23908 logger.go:67] "Deleted control plane load balancer" name="-test-cluster-apiserver" I1229 18:26:04.013197 23908 logger.go:67] "Deleted bastion host" id="id-1" E1229 18:26:04.013249 23908 logger.go:83] "non-fatal: VPC ID is missing, " I1229 18:26:04.378400 23908 logger.go:67] "Deleted VPC" vpc-id="" === RUN TestAWSClusterReconciler_IntegrationTests/Should_successfully_delete_AWSCluster_with_managed_VPC I1229 18:26:04.500253 23908 logger.go:67] "Reconciling AWSCluster delete" I1229 18:26:04.526067 23908 logger.go:67] "Deleted AWS cloud provider load balancers" I1229 18:26:04.526136 23908 logger.go:67] "Deleted control plane load balancer" name="test-cluster-apiserver" I1229 18:26:04.561556 23908 logger.go:67] "Successfully deleted all associated ClassicELBListeners" ... skipping 26 lines ... === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_success === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_success/Should_successfully_create_AWSCluster_with_Cluster_Finalizer_and_LoadBalancerReady_status_true_on_AWSCluster I1229 18:26:05.447554 23908 logger.go:67] "Reconciling AWSCluster" === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_reconcile_network_failure I1229 18:26:05.477875 23908 logger.go:67] "Reconciling AWSCluster" E1229 18:26:05.478762 23908 logger.go:83] "failed to reconcile network" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_ClusterSecurityGroupsReadyCondition_status_false I1229 18:26:05.479100 23908 logger.go:67] "Reconciling AWSCluster" E1229 18:26:05.479847 23908 logger.go:83] "failed to reconcile security groups" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_BastionHostReadyCondition_status_false I1229 18:26:05.480106 23908 logger.go:67] "Reconciling AWSCluster" E1229 18:26:05.480934 23908 logger.go:83] "failed to reconcile bastion host" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_failure_in_LoadBalancer_reconciliation I1229 18:26:05.481279 23908 logger.go:67] "Reconciling AWSCluster" E1229 18:26:05.482023 23908 logger.go:83] "failed to reconcile load balancer" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_LoadBalancer_reconcile_failure_with_WaitForDNSName_condition_as_false I1229 18:26:05.482342 23908 logger.go:67] "Reconciling AWSCluster" I1229 18:26:05.483117 23908 logger.go:67] "Waiting on API server ELB DNS name" === RUN TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_LoadBalancer_reconcile_failure_with_WaitForDNSNameResolve_condition_as_false I1229 18:26:05.483470 23908 logger.go:67] "Reconciling AWSCluster" E1229 18:26:05.533343 23908 logger.go:83] "failed to get IP address for dns name" err="lookup test-apiserver.us-east-1.aws on 10.63.240.10:53: no such host" dns="test-apiserver.us-east-1.aws" I1229 18:26:05.533398 23908 logger.go:67] "Waiting on API server ELB DNS name to resolve" === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_success === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_success/Should_successfully_delete_AWSCluster_with_Cluster_Finalizer_removed I1229 18:26:05.533978 23908 logger.go:67] "Reconciling AWSCluster delete" === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_failure === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_delete_with_LoadBalancer_deletion_failed_and_Cluster_Finalizer_not_removed I1229 18:26:05.534396 23908 logger.go:67] "Reconciling AWSCluster delete" E1229 18:26:05.534475 23908 logger.go:83] "error deleting load balancer" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_delete_with_Bastion_deletion_failed_and_Cluster_Finalizer_not_removed I1229 18:26:05.534683 23908 logger.go:67] "Reconciling AWSCluster delete" E1229 18:26:05.534766 23908 logger.go:83] "error deleting bastion" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_delete_with_security_group_deletion_failed_and_Cluster_Finalizer_not_removed I1229 18:26:05.534972 23908 logger.go:67] "Reconciling AWSCluster delete" E1229 18:26:05.535053 23908 logger.go:83] "error deleting security groups" err="failed to get resource" === RUN TestAWSClusterReconcileOperations/Reconcile_delete_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_delete_with_network_deletion_failed_and_Cluster_Finalizer_not_removed I1229 18:26:05.535229 23908 logger.go:67] "Reconciling AWSCluster delete" E1229 18:26:05.535334 23908 logger.go:83] "error deleting network" err="failed to get resource" --- PASS: TestAWSClusterReconcileOperations (0.09s) --- PASS: TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster (0.09s) --- PASS: TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_success (0.03s) --- PASS: TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_success/Should_successfully_create_AWSCluster_with_Cluster_Finalizer_and_LoadBalancerReady_status_true_on_AWSCluster (0.03s) --- PASS: TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure (0.06s) --- PASS: TestAWSClusterReconcileOperations/Reconciling_an_AWSCluster/Reconcile_failure/Should_fail_AWSCluster_create_with_reconcile_network_failure (0.00s) ... skipping 37 lines ... === RUN TestAWSMachineReconciler_IntegrationTests/Should_successfully_reconcile_control_plane_machine_deletion I1229 18:26:06.398358 23908 logger.go:67] "Handling deleted AWSMachine" I1229 18:26:06.398809 23908 logger.go:67] "Terminating EC2 instance" instance-id="id-1" I1229 18:26:06.459160 23908 logger.go:67] "EC2 instance successfully terminated" instance-id="id-1" === RUN TestAWSMachineReconciler_IntegrationTests/Should_fail_reconciling_control-plane_machine_creation_while_attaching_load_balancer I1229 18:26:06.616064 23908 logger.go:67] "Creating EC2 instance" E1229 18:26:06.658766 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"two\" with load balancer - error determining registration status: expected 1 ELB description for \"test-cluster-apiserver\", got 0" === RUN TestAWSMachineReconciler_IntegrationTests/Should_fail_in_reconciling_control-plane_machine_deletion_while_terminating_instance_ I1229 18:26:06.726843 23908 logger.go:67] "Handling deleted AWSMachine" I1229 18:26:06.727153 23908 logger.go:67] "Terminating EC2 instance" instance-id="id-1" E1229 18:26:06.779523 23908 logger.go:83] "failed to terminate instance" err="failed to terminate instance with id \"id-1\": Failed to delete instance" --- PASS: TestAWSMachineReconciler_IntegrationTests (1.11s) --- PASS: TestAWSMachineReconciler_IntegrationTests/Should_successfully_reconcile_control_plane_machine_creation (0.67s) --- PASS: TestAWSMachineReconciler_IntegrationTests/Should_successfully_reconcile_control_plane_machine_deletion (0.13s) --- PASS: TestAWSMachineReconciler_IntegrationTests/Should_fail_reconciling_control-plane_machine_creation_while_attaching_load_balancer (0.21s) --- PASS: TestAWSMachineReconciler_IntegrationTests/Should_fail_in_reconciling_control-plane_machine_deletion_while_terminating_instance_ (0.10s) === RUN TestAWSMachineReconciler === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_can't_reach_amazon === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_can't_reach_amazon/should_exit_immediately_on_an_error_state === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_can't_reach_amazon/should_exit_immediately_if_cluster_infra_isn't_ready === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_can't_reach_amazon/should_exit_immediately_if_bootstrap_data_secret_reference_isn't_available === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_can't_reach_amazon/should_return_an_error_when_we_can't_list_instances_by_tags E1229 18:26:06.807500 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:06.807500 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:06.807500 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:06.807500 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_can't_reach_amazon/shouldn't_add_our_finalizer_to_the_machine E1229 18:26:06.808461 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:06.808461 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:06.808461 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:06.808461 23908 logger.go:83] "unable to find instance" err="failed to query AWSMachine instance by tags: no connection available " === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_provider_ID_is_populated_correctly === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_provider_ID_is_populated_correctly/should_look_up_by_provider_ID_when_one_exists E1229 18:26:06.809501 23908 logger.go:83] "unable to find instance" err="no connection available " E1229 18:26:06.809501 23908 logger.go:83] "unable to find instance" err="no connection available " E1229 18:26:06.809501 23908 logger.go:83] "unable to find instance" err="no connection available " E1229 18:26:06.809501 23908 logger.go:83] "unable to find instance" err="no connection available " === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_provider_ID_is_populated_correctly/should_fail_to_create_instance_and_keep_the_finalizers_as_is I1229 18:26:06.815776 23908 logger.go:67] "Creating EC2 instance" E1229 18:26:06.820006 23908 logger.go:83] "unable to create instance" err="failed to create AWSMachine instance: Invalid instance" E1229 18:26:06.820006 23908 logger.go:83] "unable to create instance" err="failed to create AWSMachine instance: Invalid instance" E1229 18:26:06.820006 23908 logger.go:83] "unable to create instance" err="failed to create AWSMachine instance: Invalid instance" E1229 18:26:06.820006 23908 logger.go:83] "unable to create instance" err="failed to create AWSMachine instance: Invalid instance" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/should_fail_to_find_instance_if_no_provider_ID_provided E1229 18:26:06.821218 23908 logger.go:83] "unable to find instance" err="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" E1229 18:26:06.821218 23908 logger.go:83] "unable to find instance" err="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" E1229 18:26:06.821218 23908 logger.go:83] "unable to find instance" err="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" E1229 18:26:06.821218 23908 logger.go:83] "unable to find instance" err="failed to parse Spec.ProviderID: providerID must be of the form <cloudProvider>://<optional>/<segments>/<provider id>" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_succeeds === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_succeeds/instance_security_group_errors === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_succeeds/instance_security_group_errors/should_set_attributes_after_creating_an_instance E1229 18:26:06.832155 23908 logger.go:83] "unable to get instance security groups" err="stop here" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_succeeds/instance_security_group_errors/should_set_instance_to_pending E1229 18:26:06.847131 23908 logger.go:83] "unable to get instance security groups" err="stop here" ... skipping 40 lines ... I1229 18:26:07.089180 23908 logger.go:71] "done reconciling instance" instance=&{ID:myMachine State:pending Type: SubnetID: ImageID: SSHKeyName:<nil> SecurityGroupIDs:[] UserData:<nil> IAMProfile: Addresses:[] PrivateIP:<nil> PublicIP:<nil> ENASupport:<nil> EBSOptimized:<nil> RootVolume:<nil> NonRootVolumes:[] NetworkInterfaces:[] Tags:map[] AvailabilityZone: SpotMarketOptions:<nil> Tenancy: VolumeIDs:[volume-1 volume-2]} === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_succeeds/should_fail_to_delete_bootstrap_data_secret_if_AWSMachine_state_is_updated I1229 18:26:07.097127 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.107376 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" I1229 18:26:07.107569 23908 logger.go:67] "Deleting unneeded entry from AWS Secret" secretPrefix="test" I1229 18:26:07.107713 23908 logger.go:67] "Unable to delete entries from AWS Secret containing encrypted userdata" secretPrefix="test" E1229 18:26:07.107804 23908 awsmachine_controller.go:552] "unable to delete secrets" err="failed to delete entries from AWS Secret" E1229 18:26:07.107804 23908 awsmachine_controller.go:552] "unable to delete secrets" err="failed to delete entries from AWS Secret" E1229 18:26:07.107804 23908 awsmachine_controller.go:552] "unable to delete secrets" err="failed to delete entries from AWS Secret" E1229 18:26:07.107804 23908 awsmachine_controller.go:552] "unable to delete secrets" err="failed to delete entries from AWS Secret" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/Should_fail_while_getting_userdata I1229 18:26:07.115869 23908 logger.go:67] "Creating EC2 instance" E1229 18:26:07.134795 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: failed to generate init script" E1229 18:26:07.134795 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: failed to generate init script" E1229 18:26:07.134795 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: failed to generate init script" E1229 18:26:07.134795 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: failed to generate init script" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/should_fail_to_determine_the_registration_status_of_control_plane_ELB I1229 18:26:07.138977 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.158792 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" I1229 18:26:07.177946 23908 logger.go:71] "registering to classic load balancer" E1229 18:26:07.178174 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" E1229 18:26:07.178174 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" E1229 18:26:07.178174 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" E1229 18:26:07.178174 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer - error determining registration status: error describing ELB" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/should_fail_to_attach_control_plane_ELB_to_instance I1229 18:26:07.183836 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.202749 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" I1229 18:26:07.203052 23908 logger.go:71] "registering to classic load balancer" E1229 18:26:07.203337 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" E1229 18:26:07.203337 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" E1229 18:26:07.203337 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" E1229 18:26:07.203337 23908 logger.go:83] "failed to reconcile LB attachment" err="could not register control plane instance \"myMachine\" with load balancer: failed to attach ELB" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/should_fail_to_delete_bootstrap_data_secret_if_AWSMachine_is_in_failed_state I1229 18:26:07.204183 23908 logger.go:67] "Error state detected, skipping reconciliation" E1229 18:26:07.204375 23908 logger.go:83] "unable to reconcile machine" err="secretPrefix present, but secretCount is not set" E1229 18:26:07.204375 23908 logger.go:83] "unable to reconcile machine" err="secretPrefix present, but secretCount is not set" E1229 18:26:07.204375 23908 logger.go:83] "unable to reconcile machine" err="secretPrefix present, but secretCount is not set" E1229 18:26:07.204375 23908 logger.go:83] "unable to reconcile machine" err="secretPrefix present, but secretCount is not set" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/Should_fail_in_ensureTag === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/Should_fail_in_ensureTag/Should_fail_to_return_machine_annotations_after_instance_is_created I1229 18:26:07.207802 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.210388 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" E1229 18:26:07.210597 23908 logger.go:83] "failed to ensure tags" err="json: cannot unmarshal number into Go value of type map[string]interface {}" E1229 18:26:07.210597 23908 logger.go:83] "failed to ensure tags" err="json: cannot unmarshal number into Go value of type map[string]interface {}" E1229 18:26:07.210597 23908 logger.go:83] "failed to ensure tags" err="json: cannot unmarshal number into Go value of type map[string]interface {}" E1229 18:26:07.210597 23908 logger.go:83] "failed to ensure tags" err="json: cannot unmarshal number into Go value of type map[string]interface {}" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/Should_fail_in_ensureTag/Should_fail_to_update_resource_tags_after_instance_is_created I1229 18:26:07.213908 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.217026 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" E1229 18:26:07.217307 23908 logger.go:83] "failed to ensure tags" err="failed to update resource tag" E1229 18:26:07.217307 23908 logger.go:83] "failed to ensure tags" err="failed to update resource tag" E1229 18:26:07.217307 23908 logger.go:83] "failed to ensure tags" err="failed to update resource tag" E1229 18:26:07.217307 23908 logger.go:83] "failed to ensure tags" err="failed to update resource tag" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/While_ensuring_SecurityGroups === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/While_ensuring_SecurityGroups/Should_fail_to_return_machine_annotations I1229 18:26:07.221291 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.224112 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" E1229 18:26:07.224373 23908 logger.go:83] "unable to ensure security groups" err="json: cannot unmarshal number into Go value of type map[string]interface {}" E1229 18:26:07.224373 23908 logger.go:83] "unable to ensure security groups" err="json: cannot unmarshal number into Go value of type map[string]interface {}" E1229 18:26:07.224373 23908 logger.go:83] "unable to ensure security groups" err="json: cannot unmarshal number into Go value of type map[string]interface {}" E1229 18:26:07.224373 23908 logger.go:83] "unable to ensure security groups" err="json: cannot unmarshal number into Go value of type map[string]interface {}" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/While_ensuring_SecurityGroups/Should_fail_to_fetch_core_security_groups I1229 18:26:07.227580 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.230206 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" E1229 18:26:07.230456 23908 logger.go:83] "unable to ensure security groups" err="failed to get core security groups" E1229 18:26:07.230456 23908 logger.go:83] "unable to ensure security groups" err="failed to get core security groups" E1229 18:26:07.230456 23908 logger.go:83] "unable to ensure security groups" err="failed to get core security groups" E1229 18:26:07.230456 23908 logger.go:83] "unable to ensure security groups" err="failed to get core security groups" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/While_ensuring_SecurityGroups/Should_fail_if_ensureSecurityGroups_fails_to_fetch_additional_security_groups I1229 18:26:07.234422 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.239549 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" E1229 18:26:07.239862 23908 logger.go:83] "unable to ensure security groups" err="failed to get filtered SGs" E1229 18:26:07.239862 23908 logger.go:83] "unable to ensure security groups" err="failed to get filtered SGs" E1229 18:26:07.239862 23908 logger.go:83] "unable to ensure security groups" err="failed to get filtered SGs" E1229 18:26:07.239862 23908 logger.go:83] "unable to ensure security groups" err="failed to get filtered SGs" === RUN TestAWSMachineReconciler/Reconciling_an_AWSMachine/when_instance_creation_fails/While_ensuring_SecurityGroups/Should_fail_to_update_security_group I1229 18:26:07.243942 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.247721 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" E1229 18:26:07.248047 23908 logger.go:83] "unable to ensure security groups" err="failed to update security groups" E1229 18:26:07.248047 23908 logger.go:83] "unable to ensure security groups" err="failed to update security groups" E1229 18:26:07.248047 23908 logger.go:83] "unable to ensure security groups" err="failed to update security groups" E1229 18:26:07.248047 23908 logger.go:83] "unable to ensure security groups" err="failed to update security groups" === RUN TestAWSMachineReconciler/Secrets_management_lifecycle === RUN TestAWSMachineReconciler/Secrets_management_lifecycle/Secrets_management_lifecycle_when_creating_EC2_instances === RUN TestAWSMachineReconciler/Secrets_management_lifecycle/Secrets_management_lifecycle_when_creating_EC2_instances/should_leverage_AWS_Secrets_Manager I1229 18:26:07.251846 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.269620 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" I1229 18:26:07.269734 23908 logger.go:71] "done reconciling instance" instance=&{ID:myMachine State:pending Type: SubnetID: ImageID: SSHKeyName:<nil> SecurityGroupIDs:[] UserData:<nil> IAMProfile: Addresses:[] PrivateIP:<nil> PublicIP:<nil> ENASupport:<nil> EBSOptimized:<nil> RootVolume:<nil> NonRootVolumes:[] NetworkInterfaces:[] Tags:map[] AvailabilityZone: SpotMarketOptions:<nil> Tenancy: VolumeIDs:[]} ... skipping 45 lines ... I1229 18:26:07.282585 23908 logger.go:71] "EC2 instance found matching deleted AWSMachine" instance-id="myMachine" I1229 18:26:07.282675 23908 logger.go:67] "Terminating EC2 instance" instance-id="myMachine" I1229 18:26:07.284252 23908 logger.go:67] "EC2 instance successfully terminated" instance-id="myMachine" === RUN TestAWSMachineReconciler/Secrets_management_lifecycle/Secrets_management_lifecycle_when_there_is_an_intermittent_connection_issue_and_no_secret_could_be_stored === RUN TestAWSMachineReconciler/Secrets_management_lifecycle/Secrets_management_lifecycle_when_there_is_an_intermittent_connection_issue_and_no_secret_could_be_stored/should_error_if_secret_could_not_be_created I1229 18:26:07.286821 23908 logger.go:67] "Creating EC2 instance" E1229 18:26:07.288913 23908 logger.go:83] "Failed to create AWS Secret entry" err="connection error" secretPrefix="test/secret" E1229 18:26:07.288913 23908 logger.go:83] "Failed to create AWS Secret entry" err="connection error" secretPrefix="test/secret" E1229 18:26:07.288913 23908 logger.go:83] "Failed to create AWS Secret entry" err="connection error" secretPrefix="test/secret" E1229 18:26:07.288913 23908 logger.go:83] "Failed to create AWS Secret entry" err="connection error" secretPrefix="test/secret" E1229 18:26:07.289293 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: connection error" E1229 18:26:07.289293 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: connection error" E1229 18:26:07.289293 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: connection error" E1229 18:26:07.289293 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: connection error" === RUN TestAWSMachineReconciler/Secrets_management_lifecycle/Secrets_management_lifecycle_when_there_is_an_intermittent_connection_issue_and_no_secret_could_be_stored/should_update_prefix_and_count_on_successful_creation I1229 18:26:07.291900 23908 logger.go:67] "Creating EC2 instance" I1229 18:26:07.297121 23908 logger.go:67] "EC2 instance state changed" state=pending instance-id="myMachine" I1229 18:26:07.297417 23908 logger.go:71] "done reconciling instance" instance=&{ID:myMachine State:pending Type: SubnetID: ImageID: SSHKeyName:<nil> SecurityGroupIDs:[] UserData:<nil> IAMProfile: Addresses:[] PrivateIP:<nil> PublicIP:<nil> ENASupport:<nil> EBSOptimized:<nil> RootVolume:<nil> NonRootVolumes:[] NetworkInterfaces:[] Tags:map[] AvailabilityZone: SpotMarketOptions:<nil> Tenancy: VolumeIDs:[]} === RUN TestAWSMachineReconciler/Object_storage_lifecycle === RUN TestAWSMachineReconciler/Object_storage_lifecycle/creating_EC2_instances ... skipping 44 lines ... I1229 18:26:07.342158 23908 logger.go:71] "EC2 instance found matching deleted AWSMachine" instance-id="myMachine" I1229 18:26:07.342253 23908 logger.go:67] "Terminating EC2 instance" instance-id="myMachine" I1229 18:26:07.344173 23908 logger.go:67] "EC2 instance successfully terminated" instance-id="myMachine" === RUN TestAWSMachineReconciler/Object_storage_lifecycle/there_is_an_intermittent_connection_issue_and_no_object_could_be_created === RUN TestAWSMachineReconciler/Object_storage_lifecycle/there_is_an_intermittent_connection_issue_and_no_object_could_be_created/should_error_if_object_could_not_be_created I1229 18:26:07.346559 23908 logger.go:67] "Creating EC2 instance" E1229 18:26:07.346862 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: creating userdata object: connection error" E1229 18:26:07.346862 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: creating userdata object: connection error" E1229 18:26:07.346862 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: creating userdata object: connection error" E1229 18:26:07.346862 23908 logger.go:83] "unable to create instance" err="failed to resolve userdata: creating userdata object: connection error" === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/should_exit_immediately_on_an_error_state I1229 18:26:07.347618 23908 logger.go:67] "Handling deleted AWSMachine" I1229 18:26:07.347735 23908 logger.go:67] "Deleting unneeded entry from AWS Secret" secretPrefix="prefix" E1229 18:26:07.347961 23908 logger.go:83] "query to find instance failed" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:07.347961 23908 logger.go:83] "query to find instance failed" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:07.347961 23908 logger.go:83] "query to find instance failed" err="failed to query AWSMachine instance by tags: no connection available " E1229 18:26:07.347961 23908 logger.go:83] "query to find instance failed" err="failed to query AWSMachine instance by tags: no connection available " === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/should_log_and_remove_finalizer_when_no_machine_exists === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/should_ignore_instances_in_shutting_down_state === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/should_ignore_instances_in_terminated_down_state === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/instance_not_shutting_down_yet === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/instance_not_shutting_down_yet/should_return_an_error_when_the_instance_can't_be_terminated E1229 18:26:07.362247 23908 logger.go:83] "failed to terminate instance" err="can't reach AWS to terminate machine" === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/instance_not_shutting_down_yet/when_instance_can_be_shut_down === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/instance_not_shutting_down_yet/when_instance_can_be_shut_down/should_error_when_it_can't_retrieve_security_groups_if_there_are_network_interfaces I1229 18:26:07.374127 23908 logger.go:67] "Handling deleted AWSMachine" I1229 18:26:07.374165 23908 logger.go:67] "Deleting unneeded entry from AWS Secret" secretPrefix="prefix" I1229 18:26:07.374318 23908 logger.go:71] "EC2 instance found matching deleted AWSMachine" instance-id="aws:////myid" I1229 18:26:07.374342 23908 logger.go:67] "Terminating EC2 instance" instance-id="aws:////myid" E1229 18:26:07.375810 23908 logger.go:83] "failed to get core security groups to detach from instance's network interfaces" err="can't reach AWS to list security groups" E1229 18:26:07.375810 23908 logger.go:83] "failed to get core security groups to detach from instance's network interfaces" err="can't reach AWS to list security groups" E1229 18:26:07.375810 23908 logger.go:83] "failed to get core security groups to detach from instance's network interfaces" err="can't reach AWS to list security groups" E1229 18:26:07.375810 23908 logger.go:83] "failed to get core security groups to detach from instance's network interfaces" err="can't reach AWS to list security groups" === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/instance_not_shutting_down_yet/when_instance_can_be_shut_down/should_error_when_it_can't_detach_a_security_group_from_an_interface I1229 18:26:07.376218 23908 logger.go:67] "Handling deleted AWSMachine" I1229 18:26:07.376250 23908 logger.go:67] "Deleting unneeded entry from AWS Secret" secretPrefix="prefix" I1229 18:26:07.376352 23908 logger.go:71] "EC2 instance found matching deleted AWSMachine" instance-id="aws:////myid" I1229 18:26:07.376371 23908 logger.go:67] "Terminating EC2 instance" instance-id="aws:////myid" I1229 18:26:07.378034 23908 logger.go:71] "Detaching security groups from provided network interface" groups=[sg0 sg1] instanceID="aws:////myid" E1229 18:26:07.379783 23908 logger.go:83] "failed to detach security groups from instance's network interfaces" err="can't reach AWS to detach security group" E1229 18:26:07.379783 23908 logger.go:83] "failed to detach security groups from instance's network interfaces" err="can't reach AWS to detach security group" E1229 18:26:07.379783 23908 logger.go:83] "failed to detach security groups from instance's network interfaces" err="can't reach AWS to detach security group" E1229 18:26:07.379783 23908 logger.go:83] "failed to detach security groups from instance's network interfaces" err="can't reach AWS to detach security group" === RUN TestAWSMachineReconciler/Deleting_an_AWSMachine/instance_not_shutting_down_yet/when_instance_can_be_shut_down/should_detach_all_combinations_of_network_interfaces I1229 18:26:07.380214 23908 logger.go:67] "Handling deleted AWSMachine" I1229 18:26:07.380244 23908 logger.go:67] "Deleting unneeded entry from AWS Secret" secretPrefix="prefix" I1229 18:26:07.380349 23908 logger.go:71] "EC2 instance found matching deleted AWSMachine" instance-id="aws:////myid" I1229 18:26:07.380370 23908 logger.go:67] "Terminating EC2 instance" instance-id="aws:////myid" I1229 18:26:07.384787 23908 logger.go:71] "Detaching security groups from provided network interface" groups=[sg0 sg1] instanceID="aws:////myid" ... skipping 231 lines ... --- PASS: TestAWSMachineReconciler_Reconcile/Should_fail_to_reconcile_while_fetching_infra_cluster (0.11s) PASS I1229 18:26:08.461982 23908 certwatcher.go:142] "controller-runtime/certwatcher: certificate event" event="REMOVE \"/tmp/envtest-serving-certs-1218541497/tls.crt\"" I1229 18:26:08.462119 23908 internal.go:571] "Stopping and waiting for non leader election runnables" I1229 18:26:08.462152 23908 internal.go:575] "Stopping and waiting for leader election runnables" I1229 18:26:08.462167 23908 internal.go:581] "Stopping and waiting for caches" E1229 18:26:08.462472 23908 certwatcher.go:147] "controller-runtime/certwatcher: error re-watching file" err="no such file or directory" E1229 18:26:08.462472 23908 certwatcher.go:147] "controller-runtime/certwatcher: error re-watching file" err="no such file or directory" E1229 18:26:08.462472 23908 certwatcher.go:147] "controller-runtime/certwatcher: error re-watching file" err="no such file or directory" E1229 18:26:08.462472 23908 certwatcher.go:147] "controller-runtime/certwatcher: error re-watching file" err="no such file or directory" I1229 18:26:08.462528 23908 internal.go:585] "Stopping and waiting for webhooks" I1229 18:26:08.462599 23908 server.go:277] "controller-runtime/webhook: shutting down webhook server" E1229 18:26:08.462533 23908 certwatcher.go:152] "controller-runtime/certwatcher: error re-reading certificate" err="open /tmp/envtest-serving-certs-1218541497/tls.crt: no such file or directory" E1229 18:26:08.462533 23908 certwatcher.go:152] "controller-runtime/certwatcher: error re-reading certificate" err="open /tmp/envtest-serving-certs-1218541497/tls.crt: no such file or directory" E1229 18:26:08.462533 23908 certwatcher.go:152] "controller-runtime/certwatcher: error re-reading certificate" err="open /tmp/envtest-serving-certs-1218541497/tls.crt: no such file or directory" E1229 18:26:08.462533 23908 certwatcher.go:152] "controller-runtime/certwatcher: error re-reading certificate" err="open /tmp/envtest-serving-certs-1218541497/tls.crt: no such file or directory" I1229 18:26:08.462749 23908 internal.go:589] "Wait completed, proceeding to shutdown the manager" ok sigs.k8s.io/cluster-api-provider-aws/v2/controllers 22.546s === RUN TestFuzzyConversion === RUN TestFuzzyConversion/for_AWSManagedControlPlane === RUN TestFuzzyConversion/for_AWSManagedControlPlane/spoke-hub-spoke === RUN TestFuzzyConversion/for_AWSManagedControlPlane/hub-spoke-hub ... skipping 9 lines ... I1229 18:25:59.413709 23797 server.go:148] "controller-runtime/webhook: Registering webhook" path="/validate-controlplane-cluster-x-k8s-io-v1beta2-awsmanagedcontrolplane" Starting the manager I1229 18:25:59.413972 23797 server.go:216] "controller-runtime/webhook/webhooks: Starting webhook server" I1229 18:25:59.415806 23797 certwatcher.go:131] "controller-runtime/certwatcher: Updated current TLS certificate" I1229 18:25:59.416091 23797 server.go:270] "controller-runtime/webhook: Serving webhook server" host="" port=34383 I1229 18:25:59.417215 23797 certwatcher.go:85] "controller-runtime/certwatcher: Starting certificate watcher" 2022/12/29 18:26:00 http: TLS handshake error from 127.0.0.1:40216: EOF === RUN TestDefaultingWebhook === RUN TestDefaultingWebhook/less_than_100_chars === RUN TestDefaultingWebhook/less_than_100_chars/for_AWSManagedMachinePool === RUN TestDefaultingWebhook/less_than_100_chars/for_AWSManagedMachinePool/validate-on-create I1229 18:26:00.414990 23797 awsmanagedcontrolplane_webhook.go:404] "awsmanagedcontrolplane-resource: AWSManagedControlPlane setting defaults" control-plane="default/cluster1" I1229 18:26:00.415010 23797 awsmanagedcontrolplane_webhook.go:407] "awsmanagedcontrolplane-resource: EKSClusterName is empty, generating name" ... skipping 574 lines ... I1229 18:26:11.013118 24072 controller.go:185] "Starting EventSource" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" source="kind source: *v1beta2.AWSManagedControlPlane" I1229 18:26:11.013150 24072 controller.go:193] "Starting Controller" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" I1229 18:26:11.013759 24072 certwatcher.go:131] "controller-runtime/certwatcher: Updated current TLS certificate" I1229 18:26:11.014066 24072 certwatcher.go:85] "controller-runtime/certwatcher: Starting certificate watcher" I1229 18:26:11.014072 24072 server.go:270] "controller-runtime/webhook: Serving webhook server" host="" port=37457 I1229 18:26:11.314488 24072 controller.go:227] "Starting workers" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" worker count=1 2022/12/29 18:26:12 http: TLS handshake error from 127.0.0.1:47556: EOF === RUN TestAWSControllerIdentityController === RUN TestAWSControllerIdentityController/should_create_AWSClusterControllerIdentity_when_identityRef_is_not_specified awscontrolleridentity_controller_test.go:43: Ensuring AWSClusterControllerIdentity instance is created I1229 18:26:12.131453 24072 logger.go:67] "AWSClusterControllerIdentity instance not found, creating a new instance" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" AWSCluster="default/foo" namespace="default" name="foo" reconcileID=39bc6daa-ecec-46cf-a449-fbde05ad1f31 cluster="default/foo" --- PASS: TestAWSControllerIdentityController (0.16s) --- PASS: TestAWSControllerIdentityController/should_create_AWSClusterControllerIdentity_when_identityRef_is_not_specified (0.16s) ... skipping 32 lines ... I1229 18:26:15.497927 24137 certwatcher.go:85] "controller-runtime/certwatcher: Starting certificate watcher" I1229 18:26:15.497992 24137 server.go:270] "controller-runtime/webhook: Serving webhook server" host="" port=34807 === RUN TestAWSMachinePoolReconciler === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/when_can't_reach_amazon === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/when_can't_reach_amazon/should_exit_immediately_on_an_error_state 2022/12/29 18:26:16 http: TLS handshake error from 127.0.0.1:51286: EOF I1229 18:26:18.607327 24137 http.go:96] "controller-runtime/webhook/webhooks: received request" webhook="/mutate-infrastructure-cluster-x-k8s-io-v1beta2-awsmachinepool" UID=007c6fc0-bd83-40fb-87f8-288c59984369 kind="infrastructure.cluster.x-k8s.io/v1beta2, Kind=AWSMachinePool" resource={Group:infrastructure.cluster.x-k8s.io Version:v1beta2 Resource:awsmachinepools} I1229 18:26:18.607971 24137 awsmachinepool_webhook.go:162] "awsmachinepool-resource: DefaultCoolDown is zero, setting 300 seconds as default" I1229 18:26:18.608615 24137 http.go:143] "controller-runtime/webhook/webhooks: wrote response" webhook="/mutate-infrastructure-cluster-x-k8s-io-v1beta2-awsmachinepool" code=200 reason= UID=007c6fc0-bd83-40fb-87f8-288c59984369 allowed=true I1229 18:26:18.611451 24137 http.go:96] "controller-runtime/webhook/webhooks: received request" webhook="/validate-infrastructure-cluster-x-k8s-io-v1beta2-awsmachinepool" UID=12bea314-b726-4098-8474-af3d2e916704 kind="infrastructure.cluster.x-k8s.io/v1beta2, Kind=AWSMachinePool" resource={Group:infrastructure.cluster.x-k8s.io Version:v1beta2 Resource:awsmachinepools} I1229 18:26:18.611591 24137 awsmachinepool_webhook.go:113] "awsmachinepool-resource: AWSMachinePool validate create" machine-pool="default/test" I1229 18:26:18.611683 24137 http.go:143] "controller-runtime/webhook/webhooks: wrote response" webhook="/validate-infrastructure-cluster-x-k8s-io-v1beta2-awsmachinepool" code=200 reason= UID=12bea314-b726-4098-8474-af3d2e916704 allowed=true === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/when_can't_reach_amazon/should_add_our_finalizer_to_the_machinepool === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/when_can't_reach_amazon/should_exit_immediately_if_cluster_infra_isn't_ready === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/when_can't_reach_amazon/should_exit_immediately_if_bootstrap_data_secret_reference_isn't_available === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/there's_a_provider_ID === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/there's_a_provider_ID/should_look_up_by_provider_ID_when_one_exists E1229 18:26:18.892050 24137 logger.go:83] "failed to reconcile launch template" err="no connection available " === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/there's_suspended_processes_provided_during_ASG_creation === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/there's_suspended_processes_provided_during_ASG_creation/it_should_not_call_suspend_as_we_don't_have_an_ASG_yet === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/all_processes_are_suspended === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/all_processes_are_suspended/processes_should_be_suspended_during_an_update_call === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/there_are_existing_processes_already_suspended === RUN TestAWSMachinePoolReconciler/Reconciling_an_AWSMachinePool/there_are_existing_processes_already_suspended/it_should_suspend_and_resume_processes_that_are_desired_to_be_suspended_and_desired_to_be_resumed ... skipping 74 lines ... I1229 18:26:21.573272 24248 certwatcher.go:131] "controller-runtime/certwatcher: Updated current TLS certificate" I1229 18:26:21.573465 24248 certwatcher.go:85] "controller-runtime/certwatcher: Starting certificate watcher" I1229 18:26:21.573604 24248 server.go:270] "controller-runtime/webhook: Serving webhook server" host="" port=35095 I1229 18:26:22.079005 24248 controller.go:185] "Starting EventSource" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" source="kind source: *v1beta2.AWSCluster" I1229 18:26:22.079060 24248 controller.go:193] "Starting Controller" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" I1229 18:26:22.079138 24248 controller.go:227] "Starting workers" controller="awscluster" controllerGroup="infrastructure.cluster.x-k8s.io" controllerKind="AWSCluster" worker count=1 2022/12/29 18:26:22 http: TLS handshake error from 127.0.0.1:55658: EOF awsinstancestate_controller_test.go:130: Ensuring queue URLs are up-to-date I1229 18:26:22.761816 24248 awsinstancestate_controller.go:85] "controllers/AWSInstanceState: cluster not found, removing queue URL" cluster="default/aws-cluster-2" awsinstancestate_controller_test.go:141: Ensuring we stop tracking deleted queue awsinstancestate_controller_test.go:148: Ensuring newly created cluster is added to tracked clusters awsinstancestate_controller_test.go:158: Ensuring machine is labelled with correct instance state --- PASS: TestAWSInstanceStateController (2.02s) ... skipping 152 lines ... === RUN TestServiceCreateASG === RUN TestServiceCreateASG/should_return_without_error_if_create_ASG_is_successful I1229 18:26:24.018238 24524 logger.go:67] "Running instance" === RUN TestServiceCreateASG/should_return_error_if_subnet_not_found_for_asg === RUN TestServiceCreateASG/should_return_error_if_create_ASG_fails I1229 18:26:24.020421 24524 logger.go:67] "Running instance" E1229 18:26:24.020504 24524 logger.go:83] "unable to create AutoScalingGroup" err="failed to create autoscaling group: dependency failure" === RUN TestServiceCreateASG/should_return_error_if_launch_template_is_missing --- PASS: TestServiceCreateASG (0.00s) --- PASS: TestServiceCreateASG/should_return_without_error_if_create_ASG_is_successful (0.00s) --- PASS: TestServiceCreateASG/should_return_error_if_subnet_not_found_for_asg (0.00s) --- PASS: TestServiceCreateASG/should_return_error_if_create_ASG_fails (0.00s) --- PASS: TestServiceCreateASG/should_return_error_if_launch_template_is_missing (0.00s) ... skipping 73 lines ... not to be empty === RUN TestReconcileCniVpcCniValues/users_can_set_environment_values_overwriting_existing_values_with_secondary_cidr cni_test.go:233: Expected <[]client.Object | len:0, cap:0>: nil not to be empty --- FAIL: TestReconcileCniVpcCniValues (0.00s) --- PASS: TestReconcileCniVpcCniValues/users_can_set_environment_values_without_secondary_cidr (0.00s) --- PASS: TestReconcileCniVpcCniValues/users_can_set_environment_values_without_duplications_without_secondary_cidr (0.00s) --- PASS: TestReconcileCniVpcCniValues/users_can_set_environment_values_overwriting_existing_values_without_secondary_cidr (0.00s) --- FAIL: TestReconcileCniVpcCniValues/users_can_set_environment_values_with_secondary_cidr (0.00s) --- FAIL: TestReconcileCniVpcCniValues/users_can_set_environment_values_without_duplications_with_secondary_cidr (0.00s) --- FAIL: TestReconcileCniVpcCniValues/users_can_set_environment_values_overwriting_existing_values_with_secondary_cidr (0.00s) FAIL FAIL sigs.k8s.io/cluster-api-provider-aws/v2/pkg/cloud/services/awsnode 0.236s === RUN TestDefaultAMILookup === RUN TestDefaultAMILookup/Should_return_latest_AMI_in_case_of_valid_inputs === RUN TestDefaultAMILookup/Should_return_with_error_if_AWS_DescribeImages_call_failed_with_some_error === RUN TestDefaultAMILookup/Should_return_with_error_if_empty_list_of_images_returned_from_AWS_ --- PASS: TestDefaultAMILookup (0.00s) --- PASS: TestDefaultAMILookup/Should_return_latest_AMI_in_case_of_valid_inputs (0.00s) ... skipping 1419 lines ... --- PASS: TestGetManagerNamespace/env_var_empty (0.00s) === RUN TestGetNamespaceFromFile --- PASS: TestGetNamespaceFromFile (0.00s) PASS ok sigs.k8s.io/cluster-api-provider-aws/v2/util/system 0.008s ? sigs.k8s.io/cluster-api-provider-aws/v2/version [no test files] FAIL make: *** [Makefile:398: test-verbose] Error 1