Skip to content

AS3 per-app deployment blocked in infinite loop "Still Creating..." #1041

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom
Open
emze9 opened this issue Jan 14, 2025 · 0 comments
Open

AS3 per-app deployment blocked in infinite loop "Still Creating..." #1041

emze9 opened this issue Jan 14, 2025 · 0 comments
Labels
bug

Comments

@emze9
Copy link

emze9 commented Jan 14, 2025

Environment

  • TMOS/Bigip Version: BIG-IP 17.1.2 Build 0.0.8 Final
  • Terraform Version: Terraform v1.10.1
  • Terraform bigip provider Version: v1.22.6
  • AS3 Version: 3.53.0

Summary

When doing an AS3 per-app deployment, it seems that the terraform provider is not receiving / interpreting the results message from AS3 causing a Still creating... infinite loop.

Steps To Reproduce

Steps to reproduce the behavior:

  1. main.tf :
terraform {
  required_providers {
    bigip = {
      source = "F5Networks/bigip"
      version = "1.22.6"
    }
  }
  required_version = ">= 0.13"
}

provider "bigip" {
  address  = "example.com"
  username = "ansible-as3"
  password = var.password
}

# Example Usage for json file
resource "bigip_as3" "example" {
  as3_json = file("../playbooks/tmp/example.json")
  tenant_name = "misc"
}
  1. To get to know more about the issue, provide terraform debug logs
terraform@caf809b49787:/Workspace/waf/as3/terraform$ terraform apply
2025-01-14T10:43:00.455Z [INFO]  provider: configuring client automatic mTLS
2025-01-14T10:43:00.466Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 args=[".terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6"]
2025-01-14T10:43:00.474Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 pid=1850
2025-01-14T10:43:00.474Z [DEBUG] provider: waiting for RPC address: plugin=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6
2025-01-14T10:43:00.907Z [INFO]  provider.terraform-provider-bigip_v1.22.6: configuring server automatic mTLS: timestamp=2025-01-14T10:43:00.907Z
2025-01-14T10:43:00.968Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: plugin address: address=/tmp/plugin3733469932 network=unix timestamp=2025-01-14T10:43:00.968Z
2025-01-14T10:43:00.968Z [DEBUG] provider: using plugin: version=5
2025-01-14T10:43:01.028Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2025-01-14T10:43:01.032Z [INFO]  provider: plugin process exited: plugin=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 id=1850
2025-01-14T10:43:01.032Z [DEBUG] provider: plugin exited
2025-01-14T10:43:01.041Z [INFO]  provider: configuring client automatic mTLS
2025-01-14T10:43:01.046Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 args=[".terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6"]
2025-01-14T10:43:01.054Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 pid=1865
2025-01-14T10:43:01.054Z [DEBUG] provider: waiting for RPC address: plugin=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6
2025-01-14T10:43:01.547Z [INFO]  provider.terraform-provider-bigip_v1.22.6: configuring server automatic mTLS: timestamp=2025-01-14T10:43:01.547Z
2025-01-14T10:43:01.594Z [DEBUG] provider: using plugin: version=5
2025-01-14T10:43:01.594Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: plugin address: address=/tmp/plugin1594001163 network=unix timestamp=2025-01-14T10:43:01.594Z
2025-01-14T10:43:01.657Z [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2025-01-14T10:43:01.661Z [INFO]  provider: plugin process exited: plugin=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 id=1865
2025-01-14T10:43:01.661Z [DEBUG] provider: plugin exited
2025-01-14T10:43:01.670Z [INFO]  provider: configuring client automatic mTLS
2025-01-14T10:43:01.675Z [DEBUG] provider: starting plugin: path=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 args=[".terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6"]
2025-01-14T10:43:01.682Z [DEBUG] provider: plugin started: path=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6 pid=1880
2025-01-14T10:43:01.682Z [DEBUG] provider: waiting for RPC address: plugin=.terraform/providers/registry.terraform.io/f5networks/bigip/1.22.6/linux_amd64/terraform-provider-bigip_v1.22.6
2025-01-14T10:43:02.130Z [INFO]  provider.terraform-provider-bigip_v1.22.6: configuring server automatic mTLS: timestamp=2025-01-14T10:43:02.130Z
2025-01-14T10:43:02.188Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: plugin address: address=/tmp/plugin1077997876 network=unix timestamp=2025-01-14T10:43:02.188Z
2025-01-14T10:43:02.188Z [DEBUG] provider: using plugin: version=5
2025-01-14T10:43:02.248Z [WARN]  ValidateProviderConfig from "provider[\"registry.terraform.io/f5networks/bigip\"]" changed the config value, but that value is unused
2025-01-14T10:43:02.250Z [INFO]  provider.terraform-provider-bigip_v1.22.6: Initializing BigIP connection: timestamp=2025-01-14T10:43:02.250Z
2025-01-14T10:43:02.944Z [ERROR] Checkpoint error: Get "https://checkpoint-api.hashicorp.com/v1/check/terraform?arch=amd64&os=linux&signature=c5ce57e4-1753-d585-1160-0e290f1ebe8f&version=1.10.1": context deadline exceeded (Client.Timeout exceeded 
while awaiting headers)
bigip_as3.example: Creating...
2025-01-14T10:44:08.711Z [INFO]  provider.terraform-provider-bigip_v1.22.6: BigIP Setting perAppDeploymentAllowed:true: timestamp=2025-01-14T10:44:08.711Z
2025-01-14T10:44:08.711Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: perApplication:true: timestamp=2025-01-14T10:44:08.711Z
2025-01-14T10:44:08.711Z [INFO]  provider.terraform-provider-bigip_v1.22.6: Creating As3 config perApplication : tenant name :misc: timestamp=2025-01-14T10:44:08.711Z
2025-01-14T10:44:08.711Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: tenant name :misc: timestamp=2025-01-14T10:44:08.711Z
2025-01-14T10:44:13.656Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: Per-App Deployment Code = 0,ID = 7b119337-9cf3-44cc-bcdd-9003d5f10eb3: timestamp=2025-01-14T10:44:13.656Z
2025-01-14T10:44:13.656Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: Per-App Deployment task status = map[declaration:map[] id:7b119337-9cf3-44cc-bcdd-9003d5f10eb3 results:[map[code:0 host: message:in progress runTime:0 tenant:]] selfLink:https://localhost/mgmt/shared/appsvcs/task/7b119337-9cf3-44cc-bcdd-9003d5f10eb3]: timestamp=2025-01-14T10:44:13.656Z
2025-01-14T10:44:16.553Z [INFO]  provider.terraform-provider-bigip_v1.22.6: respCode: 0: timestamp=2025-01-14T10:44:16.552Z
2025-01-14T10:44:16.553Z [INFO]  provider.terraform-provider-bigip_v1.22.6: message: in progress: timestamp=2025-01-14T10:44:16.552Z
...
results:[map[code:200 declarationId:autogen_1d67485d-35cd-4301-b178-811f9d41aa66 host:localhost message:no change runTime:3088 tenant:misc]] selfLink:https://localhost/mgmt/shared/appsvcs/task/7b119337-9cf3-44cc-bcdd-9003d5f10eb3]: timestamp=2025-01-14T10:44:25.656Z
2025-01-14T10:44:25.657Z [DEBUG] provider.terraform-provider-bigip_v1.22.6: task Id from deployment :7b119337-9cf3-44cc-bcdd-9003d5f10eb3: timestamp=2025-01-14T10:44:25.656Z
bigip_as3.example: Still creating... [20s elapsed]
...
bigip_as3.example: Still creating... [10m20s elapsed]
  1. example.json:
{
    "controls": {
        "class": "Controls",
        "logLevel": "debug",
        "trace": true
    },
    "example": {
        "class": "Application",
        "example": {
            "class": "Service_HTTP",
            "pool": "pool_example",
            "virtualAddresses": [
                "10.20.20.4"
            ],
            "virtualPort": 80
        },
        "pool_example": {
            "class": "Pool",
            "loadBalancingMode": "round-robin",
            "members": [
                {
                    "serverAddresses": [
                        "10.20.20.2"
                    ],
                    "servicePort": 80
                }
            ]
        }
    },
    "schemaVersion": "3.53.0",
    "id": "example"
}

Expected Behavior

The terraform provider should catch the response from the BIG-IP and create a terraform.tfstate file.

Actual Behavior

The terraform provider is not receiving / interpreting the results message from AS3 causing a Still creating... infinite loop.
restnoded.log:

Tue, 14 Jan 2025 10:44:09 GMT - fine: [appsvcs] {"message":"Request ?async=true: creating data-group async task 7b119337-9cf3-44cc-bcdd-9003d5f10eb3 and responding with 202 while we continue processing.","level":"debug"}
Tue, 14 Jan 2025 10:44:09 GMT - fine: [appsvcs] {"message":"validating declaration having id autogen_1d67485d-35cd-4301-b178-811f9d41aa66","level":"debug"}
Tue, 14 Jan 2025 10:44:09 GMT - config: [appsvcs] {"message":"Parser time: 2 milliseconds","level":"notice"}
Tue, 14 Jan 2025 10:44:09 GMT - fine: [appsvcs] {"message":"declaration is valid","level":"debug"}
Tue, 14 Jan 2025 10:44:09 GMT - fine: [appsvcs] {"message":"success parsing declaration","level":"debug"}
Tue, 14 Jan 2025 10:44:11 GMT - fine: [appsvcs] {"message":"Begin parsing desired misc config","level":"debug"}
Tue, 14 Jan 2025 10:44:11 GMT - config: [appsvcs] {"message":"parsing desired misc config time: 0.02 seconds","level":"notice"}
Tue, 14 Jan 2025 10:44:11 GMT - fine: [appsvcs] {"message":"Begin parsing current misc config","level":"debug"}
Tue, 14 Jan 2025 10:44:12 GMT - finest: socket 506 opened
Tue, 14 Jan 2025 10:44:12 GMT - severe: [RestOperationDispatcher] 'shared/service-discovery/task' not found.
Tue, 14 Jan 2025 10:44:12 GMT - severe: [ErrorHandlingModule] RestOperation failed: "/shared/service-discovery/task?$filter=partition+eq+misc". {"code":404,"message":"","referer":"Unknown","originalRequestBody":"","errorStack":[]}
Tue, 14 Jan 2025 10:44:12 GMT - warning: [appsvcs] {"message":"Unable to fetch state from /mgmt/shared/service-discovery/task","level":"warning"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"fetched declaration from target, 1 blocks, 3 Tenants","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - config: [appsvcs] {"message":"parsing current misc config time: 3.031 seconds","level":"notice"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"Begin generating misc diff","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - config: [appsvcs] {"message":"generating misc diff time: 0.023 seconds","level":"notice"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"Begin updating misc","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"code":200,"message":"no change","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - config: [appsvcs] {"message":"updating misc time: 0 seconds","level":"notice"}
Tue, 14 Jan 2025 10:44:14 GMT - info: [appsvcs] {"code":200,"message":"no change","host":"localhost","tenant":"misc","runTime":3088,"declarationId":"autogen_1d67485d-35cd-4301-b178-811f9d41aa66","level":"info"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"Begin post process updating all_tenants","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - config: [appsvcs] {"message":"post process updating all_tenants time: 0.001 seconds","level":"notice"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"deployed= 1 good 0 bad 0 changes","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"0 changes, save current declaration for later","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"store decl on target, blocks=1","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"next purge stored decls in excess of 4","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"stopping refresh of mutex","level":"debug"}
Tue, 14 Jan 2025 10:44:14 GMT - fine: [appsvcs] {"message":"Queuing release of global lock","level":"debug"}
Tue, 14 Jan 2025 10:44:15 GMT - fine: [appsvcs] {"message":"All tenant locks released","level":"debug"}
Tue, 14 Jan 2025 10:44:17 GMT - finest: socket 506 closed
Tue, 14 Jan 2025 10:44:18 GMT - fine: [appsvcs] {"message":"BIG-IP config saved","level":"debug"}
Tue, 14 Jan 2025 10:44:18 GMT - fine: [appsvcs] {"message":"Finishing async request, setting data-group async task 7b119337-9cf3-44cc-bcdd-9003d5f10eb3 to indicate the request is completed.","level":"debug"}
@emze9 emze9 added the bug label Jan 14, 2025
@emze9
Copy link
Author

emze9 commented Feb 20, 2025

It happens with other resources:

terraform {
  required_providers {
    bigip = {
      source = "F5Networks/bigip"
      version = "1.22.7"
    }
  }
  required_version = ">= 1.10"
}

provider "bigip" {
  address       = var.bigip_address
  username      = var.bigip_username
  password      = var.bigip_password
  token_timeout = var.bigip_token_timeout
  token_auth    = var.bigip_token_auth
}

resource "bigip_ltm_profile_http" "sanjose-http" {
  name                  = "/Common/sanjose-http"
  defaults_from         = "/Common/http"
}

bigip_address  = redacted
bigip_username = "as3"
bigip_password = redacted
bigip_token_timeout = 86400
bigip_token_auth = false

2025-02-20T10:21:06.663Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Served request: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:510 tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=a5d1c113-4573-a021-6785-d87039eef475 @module=sdk.proto tf_rpc=GetProviderSchema timestamp=2025-02-20T10:21:06.663Z
2025-02-20T10:21:06.670Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.670Z [TRACE] GRPCProvider: ValidateProviderConfig
2025-02-20T10:21:06.670Z [TRACE] GRPCProvider: GetProviderSchema
2025-02-20T10:21:06.671Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received request: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:518 tf_proto_version=5.3 tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 tf_rpc=PrepareProviderConfig @module=sdk.proto tf_provider_addr=provider timestamp=2025-02-20T10:21:06.671Z
2025-02-20T10:21:06.671Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Skipping protocol data file writing because no data directory is set. Use the TF_LOG_SDK_PROTO_DATA_DIR environment variable to enable this functionality.: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/internal/logging/protocol_data.go:38 @module=sdk.proto tf_provider_addr=provider tf_proto_version=5.3 tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 tf_rpc=PrepareProviderConfig timestamp=2025-02-20T10:21:06.671Z
2025-02-20T10:21:06.671Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Sending request downstream: tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 @module=sdk.proto tf_provider_addr=provider tf_proto_version=5.3 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 timestamp=2025-02-20T10:21:06.671Z
2025-02-20T10:21:06.671Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Preparing provider configuration: tf_provider_addr=provider tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:128 @module=sdk.helper_schema timestamp=2025-02-20T10:21:06.671Z
2025-02-20T10:21:06.674Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Calling downstream: tf_provider_addr=provider tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:216 @module=sdk.helper_schema timestamp=2025-02-20T10:21:06.674Z
2025-02-20T10:21:06.675Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 tf_rpc=PrepareProviderConfig @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:218 timestamp=2025-02-20T10:21:06.675Z
2025-02-20T10:21:06.675Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received downstream response: diagnostic_error_count=0 diagnostic_warning_count=0 tf_provider_addr=provider tf_req_duration_ms=4 @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto tf_proto_version=5.3 tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 tf_rpc=PrepareProviderConfig timestamp=2025-02-20T10:21:06.675Z
2025-02-20T10:21:06.675Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Served request: tf_rpc=PrepareProviderConfig @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:539 tf_req_id=cb439c9c-8668-7ead-c162-fa297b2f0617 timestamp=2025-02-20T10:21:06.675Z
2025-02-20T10:21:06.676Z [WARN]  ValidateProviderConfig from "provider[\"registry.terraform.io/f5networks/bigip\"]" changed the config value, but that value is unused
2025-02-20T10:21:06.676Z [TRACE] GRPCProvider: ConfigureProvider
2025-02-20T10:21:06.676Z [TRACE] GRPCProvider: GetProviderSchema
2025-02-20T10:21:06.676Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received request: @module=sdk.proto tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:547 tf_proto_version=5.3 tf_req_id=9fdaf175-ceae-4dad-a5e7-791c62d27a40 tf_rpc=Configure timestamp=2025-02-20T10:21:06.676Z
2025-02-20T10:21:06.677Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Sending request downstream: tf_rpc=Configure @module=sdk.proto tf_provider_addr=provider @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 tf_proto_version=5.3 tf_req_id=9fdaf175-ceae-4dad-a5e7-791c62d27a40 timestamp=2025-02-20T10:21:06.676Z
2025-02-20T10:21:06.677Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Calling downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=9fdaf175-ceae-4dad-a5e7-791c62d27a40 tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:556 timestamp=2025-02-20T10:21:06.677Z
2025-02-20T10:21:06.677Z [INFO]  provider.terraform-provider-bigip_v1.22.7: Initializing BigIP connection: timestamp=2025-02-20T10:21:06.677Z
2025-02-20T10:21:06.866Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Called downstream: @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=9fdaf175-ceae-4dad-a5e7-791c62d27a40 tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:558 timestamp=2025-02-20T10:21:06.866Z
2025-02-20T10:21:06.866Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received downstream response: @module=sdk.proto diagnostic_warning_count=0 tf_req_duration_ms=189 tf_rpc=Configure tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=9fdaf175-ceae-4dad-a5e7-791c62d27a40 @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 diagnostic_error_count=0 timestamp=2025-02-20T10:21:06.866Z
2025-02-20T10:21:06.866Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Served request: tf_proto_version=5.3 tf_rpc=Configure @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:567 @module=sdk.proto tf_provider_addr=provider tf_req_id=9fdaf175-ceae-4dad-a5e7-791c62d27a40 timestamp=2025-02-20T10:21:06.866Z
2025-02-20T10:21:06.866Z [TRACE] vertex "provider[\"registry.terraform.io/f5networks/bigip\"]": visit complete
2025-02-20T10:21:06.866Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http (expand)": starting visit (*terraform.nodeExpandApplyableResource)
2025-02-20T10:21:06.866Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http (expand)": does not belong to any module instance
2025-02-20T10:21:06.866Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http (expand)": expanding dynamic subgraph
2025-02-20T10:21:06.866Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http (expand)": produced no dynamic subgraph
2025-02-20T10:21:06.866Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http (expand)": visit complete
2025-02-20T10:21:06.866Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http": starting visit (*terraform.NodeApplyableResourceInstance)
2025-02-20T10:21:06.867Z [TRACE] vertex "bigip_ltm_profile_http.sanjose-http": belongs to
2025-02-20T10:21:06.867Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.867Z [TRACE] readDiff: Read Create change from plan for bigip_ltm_profile_http.sanjose-http
2025-02-20T10:21:06.867Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.867Z [TRACE] readResourceInstanceState: reading state for bigip_ltm_profile_http.sanjose-http
2025-02-20T10:21:06.867Z [TRACE] readResourceInstanceState: no state present for bigip_ltm_profile_http.sanjose-http
2025-02-20T10:21:06.867Z [TRACE] readDiff: Read Create change from plan for bigip_ltm_profile_http.sanjose-http
2025-02-20T10:21:06.867Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.867Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.867Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.867Z [TRACE] Re-validating config for "bigip_ltm_profile_http.sanjose-http"
2025-02-20T10:21:06.867Z [TRACE] GRPCProvider: ValidateResourceConfig
2025-02-20T10:21:06.867Z [TRACE] GRPCProvider: GetProviderSchema
2025-02-20T10:21:06.868Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received request: tf_proto_version=5.3 tf_req_id=18eddc04-7e4d-a43d-2e37-c56a30b2f306 tf_resource_type=bigip_ltm_profile_http @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:679 @module=sdk.proto tf_provider_addr=provider tf_rpc=ValidateResourceTypeConfig timestamp=2025-02-20T10:21:06.868Z
2025-02-20T10:21:06.868Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Sending request downstream: tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=18eddc04-7e4d-a43d-2e37-c56a30b2f306 tf_rpc=ValidateResourceTypeConfig @module=sdk.proto tf_resource_type=bigip_ltm_profile_http @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 timestamp=2025-02-20T10:21:06.868Z
2025-02-20T10:21:06.868Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Calling downstream: @module=sdk.helper_schema tf_req_id=18eddc04-7e4d-a43d-2e37-c56a30b2f306 tf_resource_type=bigip_ltm_profile_http tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:245 tf_provider_addr=provider timestamp=2025-02-20T10:21:06.868Z
2025-02-20T10:21:06.869Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Called downstream: @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/grpc_provider.go:247 tf_resource_type=bigip_ltm_profile_http @module=sdk.helper_schema tf_provider_addr=provider tf_req_id=18eddc04-7e4d-a43d-2e37-c56a30b2f306 tf_rpc=ValidateResourceTypeConfig timestamp=2025-02-20T10:21:06.868Z
2025-02-20T10:21:06.869Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received downstream response: tf_proto_version=5.3 tf_rpc=ValidateResourceTypeConfig @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto diagnostic_error_count=0 tf_resource_type=bigip_ltm_profile_http diagnostic_warning_count=0 tf_provider_addr=provider tf_req_duration_ms=0 tf_req_id=18eddc04-7e4d-a43d-2e37-c56a30b2f306 timestamp=2025-02-20T10:21:06.869Z
2025-02-20T10:21:06.869Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Served request: @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_resource_type=bigip_ltm_profile_http @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:699 tf_req_id=18eddc04-7e4d-a43d-2e37-c56a30b2f306 tf_rpc=ValidateResourceTypeConfig timestamp=2025-02-20T10:21:06.869Z
2025-02-20T10:21:06.869Z [TRACE] GRPCProvider: PlanResourceChange
2025-02-20T10:21:06.869Z [TRACE] GRPCProvider: GetProviderSchema
2025-02-20T10:21:06.870Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received request: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:770 @module=sdk.proto tf_req_id=50d02e56-0bcb-9507-7b06-811f9dc80280 tf_resource_type=bigip_ltm_profile_http tf_proto_version=5.3 tf_provider_addr=provider tf_rpc=PlanResourceChange timestamp=2025-02-20T10:21:06.870Z
2025-02-20T10:21:06.870Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Sending request downstream: @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=50d02e56-0bcb-9507-7b06-811f9dc80280 tf_resource_type=bigip_ltm_profile_http tf_rpc=PlanResourceChange timestamp=2025-02-20T10:21:06.870Z
2025-02-20T10:21:06.874Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received downstream response: tf_resource_type=bigip_ltm_profile_http tf_rpc=PlanResourceChange tf_req_duration_ms=3 tf_proto_version=5.3 tf_provider_addr=provider tf_req_id=50d02e56-0bcb-9507-7b06-811f9dc80280 @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:37 @module=sdk.proto diagnostic_error_count=0 diagnostic_warning_count=0 timestamp=2025-02-20T10:21:06.873Z
2025-02-20T10:21:06.874Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Served request: @module=sdk.proto tf_proto_version=5.3 tf_resource_type=bigip_ltm_profile_http tf_rpc=PlanResourceChange @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:796 tf_provider_addr=provider tf_req_id=50d02e56-0bcb-9507-7b06-811f9dc80280 timestamp=2025-02-20T10:21:06.874Z
2025-02-20T10:21:06.874Z [WARN]  Provider "registry.terraform.io/f5networks/bigip" produced an invalid plan for bigip_ltm_profile_http.sanjose-http, but we are tolerating it because it is using the legacy plugin SDK.
    The following problems may be the cause of any confusing errors from downstream operations:
      - .enforcement: attribute representing nested block must not be unknown itself; set nested attribute values to unknown instead
      - .http_strict_transport_security: attribute representing nested block must not be unknown itself; set nested attribute values to unknown instead
2025-02-20T10:21:06.874Z [TRACE] checkPlannedChange: Verifying that actual change (action Create) matches planned change (action Create)
bigip_ltm_profile_http.sanjose-http: Creating...
2025-02-20T10:21:06.875Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.875Z [INFO]  Starting apply for bigip_ltm_profile_http.sanjose-http
2025-02-20T10:21:06.875Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.875Z [TRACE] terraform.contextPlugins: Schema for provider "registry.terraform.io/f5networks/bigip" is in the global cache
2025-02-20T10:21:06.875Z [DEBUG] bigip_ltm_profile_http.sanjose-http: applying the planned Create change
2025-02-20T10:21:06.875Z [TRACE] GRPCProvider: ApplyResourceChange
2025-02-20T10:21:06.875Z [TRACE] GRPCProvider: GetProviderSchema
2025-02-20T10:21:06.876Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Received request: @module=sdk.proto tf_provider_addr=provider tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/tf5server/server.go:805 tf_proto_version=5.3 tf_req_id=40cb2eb9-4a7c-e825-11e2-bee0918fef7c tf_resource_type=bigip_ltm_profile_http timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.876Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Sending request downstream: tf_provider_addr=provider tf_resource_type=bigip_ltm_profile_http tf_proto_version=5.3 tf_req_id=40cb2eb9-4a7c-e825-11e2-bee0918fef7c tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/terraform-plugin-go@v0.14.3/tfprotov5/internal/tf5serverlogging/downstream_request.go:17 @module=sdk.proto timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.876Z [DEBUG] provider.terraform-provider-bigip_v1.22.7: setting computed for "http_strict_transport_security" from ComputedKeys: timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.876Z [DEBUG] provider.terraform-provider-bigip_v1.22.7: setting computed for "enforcement" from ComputedKeys: timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.876Z [DEBUG] provider.terraform-provider-bigip_v1.22.7: setting computed for "xff_alternative_names" from ComputedKeys: timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.876Z [DEBUG] provider.terraform-provider-bigip_v1.22.7: setting computed for "response_headers_permitted" from ComputedKeys: timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.877Z [TRACE] provider.terraform-provider-bigip_v1.22.7: Calling downstream: @module=sdk.helper_schema tf_resource_type=bigip_ltm_profile_http tf_provider_addr=provider tf_req_id=40cb2eb9-4a7c-e825-11e2-bee0918fef7c tf_rpc=ApplyResourceChange @caller=github.com/hashicorp/terraform-plugin-sdk/v2@v2.25.0/helper/schema/resource.go:836 timestamp=2025-02-20T10:21:06.876Z
2025-02-20T10:21:06.877Z [INFO]  provider.terraform-provider-bigip_v1.22.7: Creating HTTP Profile:/Common/sanjose-http: timestamp=2025-02-20T10:21:06.877Z
2025-02-20T10:21:11.097Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/f5networks/bigip\"] (close)" is waiting for "bigip_ltm_profile_http.sanjose-http"
2025-02-20T10:21:11.098Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/f5networks/bigip\"] (close)"
2025-02-20T10:21:16.100Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/f5networks/bigip\"] (close)"
2025-02-20T10:21:16.100Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/f5networks/bigip\"] (close)" is waiting for "bigip_ltm_profile_http.sanjose-http"
bigip_ltm_profile_http.sanjose-http: Still creating... [10s elapsed]
2025-02-20T10:21:21.100Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/f5networks/bigip\"] (close)"
2025-02-20T10:21:21.100Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/f5networks/bigip\"] (close)" is waiting for "bigip_ltm_profile_http.sanjose-http"
2025-02-20T10:21:26.104Z [TRACE] dag/walk: vertex "provider[\"registry.terraform.io/f5networks/bigip\"] (close)" is waiting for "bigip_ltm_profile_http.sanjose-http"
2025-02-20T10:21:26.104Z [TRACE] dag/walk: vertex "root" is waiting for "provider[\"registry.terraform.io/f5networks/bigip\"] (close)"
bigip_ltm_profile_http.sanjose-http: Still creating... [20s elapsed]

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@emze9