Issue with disks upon restore/snapshot revert

mvandriessen commented 5 months ago

Community Guidelines

[X] I have read and agree to the HashiCorp Community Guidelines .
[X] Vote on this issue by adding a 👍 reaction to the original issue initial description to help the maintainers prioritize.
[X] Do not leave "+1" or other comments that do not add relevant information or questions.
[X] If you are interested in working on this issue or have submitted a pull request, please leave a comment.

Terraform

v1.8.2

Terraform Provider

v2.7.0

VMware vSphere

v7.0.3

Description

We've created a module for rolling out our VMs and we had used the following logic..

  disk {
    label       = "OS-disk"
    size        = 60
    unit_number = 0
  }
  dynamic "disk" {
    for_each = var.data_disks
    content {
      label       = disk.key
      size        = disk.value.size
      unit_number = index(keys(var.data_disks), disk.key) + 1
    }
  }

This resulted in all our disks after the OS-Disk to be named disk1, disk2, etc - this is the recommended disk naming scheme according to the documentation.

Turns out that when you restore a VM from backup or revert from a snapshot, vSphere defaults to setting the labels like "Hard disk 1" , "Hard disk 2", ... Since label is an immutable attribute, Terraform will destroy the disk and create a new one upon the next plan/apply.

The documentation should be changed to reflect this.

Affected Resources or Data Sources

resource/vsphere_virtual_machine

Terraform Configuration

  disk {
    label       = "OS-disk"
    size        = 60
    unit_number = 0
  }
  dynamic "disk" {
    for_each = var.data_disks
    content {
      label       = disk.key
      size        = disk.value.size
      unit_number = index(keys(var.data_disks), disk.key) + 1
    }
  }

In our tfvars file, the data_disks block looks like

   data_disks = {
      disk1 = {
        size = 200
      }
    }

Debug Output

https://gist.github.com/mvandriessen/4cdd48205c44ee8dcf05283ede551d67

Panic Output

No response

Expected Behavior

We expect labels to follow the naming convention as outlined in the documentation (disk0, disk1, disk2), in all scenarios.

Actual Behavior

Upon snapshot revert or backup restore, all hard disks receive the label "Hard disk 1", "Hard disk 2", ...

Steps to Reproduce

Create a virtual machine
Create a vSphere snapshot
Revert to snapshot
Trigger a plan

Environment Details

No response

Screenshots

No response

References

No response

github-actions[bot] commented 5 months ago

Hello, mvandriessen! 🖐

Thank you for submitting an issue for this provider. The issue will now enter into the issue lifecycle.

If you want to contribute to this project, please review the contributing guidelines and information on submitting pull requests.

tenthirtyam commented 4 months ago

Ref: #363 (Related to import requirements.)

Will add an update to the docs.

xtala commented 4 months ago

I think this isn't necessarily a documentation issue. The current requirements of disk label names (diskN), clash with vSpheres snapshot mechanism (which sets to label to Hard Disk N).

rdrgporto commented 1 month ago

Hi,

Any updates on this bug? The only way to fix it is by changing the label value to Hard Disk N. For example:

disk {
  label            = "Hard disk 1"
  size             = 20
  eagerly_scrub    = false
  thin_provisioned = true
  unit_number      = 0
}

Regards

burnsjared0415 commented 1 month ago

starting to look at this

burnsjared0415 commented 1 month ago

i am not getting this behavior when do this, i created a vm

resource "vsphere_virtual_machine" "vm" {
  name             = "foo"
  resource_pool_id = data.vsphere_compute_cluster.cluster.resource_pool_id
  datastore_id     = data.vsphere_datastore.datastore.id
  num_cpus         = 2
  memory           = 1024
  guest_id         = "ubuntu64Guest"
  wait_for_guest_net_routable = false
  wait_for_guest_net_timeout = 0
   network_interface {
    network_id   = data.vsphere_network.network.id
  }
   cdrom {
    datastore_id = data.vsphere_datastore.datastore.id
    path         = "/iso/ubuntu-22.04.4-live-server-amd64.iso"
  }
  disk {
    label       = "OS-disk"
    size        = 60
    unit_number = 0
  }
    disk {
    label       = "APP-disk"
    size        = 5
    unit_number = 1
  }
}

i took a snapshot, revert snapshot then did a terraform refresh and did a plan, no change to the state file or even change to the label, i only have one change which has to do with the path for the iso

rdrgporto commented 1 month ago

Hi, @burnsjared0415

I am facing a problem when importing the Terraform state from vSphere, as the disk label indicates that it has changed.

~ disk {
  ~ label             = "Hard disk 1" -> "<deleted>"
   # (20 unchanged attributes hidden)
}

The virtual machine was deployed with the following disk configuration:

disk {
  label            = "disk0"
  size             = "40"
  eagerly_scrub    = data.vsphere_virtual_machine.template.disks.0.eagerly_scrub
  thin_provisioned = data.vsphere_virtual_machine.template.disks.0.thin_provisioned
}

I conducted another test, deploying a machine with the following disk configuration:

disk {
  label            = "Hard disk 1"
  size             = "40"
  eagerly_scrub    = data.vsphere_virtual_machine.template.disks.0.eagerly_scrub
  thin_provisioned = data.vsphere_virtual_machine.template.disks.0.thin_provisioned
}

I deleted its state and imported it again (using the terraform import command). After running a terraform plan, the error no longer appears. Does vSphere needs Hard Disk 1 label?

It seems that something has changed, either in vSphere, Terraform, or the provider. This was not happening a few months ago.

Lastly, there is a related issue on this problem (https://github.com/hashicorp/terraform-provider-vsphere/issues/1944).

burnsjared0415 commented 1 month ago

What i am notices now, is that on import, if you lets say deployed the vm with a configuration like this:

  disk {
    label       = "OS-disk"
    size        = 60
    unit_number = 0
  }
    disk {
    label       = "APP-disk"
    size        = 5
    unit_number = 1
  }
    disk {
    label       = "test-disk"
    size        = 5
    unit_number = 2
  }

and then you import and then try to plan on that import it will delete the disk, if you change your tf config before import and to have


  disk {
    label       = "Hard disk 1"
    size        = 60
    unit_number = 0
  }
    disk {
    label       = "Hard disk 2"
    size        = 5
    unit_number = 1
  }
    disk {
    label       = "Hard disk 3"
    size        = 5
    unit_number = 2
  }
  So when import runs it will change the disk labels what they where deployed with to what it is expecting which is Hard disk x, i will see if i can figure out why it will not import differently but it looks like the default behavior might have changed if this was working a couple months ago, i will look at the related issue to see how they related.

rdrgporto commented 1 month ago

Hi @burnsjared0415,

Exactly, when running terraform plan, it indicates that the disk will be deleted. This behavior is a bit strange, as a few months ago I imported the state of a virtual machine without any issues.

From the beginning, I've always used disk.label = "disk0" as per recommendations. It seems that the recommendation should be changed to using "Hard Disk 1" from now on.

Thank you very much for your help!

Regards

burnsjared0415 commented 1 month ago

so what is interesting is when the import goes out it gets the default label, i need to figure out where the label is stored.

2024-10-02T08:43:53.819-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 08:43:53 [INFO] device description: &{{} Hard disk 1 62,914,560 KB}: timestamp=2024-10-02T08:43:53.819-0400
2024-10-02T08:43:53.819-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 08:43:53 [INFO] device label: Hard disk 1: timestamp=2024-10-02T08:43:53.819-0400
2024-10-02T08:43:53.819-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 08:43:53 [INFO] device description: &{{} Hard disk 2 5,242,880 KB}: timestamp=2024-10-02T08:43:53.819-0400
2024-10-02T08:43:53.819-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 08:43:53 [INFO] device label: Hard disk 2: timestamp=2024-10-02T08:43:53.819-0400
2024-10-02T08:43:53.819-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 08:43:53 [INFO] device description: &{{} Hard disk 3 5,242,880 KB}: timestamp=2024-10-02T08:43:53.819-0400
2024-10-02T08:43:53.819-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 08:43:53 [INFO] device label: Hard disk 3: timestamp=2024-10-02T08:43:53.819-0400

burnsjared0415 commented 1 month ago

i will update the documentation to reflect this change, not sure if it is a bug at this point, looks like just a change in the provider, if documentation change will work, i will make the updates and close out this Issue, please let me know if that will work.

burnsjared0415 commented 1 month ago

on the snapshot issue here is my create state:

{
  "version": 4,
  "terraform_version": "1.5.7",
  "serial": 1,
  "lineage": "f4122cc6-3043-a839-2427-9c9a94df3c07",
  "outputs": {},
  "resources": [
    {
      "mode": "data",
      "type": "vsphere_compute_cluster",
      "name": "cluster",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "datacenter_id": "datacenter-3",
            "id": "domain-c13",
            "name": "terraform-test-cl01",
            "resource_pool_id": "resgroup-14"
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "data",
      "type": "vsphere_datacenter",
      "name": "datacenter",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "id": "datacenter-3",
            "name": "terraform-dc01"
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "data",
      "type": "vsphere_datastore",
      "name": "datastore",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "datacenter_id": "datacenter-3",
            "id": "datastore-12",
            "name": "Datastore",
            "stats": {
              "capacity": "321854111744",
              "free": "270755954688"
            }
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "data",
      "type": "vsphere_network",
      "name": "network",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "datacenter_id": "datacenter-3",
            "distributed_virtual_switch_uuid": null,
            "id": "network-11",
            "name": "VM Network",
            "type": "Network"
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "managed",
      "type": "vsphere_virtual_machine",
      "name": "vm",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 3,
          "attributes": {
            "alternate_guest_name": "",
            "annotation": "",
            "boot_delay": 0,
            "boot_retry_delay": 10000,
            "boot_retry_enabled": false,
            "cdrom": [],
            "change_version": "2024-10-02T14:21:11.006941Z",
            "clone": [],
            "cpu_hot_add_enabled": false,
            "cpu_hot_remove_enabled": false,
            "cpu_limit": -1,
            "cpu_performance_counters_enabled": false,
            "cpu_reservation": 0,
            "cpu_share_count": 2000,
            "cpu_share_level": "normal",
            "custom_attributes": null,
            "datacenter_id": null,
            "datastore_cluster_id": null,
            "datastore_id": "datastore-12",
            "default_ip_address": null,
            "disk": [
              {
                "attach": false,
                "controller_type": "scsi",
                "datastore_id": "datastore-12",
                "device_address": "scsi:0:0",
                "disk_mode": "persistent",
                "disk_sharing": "sharingNone",
                "eagerly_scrub": false,
                "io_limit": -1,
                "io_reservation": 0,
                "io_share_count": 1000,
                "io_share_level": "normal",
                "keep_on_remove": false,
                "key": 2000,
                "label": "OS-Disk",
                "path": "foo1/foo1.vmdk",
                "size": 60,
                "storage_policy_id": "",
                "thin_provisioned": true,
                "unit_number": 0,
                "uuid": "6000C290-ec0d-e8d7-5f2a-76cc87b862fe",
                "write_through": false
              },
              {
                "attach": false,
                "controller_type": "scsi",
                "datastore_id": "datastore-12",
                "device_address": "scsi:0:1",
                "disk_mode": "persistent",
                "disk_sharing": "sharingNone",
                "eagerly_scrub": false,
                "io_limit": -1,
                "io_reservation": 0,
                "io_share_count": 1000,
                "io_share_level": "normal",
                "keep_on_remove": false,
                "key": 2001,
                "label": "APP-Disk",
                "path": "foo1/foo1_1.vmdk",
                "size": 5,
                "storage_policy_id": "",
                "thin_provisioned": true,
                "unit_number": 1,
                "uuid": "6000C292-f2bc-4694-a2e6-af496e1e1654",
                "write_through": false
              },
              {
                "attach": false,
                "controller_type": "scsi",
                "datastore_id": "datastore-12",
                "device_address": "scsi:0:2",
                "disk_mode": "persistent",
                "disk_sharing": "sharingNone",
                "eagerly_scrub": false,
                "io_limit": -1,
                "io_reservation": 0,
                "io_share_count": 1000,
                "io_share_level": "normal",
                "keep_on_remove": false,
                "key": 2002,
                "label": "DB-Disk",
                "path": "foo1/foo1_2.vmdk",
                "size": 5,
                "storage_policy_id": "",
                "thin_provisioned": true,
                "unit_number": 2,
                "uuid": "6000C292-3b64-679a-0d25-3e66eff0fadf",
                "write_through": false
              }
            ],
            "efi_secure_boot_enabled": false,
            "enable_disk_uuid": false,
            "enable_logging": false,
            "ept_rvi_mode": "automatic",
            "extra_config": null,
            "extra_config_reboot_required": true,
            "firmware": "bios",
            "folder": "",
            "force_power_off": true,
            "guest_id": "ubuntu64Guest",
            "guest_ip_addresses": [],
            "hardware_version": 19,
            "host_system_id": "host-10",
            "hv_mode": "hvAuto",
            "id": "42289028-9b65-856c-3d06-1d6ef9d873c8",
            "ide_controller_count": 2,
            "ignored_guest_ips": null,
            "imported": null,
            "latency_sensitivity": "normal",
            "memory": 1024,
            "memory_hot_add_enabled": false,
            "memory_limit": -1,
            "memory_reservation": 0,
            "memory_reservation_locked_to_max": false,
            "memory_share_count": 10240,
            "memory_share_level": "normal",
            "migrate_wait_timeout": 30,
            "moid": "vm-41",
            "name": "foo1",
            "nested_hv_enabled": false,
            "network_interface": [
              {
                "adapter_type": "vmxnet3",
                "bandwidth_limit": -1,
                "bandwidth_reservation": 0,
                "bandwidth_share_count": 50,
                "bandwidth_share_level": "normal",
                "device_address": "pci:0:7",
                "key": 4000,
                "mac_address": "00:50:56:a8:ec:7b",
                "network_id": "network-11",
                "ovf_mapping": "",
                "physical_function": "",
                "use_static_mac": false
              }
            ],
            "num_cores_per_socket": 1,
            "num_cpus": 2,
            "ovf_deploy": [],
            "pci_device_id": null,
            "power_state": "on",
            "poweron_timeout": 300,
            "reboot_required": false,
            "replace_trigger": null,
            "resource_pool_id": "resgroup-14",
            "run_tools_scripts_after_power_on": true,
            "run_tools_scripts_after_resume": true,
            "run_tools_scripts_before_guest_reboot": false,
            "run_tools_scripts_before_guest_shutdown": true,
            "run_tools_scripts_before_guest_standby": true,
            "sata_controller_count": 0,
            "scsi_bus_sharing": "noSharing",
            "scsi_controller_count": 1,
            "scsi_type": "pvscsi",
            "shutdown_wait_timeout": 3,
            "storage_policy_id": "",
            "swap_placement_policy": "inherit",
            "sync_time_with_host": true,
            "sync_time_with_host_periodically": false,
            "tags": null,
            "tools_upgrade_policy": "manual",
            "uuid": "42289028-9b65-856c-3d06-1d6ef9d873c8",
            "vapp": [],
            "vapp_transport": [],
            "vbs_enabled": false,
            "vmware_tools_status": "guestToolsNotRunning",
            "vmx_path": "foo1/foo1.vmx",
            "vvtd_enabled": false,
            "wait_for_guest_ip_timeout": 0,
            "wait_for_guest_net_routable": false,
            "wait_for_guest_net_timeout": 0
          },
          "sensitive_attributes": [],
          "private": "eyJzY2hlbWFfdmVyc2lvbiI6IjMifQ==",
          "dependencies": [
            "data.vsphere_compute_cluster.cluster",
            "data.vsphere_datastore.datastore",
            "data.vsphere_network.network"
          ]
        }
      ]
    }
  ],
  "check_results": null
}

Here is the state file:

burnsja@C02Z962BLVDQ test_snap % TF_LOG=DEBUG terraform plan
2024-10-02T10:27:46.224-0400 [INFO]  Terraform version: 1.5.7
2024-10-02T10:27:46.225-0400 [DEBUG] using github.com/hashicorp/go-tfe v1.26.0
2024-10-02T10:27:46.225-0400 [DEBUG] using github.com/hashicorp/hcl/v2 v2.16.2
2024-10-02T10:27:46.225-0400 [DEBUG] using github.com/hashicorp/terraform-svchost v0.1.0
2024-10-02T10:27:46.225-0400 [DEBUG] using github.com/zclconf/go-cty v1.12.2
2024-10-02T10:27:46.225-0400 [INFO]  Go runtime version: go1.22.2
2024-10-02T10:27:46.225-0400 [INFO]  CLI args: []string{"terraform", "plan"}
2024-10-02T10:27:46.225-0400 [DEBUG] Attempting to open CLI config file: /Users/burnsja/.terraformrc
2024-10-02T10:27:46.225-0400 [INFO]  Loading CLI configuration from /Users/burnsja/.terraformrc
2024-10-02T10:27:46.225-0400 [DEBUG] Explicit provider installation configuration is set
2024-10-02T10:27:46.226-0400 [INFO]  CLI command args: []string{"plan"}
2024-10-02T10:27:46.228-0400 [DEBUG] New state was assigned lineage "97e361a7-f251-ae87-822f-f91f5618cf63"
2024-10-02T10:27:46.228-0400 [DEBUG] Provider registry.terraform.io/vmware/vcf is overridden by dev_overrides
2024-10-02T10:27:46.228-0400 [DEBUG] Provider registry.terraform.io/hashicorp/vsphere is overridden by dev_overrides
2024-10-02T10:27:46.228-0400 [DEBUG] Provider registry.terraform.io/vmware/vcf is overridden to load from /Users/burnsja/Documents/GitHub/terraform-provider-vcf
2024-10-02T10:27:46.228-0400 [DEBUG] Provider registry.terraform.io/hashicorp/vsphere is overridden to load from /Users/burnsja/Documents/GitHub/terraform-provider-vsphere
2024-10-02T10:27:46.229-0400 [DEBUG] checking for provisioner in "."
2024-10-02T10:27:46.235-0400 [DEBUG] checking for provisioner in "/usr/local/bin"
2024-10-02T10:27:46.235-0400 [DEBUG] Provider registry.terraform.io/vmware/vcf is overridden by dev_overrides
2024-10-02T10:27:46.235-0400 [DEBUG] Provider registry.terraform.io/hashicorp/vsphere is overridden by dev_overrides
╷
│ Warning: Provider development overrides are in effect
│ 
│ The following provider development overrides are set in the CLI configuration:
│  - vmware/vcf in /Users/burnsja/Documents/GitHub/terraform-provider-vcf
│  - hashicorp/vsphere in /Users/burnsja/Documents/GitHub/terraform-provider-vsphere
│ 
│ The behavior may therefore not match any released version of the provider and applying changes may cause the state to become incompatible with published releases.
╵
2024-10-02T10:27:46.236-0400 [INFO]  backend/local: starting Plan operation
2024-10-02T10:27:46.242-0400 [DEBUG] Config.VerifyDependencySelections: skipping registry.terraform.io/hashicorp/vsphere because it's overridden by a special configuration setting
2024-10-02T10:27:46.243-0400 [DEBUG] created provider logger: level=debug
2024-10-02T10:27:46.243-0400 [INFO]  provider: configuring client automatic mTLS
2024-10-02T10:27:46.258-0400 [DEBUG] provider: starting plugin: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere args=[/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere]
2024-10-02T10:27:46.266-0400 [DEBUG] provider: plugin started: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1132
2024-10-02T10:27:46.266-0400 [DEBUG] provider: waiting for RPC address: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere
2024-10-02T10:27:46.296-0400 [INFO]  provider.terraform-provider-vsphere: configuring server automatic mTLS: timestamp=2024-10-02T10:27:46.295-0400
2024-10-02T10:27:46.312-0400 [DEBUG] provider.terraform-provider-vsphere: plugin address: address=/var/folders/rr/5vtqxt696ld77msj7d9v03v40000gp/T/plugin2293417600 network=unix timestamp=2024-10-02T10:27:46.312-0400
2024-10-02T10:27:46.312-0400 [DEBUG] provider: using plugin: version=5
2024-10-02T10:27:46.337-0400 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-10-02T10:27:46.339-0400 [DEBUG] provider: plugin process exited: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1132
2024-10-02T10:27:46.339-0400 [DEBUG] provider: plugin exited
2024-10-02T10:27:46.339-0400 [DEBUG] Building and walking validate graph
2024-10-02T10:27:46.339-0400 [DEBUG] ProviderTransformer: "data.vsphere_network.network" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.339-0400 [DEBUG] ProviderTransformer: "data.vsphere_datastore.datastore" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.339-0400 [DEBUG] ProviderTransformer: "vsphere_virtual_machine.vm" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.339-0400 [DEBUG] ProviderTransformer: "data.vsphere_datacenter.datacenter" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.339-0400 [DEBUG] ProviderTransformer: "data.vsphere_compute_cluster.cluster" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.340-0400 [DEBUG] ReferenceTransformer: "vsphere_virtual_machine.vm" references: [data.vsphere_datastore.datastore data.vsphere_compute_cluster.cluster data.vsphere_network.network]
2024-10-02T10:27:46.340-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datacenter.datacenter" references: []
2024-10-02T10:27:46.340-0400 [DEBUG] ReferenceTransformer: "data.vsphere_compute_cluster.cluster" references: [data.vsphere_datacenter.datacenter]
2024-10-02T10:27:46.340-0400 [DEBUG] ReferenceTransformer: "data.vsphere_network.network" references: [data.vsphere_datacenter.datacenter]
2024-10-02T10:27:46.340-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datastore.datastore" references: [data.vsphere_datacenter.datacenter]
2024-10-02T10:27:46.340-0400 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/vsphere\"]" references: []
2024-10-02T10:27:46.341-0400 [DEBUG] Starting graph walk: walkValidate
2024-10-02T10:27:46.341-0400 [DEBUG] created provider logger: level=debug
2024-10-02T10:27:46.341-0400 [INFO]  provider: configuring client automatic mTLS
2024-10-02T10:27:46.348-0400 [DEBUG] provider: starting plugin: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere args=[/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere]
2024-10-02T10:27:46.356-0400 [DEBUG] provider: plugin started: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1133
2024-10-02T10:27:46.356-0400 [DEBUG] provider: waiting for RPC address: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere
2024-10-02T10:27:46.388-0400 [INFO]  provider.terraform-provider-vsphere: configuring server automatic mTLS: timestamp=2024-10-02T10:27:46.387-0400
2024-10-02T10:27:46.404-0400 [DEBUG] provider.terraform-provider-vsphere: plugin address: address=/var/folders/rr/5vtqxt696ld77msj7d9v03v40000gp/T/plugin3901401187 network=unix timestamp=2024-10-02T10:27:46.404-0400
2024-10-02T10:27:46.404-0400 [DEBUG] provider: using plugin: version=5
2024-10-02T10:27:46.434-0400 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-10-02T10:27:46.436-0400 [DEBUG] provider: plugin process exited: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1133
2024-10-02T10:27:46.436-0400 [DEBUG] provider: plugin exited
2024-10-02T10:27:46.436-0400 [INFO]  backend/local: plan calling Plan
2024-10-02T10:27:46.436-0400 [DEBUG] Building and walking plan graph for NormalMode
2024-10-02T10:27:46.436-0400 [DEBUG] ProviderTransformer: "data.vsphere_datacenter.datacenter (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.436-0400 [DEBUG] ProviderTransformer: "data.vsphere_compute_cluster.cluster (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.436-0400 [DEBUG] ProviderTransformer: "data.vsphere_network.network (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.436-0400 [DEBUG] ProviderTransformer: "data.vsphere_datastore.datastore (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.436-0400 [DEBUG] ProviderTransformer: "vsphere_virtual_machine.vm (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:46.436-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datacenter.datacenter (expand)" references: []
2024-10-02T10:27:46.436-0400 [DEBUG] ReferenceTransformer: "data.vsphere_compute_cluster.cluster (expand)" references: [data.vsphere_datacenter.datacenter (expand)]
2024-10-02T10:27:46.436-0400 [DEBUG] ReferenceTransformer: "data.vsphere_network.network (expand)" references: [data.vsphere_datacenter.datacenter (expand)]
2024-10-02T10:27:46.436-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datastore.datastore (expand)" references: [data.vsphere_datacenter.datacenter (expand)]
2024-10-02T10:27:46.436-0400 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/vsphere\"]" references: []
2024-10-02T10:27:46.437-0400 [DEBUG] ReferenceTransformer: "vsphere_virtual_machine.vm (expand)" references: [data.vsphere_datastore.datastore (expand) data.vsphere_compute_cluster.cluster (expand) data.vsphere_network.network (expand)]
2024-10-02T10:27:46.437-0400 [DEBUG] Starting graph walk: walkPlan
2024-10-02T10:27:46.438-0400 [DEBUG] created provider logger: level=debug
2024-10-02T10:27:46.438-0400 [INFO]  provider: configuring client automatic mTLS
2024-10-02T10:27:46.444-0400 [DEBUG] provider: starting plugin: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere args=[/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere]
2024-10-02T10:27:46.452-0400 [DEBUG] provider: plugin started: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1135
2024-10-02T10:27:46.452-0400 [DEBUG] provider: waiting for RPC address: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere
2024-10-02T10:27:46.483-0400 [INFO]  provider.terraform-provider-vsphere: configuring server automatic mTLS: timestamp=2024-10-02T10:27:46.483-0400
2024-10-02T10:27:46.500-0400 [DEBUG] provider: using plugin: version=5
2024-10-02T10:27:46.500-0400 [DEBUG] provider.terraform-provider-vsphere: plugin address: address=/var/folders/rr/5vtqxt696ld77msj7d9v03v40000gp/T/plugin3271504169 network=unix timestamp=2024-10-02T10:27:46.500-0400
2024-10-02T10:27:46.523-0400 [WARN]  ValidateProviderConfig from "provider[\"registry.terraform.io/hashicorp/vsphere\"]" changed the config value, but that value is unused
2024-10-02T10:27:46.524-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:46 [DEBUG] Cached SOAP client session data not valid or persistence not enabled, new session necessary: timestamp=2024-10-02T10:27:46.524-0400
2024-10-02T10:27:46.524-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:46 [DEBUG] Creating new SOAP API session on endpoint jbvcftervc02.vsanpe.vmware.com: timestamp=2024-10-02T10:27:46.524-0400
2024-10-02T10:27:47.254-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:47 [DEBUG] SOAP API session creation successful: timestamp=2024-10-02T10:27:47.254-0400
2024-10-02T10:27:47.254-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:47 [DEBUG] VMWare vSphere Client configured for URL: jbvcftervc02.vsanpe.vmware.com: timestamp=2024-10-02T10:27:47.254-0400
2024-10-02T10:27:47.254-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:47 [DEBUG] Setting up REST client: timestamp=2024-10-02T10:27:47.254-0400
2024-10-02T10:27:47.874-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:47 [DEBUG] CIS REST client configuration successful: timestamp=2024-10-02T10:27:47.874-0400
2024-10-02T10:27:47.986-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datacenter.datacenter" references: []
data.vsphere_datacenter.datacenter: Reading...
data.vsphere_datacenter.datacenter: Read complete after 0s [id=datacenter-3]
2024-10-02T10:27:48.210-0400 [DEBUG] ReferenceTransformer: "data.vsphere_compute_cluster.cluster" references: []
2024-10-02T10:27:48.210-0400 [DEBUG] ReferenceTransformer: "data.vsphere_network.network" references: []
2024-10-02T10:27:48.210-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datastore.datastore" references: []
data.vsphere_datastore.datastore: Reading...
data.vsphere_compute_cluster.cluster: Reading...
data.vsphere_network.network: Reading...
2024-10-02T10:27:48.322-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:48 [DEBUG] Looking for cluster "terraform-test-cl01" in datacenter "/terraform-dc01": timestamp=2024-10-02T10:27:48.322-0400
2024-10-02T10:27:48.322-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:48 [DEBUG] Attempting to locate compute cluster "terraform-test-cl01" in datacenter "/terraform-dc01": timestamp=2024-10-02T10:27:48.322-0400
data.vsphere_network.network: Read complete after 1s [id=network-11]
data.vsphere_datastore.datastore: Read complete after 1s [id=datastore-12]
data.vsphere_compute_cluster.cluster: Read complete after 1s [id=domain-c13]
2024-10-02T10:27:49.013-0400 [DEBUG] ReferenceTransformer: "vsphere_virtual_machine.vm" references: []
vsphere_virtual_machine.vm: Refreshing state... [id=42289028-9b65-856c-3d06-1d6ef9d873c8]
2024-10-02T10:27:49.023-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Reading state of virtual machine: timestamp=2024-10-02T10:27:49.023-0400
2024-10-02T10:27:49.023-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:49.023-0400
2024-10-02T10:27:49.023-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:49.023-0400
2024-10-02T10:27:49.245-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:49.245-0400
2024-10-02T10:27:49.245-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Fetching properties for VM "/terraform-dc01/vm/foo1": timestamp=2024-10-02T10:27:49.245-0400
2024-10-02T10:27:49.361-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] MOID for VM "/terraform-dc01/vm/foo1" is "vm-41": timestamp=2024-10-02T10:27:49.361-0400
2024-10-02T10:27:49.361-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Locating vApp container with ID resgroup-14: timestamp=2024-10-02T10:27:49.361-0400
2024-10-02T10:27:49.466-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Locating datastore with ID "datastore-12": timestamp=2024-10-02T10:27:49.466-0400
2024-10-02T10:27:49.578-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Datastore with ID "datastore-12" found: timestamp=2024-10-02T10:27:49.578-0400
2024-10-02T10:27:49.796-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualMachine] and key [vm-41].: timestamp=2024-10-02T10:27:49.796-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] ReadSCSIBusType: SCSI controller layout for first 1 controllers: pvscsi-1000: timestamp=2024-10-02T10:27:49.923-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] ReadSCSIBusSharing: SCSI controller layout for first 1 controllers: pvscsi-1000: timestamp=2024-10-02T10:27:49.923-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Beginning refresh: timestamp=2024-10-02T10:27:49.923-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Found controller "controller-1000" for device "disk-1000-0": timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Found controller "controller-1000" for device "disk-1000-1": timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Found controller "controller-1000" for device "disk-1000-2": timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Disk devices located: disk-1000-0,disk-1000-1,disk-1000-2: timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Current resource set from state: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Looking for freshly-created or re-assigned resources to read in: timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Disk devices after created/re-assigned device search: disk-1000-0,disk-1000-1,disk-1000-2: timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Resource set to write after created/re-assigned device search:: timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] DiskRefreshOperation: Looking for devices known in state: timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1.vmdk): Reading state: timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:49.924-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:49 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:49.924-0400
2024-10-02T10:27:50.140-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:50.140-0400
2024-10-02T10:27:50.250-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualDiskId] and key [vm-41:2000].: timestamp=2024-10-02T10:27:50.250-0400
2024-10-02T10:27:50.367-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Read finished (key and device address may have changed): timestamp=2024-10-02T10:27:50.367-0400
2024-10-02T10:27:50.367-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1.vmdk): Reading state: timestamp=2024-10-02T10:27:50.367-0400
2024-10-02T10:27:50.367-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:50.367-0400
2024-10-02T10:27:50.367-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:50.367-0400
2024-10-02T10:27:50.583-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:50.583-0400
2024-10-02T10:27:50.692-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualDiskId] and key [vm-41:2001].: timestamp=2024-10-02T10:27:50.692-0400
2024-10-02T10:27:50.811-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Read finished (key and device address may have changed): timestamp=2024-10-02T10:27:50.811-0400
2024-10-02T10:27:50.811-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2.vmdk): Reading state: timestamp=2024-10-02T10:27:50.811-0400
2024-10-02T10:27:50.811-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:50.811-0400
2024-10-02T10:27:50.811-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:50 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:50.811-0400
2024-10-02T10:27:51.031-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:51.031-0400
2024-10-02T10:27:51.141-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualDiskId] and key [vm-41:2002].: timestamp=2024-10-02T10:27:51.141-0400
2024-10-02T10:27:51.260-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Read finished (key and device address may have changed): timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.260-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskRefreshOperation: Resource set to write after known device search: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.260-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskRefreshOperation: Probable orphaned disk devices:: timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.260-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskRefreshOperation: Adding orphaned devices: timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.260-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskRefreshOperation: Resource set to write after adding orphaned devices: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskRefreshOperation: Final (sorted) resource set to write: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskRefreshOperation: Refresh operation complete, sending new resource set: timestamp=2024-10-02T10:27:51.260-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Beginning refresh: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Network devices located: ethernet-0: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Current resource set from state: (key 4000 at pci:0:7): timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Looking for freshly-created resources to read in: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Network devices after freshly-created device search: ethernet-0: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Resource sets to write after known device search:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Looking for devices known in state: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Reading state: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] FindVirtualDevice: Looking for device with key 4000: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] FindVirtualDevice: Device found: ethernet-0: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] Read: Adapter type not SR-IOV: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Read finished (key and device address may have changed): timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Resource sets to write after known device search: (key 4000 at pci:0:7): timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Probable orphaned network interfaces:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Adding orphaned devices: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: 0 devices and a new set of length 1: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Resource set to write after adding orphaned devices: (key 4000 at pci:0:7): timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceRefreshOperation: Refresh operation complete, sending new resource set: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Beginning refresh: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: CDROM devices located:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Current resource set from state:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Looking for freshly-created resources to read in: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: CDROM devices after freshly-created device search:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Resource set to write after freshly-created device search:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Looking for devices known in state: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Resource set to write after known device search:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Probable orphaned CDROM devices:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Resource set to write after adding orphaned devices:: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] CdromRefreshOperation: Refresh operation complete, sending new resource set: timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.261-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] Reading tags for object "vm-41": timestamp=2024-10-02T10:27:51.261-0400
2024-10-02T10:27:51.475-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] Tags for object "vm-41":: timestamp=2024-10-02T10:27:51.475-0400
2024-10-02T10:27:51.476-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Checking guest networking state: timestamp=2024-10-02T10:27:51.475-0400
2024-10-02T10:27:51.476-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): No IP addresses found in guest state: timestamp=2024-10-02T10:27:51.475-0400
2024-10-02T10:27:51.476-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Read complete: timestamp=2024-10-02T10:27:51.475-0400
2024-10-02T10:27:51.480-0400 [WARN]  Provider "registry.terraform.io/hashicorp/vsphere" produced an unexpected new value for vsphere_virtual_machine.vm during refresh.
      - .pci_device_id: was null, but now cty.SetValEmpty(cty.String)
      - .custom_attributes: was null, but now cty.MapValEmpty(cty.String)
      - .extra_config: was null, but now cty.MapValEmpty(cty.String)
      - .tags: was null, but now cty.SetValEmpty(cty.String)
      - .power_state: was cty.StringVal("on"), but now cty.StringVal("off")
      - .disk[0].path: was cty.StringVal("foo1/foo1.vmdk"), but now cty.StringVal("foo1/foo1-000002.vmdk")
      - .disk[1].path: was cty.StringVal("foo1/foo1_1.vmdk"), but now cty.StringVal("foo1/foo1_1-000002.vmdk")
      - .disk[2].path: was cty.StringVal("foo1/foo1_2.vmdk"), but now cty.StringVal("foo1/foo1_2-000002.vmdk")
2024-10-02T10:27:51.496-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Performing diff customization and validation: timestamp=2024-10-02T10:27:51.496-0400
2024-10-02T10:27:51.496-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceDiffOperation: Beginning diff validation: timestamp=2024-10-02T10:27:51.496-0400
2024-10-02T10:27:51.496-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Beginning diff validation: timestamp=2024-10-02T10:27:51.496-0400
2024-10-02T10:27:51.496-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Diff validation complete: timestamp=2024-10-02T10:27:51.496-0400
2024-10-02T10:27:51.496-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] NetworkInterfaceDiffOperation: Diff validation complete: timestamp=2024-10-02T10:27:51.496-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskDiffOperation: Beginning disk diff customization: timestamp=2024-10-02T10:27:51.496-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskDiffOperation: Beginning collective diff validation (indexes aligned to new config): timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Beginning diff validation: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Diff validation complete: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Beginning diff validation: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Diff validation complete: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Beginning diff validation: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Diff validation complete: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskDiffOperation: Beginning diff validation and normalization (indexes aligned to old state): timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Beginning normalization of existing disk: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Normalization of existing disk diff complete: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Beginning normalization of existing disk: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Normalization of existing disk diff complete: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Beginning normalization of existing disk: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Normalization of existing disk diff complete: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskDiffOperation: New resource set post-normalization: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.497-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] DiskDiffOperation: Disk diff customization complete, sending new diff: timestamp=2024-10-02T10:27:51.497-0400
2024-10-02T10:27:51.498-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] VAppDiffOperation: Verifying configuration meets requirements for vApp transport: timestamp=2024-10-02T10:27:51.498-0400
2024-10-02T10:27:51.498-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] VAppDiffOperation: ISO transport is not supported on this virtual machine or multiple transport options exist: timestamp=2024-10-02T10:27:51.498-0400
2024-10-02T10:27:51.498-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:51 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Diff customization and validation complete: timestamp=2024-10-02T10:27:51.498-0400
2024-10-02T10:27:51.500-0400 [WARN]  Provider "registry.terraform.io/hashicorp/vsphere" produced an invalid plan for vsphere_virtual_machine.vm, 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:
      - .force_power_off: planned value cty.True for a non-computed attribute
      - .poweron_timeout: planned value cty.NumberIntVal(300) for a non-computed attribute
      - .shutdown_wait_timeout: planned value cty.NumberIntVal(3) for a non-computed attribute
      - .boot_delay: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .cpu_hot_add_enabled: planned value cty.False for a non-computed attribute
      - .memory_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .run_tools_scripts_after_resume: planned value cty.True for a non-computed attribute
      - .run_tools_scripts_after_power_on: planned value cty.True for a non-computed attribute
      - .sync_time_with_host_periodically: planned value cty.False for a non-computed attribute
      - .efi_secure_boot_enabled: planned value cty.False for a non-computed attribute
      - .pci_device_id: planned value cty.SetValEmpty(cty.String) for a non-computed attribute
      - .sync_time_with_host: planned value cty.True for a non-computed attribute
      - .tools_upgrade_policy: planned value cty.StringVal("manual") for a non-computed attribute
      - .cpu_hot_remove_enabled: planned value cty.False for a non-computed attribute
      - .enable_logging: planned value cty.False for a non-computed attribute
      - .scsi_bus_sharing: planned value cty.StringVal("noSharing") for a non-computed attribute
      - .cpu_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .memory_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .memory_reservation_locked_to_max: planned value cty.False for a non-computed attribute
      - .num_cores_per_socket: planned value cty.NumberIntVal(1) for a non-computed attribute
      - .scsi_type: planned value cty.StringVal("pvscsi") for a non-computed attribute
      - .swap_placement_policy: planned value cty.StringVal("inherit") for a non-computed attribute
      - .cpu_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .extra_config_reboot_required: planned value cty.True for a non-computed attribute
      - .sata_controller_count: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .extra_config: planned value cty.MapValEmpty(cty.String) for a non-computed attribute
      - .latency_sensitivity: planned value cty.StringVal("normal") for a non-computed attribute
      - .tags: planned value cty.SetValEmpty(cty.String) for a non-computed attribute
      - .alternate_guest_name: planned value cty.StringVal("") for a non-computed attribute
      - .boot_retry_delay: planned value cty.NumberIntVal(10000) for a non-computed attribute
      - .boot_retry_enabled: planned value cty.False for a non-computed attribute
      - .custom_attributes: planned value cty.MapValEmpty(cty.String) for a non-computed attribute
      - .run_tools_scripts_before_guest_reboot: planned value cty.False for a non-computed attribute
      - .vbs_enabled: planned value cty.False for a non-computed attribute
      - .run_tools_scripts_before_guest_standby: planned value cty.True for a non-computed attribute
      - .vvtd_enabled: planned value cty.False for a non-computed attribute
      - .nested_hv_enabled: planned value cty.False for a non-computed attribute
      - .wait_for_guest_ip_timeout: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .cpu_performance_counters_enabled: planned value cty.False for a non-computed attribute
      - .memory_hot_add_enabled: planned value cty.False for a non-computed attribute
      - .migrate_wait_timeout: planned value cty.NumberIntVal(30) for a non-computed attribute
      - .cpu_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .folder: planned value cty.StringVal("") for a non-computed attribute
      - .memory_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .run_tools_scripts_before_guest_shutdown: planned value cty.True for a non-computed attribute
      - .firmware: planned value cty.StringVal("bios") for a non-computed attribute
      - .ide_controller_count: planned value cty.NumberIntVal(2) for a non-computed attribute
      - .enable_disk_uuid: planned value cty.False for a non-computed attribute
      - .scsi_controller_count: planned value cty.NumberIntVal(1) for a non-computed attribute
      - .disk[0].io_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .disk[0].attach: planned value cty.False for a non-computed attribute
      - .disk[0].disk_mode: planned value cty.StringVal("persistent") for a non-computed attribute
      - .disk[0].disk_sharing: planned value cty.StringVal("sharingNone") for a non-computed attribute
      - .disk[0].controller_type: planned value cty.StringVal("scsi") for a non-computed attribute
      - .disk[0].io_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .disk[0].eagerly_scrub: planned value cty.False for a non-computed attribute
      - .disk[0].write_through: planned value cty.False for a non-computed attribute
      - .disk[0].io_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .disk[0].keep_on_remove: planned value cty.False for a non-computed attribute
      - .disk[0].thin_provisioned: planned value cty.True for a non-computed attribute
      - .disk[0].io_share_count: planned value cty.NumberIntVal(1000) for a non-computed attribute
      - .disk[1].write_through: planned value cty.False for a non-computed attribute
      - .disk[1].eagerly_scrub: planned value cty.False for a non-computed attribute
      - .disk[1].io_share_count: planned value cty.NumberIntVal(1000) for a non-computed attribute
      - .disk[1].io_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .disk[1].keep_on_remove: planned value cty.False for a non-computed attribute
      - .disk[1].thin_provisioned: planned value cty.True for a non-computed attribute
      - .disk[1].disk_mode: planned value cty.StringVal("persistent") for a non-computed attribute
      - .disk[1].disk_sharing: planned value cty.StringVal("sharingNone") for a non-computed attribute
      - .disk[1].io_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .disk[1].attach: planned value cty.False for a non-computed attribute
      - .disk[1].io_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .disk[1].controller_type: planned value cty.StringVal("scsi") for a non-computed attribute
      - .disk[2].controller_type: planned value cty.StringVal("scsi") for a non-computed attribute
      - .disk[2].io_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .disk[2].eagerly_scrub: planned value cty.False for a non-computed attribute
      - .disk[2].write_through: planned value cty.False for a non-computed attribute
      - .disk[2].io_share_count: planned value cty.NumberIntVal(1000) for a non-computed attribute
      - .disk[2].io_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .disk[2].keep_on_remove: planned value cty.False for a non-computed attribute
      - .disk[2].thin_provisioned: planned value cty.True for a non-computed attribute
      - .disk[2].attach: planned value cty.False for a non-computed attribute
      - .disk[2].disk_mode: planned value cty.StringVal("persistent") for a non-computed attribute
      - .disk[2].disk_sharing: planned value cty.StringVal("sharingNone") for a non-computed attribute
      - .disk[2].io_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .network_interface[0].bandwidth_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .network_interface[0].physical_function: planned value cty.StringVal("") for a non-computed attribute
      - .network_interface[0].use_static_mac: planned value cty.False for a non-computed attribute
      - .network_interface[0].ovf_mapping: planned value cty.StringVal("") for a non-computed attribute
      - .network_interface[0].adapter_type: planned value cty.StringVal("vmxnet3") for a non-computed attribute
      - .network_interface[0].bandwidth_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .network_interface[0].bandwidth_share_level: planned value cty.StringVal("normal") for a non-computed attribute
2024-10-02T10:27:51.504-0400 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-10-02T10:27:51.507-0400 [DEBUG] provider: plugin process exited: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1135
2024-10-02T10:27:51.507-0400 [DEBUG] provider: plugin exited
2024-10-02T10:27:51.510-0400 [DEBUG] no planned changes, skipping apply graph check
2024-10-02T10:27:51.510-0400 [INFO]  backend/local: plan operation completed

No changes. Your infrastructure matches the configuration.

Terraform has compared your real infrastructure against your configuration and found no differences, so no changes are needed.
burnsja@C02Z962BLVDQ test_snap % TF_LOG=DEBUG terraform refresh
2024-10-02T10:27:57.051-0400 [INFO]  Terraform version: 1.5.7
2024-10-02T10:27:57.051-0400 [DEBUG] using github.com/hashicorp/go-tfe v1.26.0
2024-10-02T10:27:57.051-0400 [DEBUG] using github.com/hashicorp/hcl/v2 v2.16.2
2024-10-02T10:27:57.051-0400 [DEBUG] using github.com/hashicorp/terraform-svchost v0.1.0
2024-10-02T10:27:57.051-0400 [DEBUG] using github.com/zclconf/go-cty v1.12.2
2024-10-02T10:27:57.051-0400 [INFO]  Go runtime version: go1.22.2
2024-10-02T10:27:57.051-0400 [INFO]  CLI args: []string{"terraform", "refresh"}
2024-10-02T10:27:57.051-0400 [DEBUG] Attempting to open CLI config file: /Users/burnsja/.terraformrc
2024-10-02T10:27:57.052-0400 [INFO]  Loading CLI configuration from /Users/burnsja/.terraformrc
2024-10-02T10:27:57.052-0400 [DEBUG] Explicit provider installation configuration is set
2024-10-02T10:27:57.053-0400 [INFO]  CLI command args: []string{"refresh"}
2024-10-02T10:27:57.054-0400 [DEBUG] New state was assigned lineage "b34703f5-b22e-5a89-eaea-862349364dbc"
2024-10-02T10:27:57.055-0400 [DEBUG] Provider registry.terraform.io/vmware/vcf is overridden by dev_overrides
2024-10-02T10:27:57.055-0400 [DEBUG] Provider registry.terraform.io/hashicorp/vsphere is overridden by dev_overrides
2024-10-02T10:27:57.055-0400 [DEBUG] Provider registry.terraform.io/vmware/vcf is overridden to load from /Users/burnsja/Documents/GitHub/terraform-provider-vcf
2024-10-02T10:27:57.055-0400 [DEBUG] Provider registry.terraform.io/hashicorp/vsphere is overridden to load from /Users/burnsja/Documents/GitHub/terraform-provider-vsphere
2024-10-02T10:27:57.055-0400 [DEBUG] checking for provisioner in "."
2024-10-02T10:27:57.061-0400 [DEBUG] checking for provisioner in "/usr/local/bin"
2024-10-02T10:27:57.061-0400 [DEBUG] Provider registry.terraform.io/vmware/vcf is overridden by dev_overrides
2024-10-02T10:27:57.061-0400 [DEBUG] Provider registry.terraform.io/hashicorp/vsphere is overridden by dev_overrides
2024-10-02T10:27:57.068-0400 [DEBUG] Config.VerifyDependencySelections: skipping registry.terraform.io/hashicorp/vsphere because it's overridden by a special configuration setting
2024-10-02T10:27:57.069-0400 [DEBUG] created provider logger: level=debug
2024-10-02T10:27:57.069-0400 [INFO]  provider: configuring client automatic mTLS
2024-10-02T10:27:57.083-0400 [DEBUG] provider: starting plugin: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere args=[/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere]
2024-10-02T10:27:57.090-0400 [DEBUG] provider: plugin started: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1327
2024-10-02T10:27:57.090-0400 [DEBUG] provider: waiting for RPC address: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere
2024-10-02T10:27:57.121-0400 [INFO]  provider.terraform-provider-vsphere: configuring server automatic mTLS: timestamp=2024-10-02T10:27:57.120-0400
2024-10-02T10:27:57.138-0400 [DEBUG] provider: using plugin: version=5
2024-10-02T10:27:57.138-0400 [DEBUG] provider.terraform-provider-vsphere: plugin address: address=/var/folders/rr/5vtqxt696ld77msj7d9v03v40000gp/T/plugin4091402034 network=unix timestamp=2024-10-02T10:27:57.138-0400
2024-10-02T10:27:57.163-0400 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-10-02T10:27:57.165-0400 [DEBUG] provider: plugin process exited: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1327
2024-10-02T10:27:57.165-0400 [DEBUG] provider: plugin exited
2024-10-02T10:27:57.165-0400 [DEBUG] Building and walking validate graph
2024-10-02T10:27:57.165-0400 [DEBUG] ProviderTransformer: "data.vsphere_network.network" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.165-0400 [DEBUG] ProviderTransformer: "data.vsphere_datastore.datastore" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.165-0400 [DEBUG] ProviderTransformer: "vsphere_virtual_machine.vm" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.165-0400 [DEBUG] ProviderTransformer: "data.vsphere_datacenter.datacenter" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.166-0400 [DEBUG] ProviderTransformer: "data.vsphere_compute_cluster.cluster" (*terraform.NodeValidatableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.166-0400 [DEBUG] ReferenceTransformer: "data.vsphere_compute_cluster.cluster" references: [data.vsphere_datacenter.datacenter]
2024-10-02T10:27:57.166-0400 [DEBUG] ReferenceTransformer: "data.vsphere_network.network" references: [data.vsphere_datacenter.datacenter]
2024-10-02T10:27:57.166-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datastore.datastore" references: [data.vsphere_datacenter.datacenter]
2024-10-02T10:27:57.166-0400 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/vsphere\"]" references: []
2024-10-02T10:27:57.166-0400 [DEBUG] ReferenceTransformer: "vsphere_virtual_machine.vm" references: [data.vsphere_datastore.datastore data.vsphere_compute_cluster.cluster data.vsphere_network.network]
2024-10-02T10:27:57.166-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datacenter.datacenter" references: []
2024-10-02T10:27:57.167-0400 [DEBUG] Starting graph walk: walkValidate
2024-10-02T10:27:57.168-0400 [DEBUG] created provider logger: level=debug
2024-10-02T10:27:57.168-0400 [INFO]  provider: configuring client automatic mTLS
2024-10-02T10:27:57.173-0400 [DEBUG] provider: starting plugin: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere args=[/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere]
2024-10-02T10:27:57.184-0400 [DEBUG] provider: plugin started: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1331
2024-10-02T10:27:57.184-0400 [DEBUG] provider: waiting for RPC address: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere
2024-10-02T10:27:57.214-0400 [INFO]  provider.terraform-provider-vsphere: configuring server automatic mTLS: timestamp=2024-10-02T10:27:57.214-0400
2024-10-02T10:27:57.230-0400 [DEBUG] provider: using plugin: version=5
2024-10-02T10:27:57.230-0400 [DEBUG] provider.terraform-provider-vsphere: plugin address: address=/var/folders/rr/5vtqxt696ld77msj7d9v03v40000gp/T/plugin900939565 network=unix timestamp=2024-10-02T10:27:57.230-0400
2024-10-02T10:27:57.260-0400 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-10-02T10:27:57.261-0400 [DEBUG] provider: plugin process exited: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1331
2024-10-02T10:27:57.261-0400 [DEBUG] provider: plugin exited
2024-10-02T10:27:57.261-0400 [DEBUG] Refresh is really just plan now, so creating a NormalMode plan
2024-10-02T10:27:57.261-0400 [DEBUG] Building and walking plan graph for NormalMode
2024-10-02T10:27:57.262-0400 [DEBUG] ProviderTransformer: "data.vsphere_datastore.datastore (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.262-0400 [DEBUG] ProviderTransformer: "data.vsphere_datacenter.datacenter (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.262-0400 [DEBUG] ProviderTransformer: "data.vsphere_compute_cluster.cluster (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.262-0400 [DEBUG] ProviderTransformer: "data.vsphere_network.network (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.262-0400 [DEBUG] ProviderTransformer: "vsphere_virtual_machine.vm (expand)" (*terraform.nodeExpandPlannableResource) needs provider["registry.terraform.io/hashicorp/vsphere"]
2024-10-02T10:27:57.262-0400 [DEBUG] ReferenceTransformer: "provider[\"registry.terraform.io/hashicorp/vsphere\"]" references: []
2024-10-02T10:27:57.262-0400 [DEBUG] ReferenceTransformer: "vsphere_virtual_machine.vm (expand)" references: [data.vsphere_datastore.datastore (expand) data.vsphere_compute_cluster.cluster (expand) data.vsphere_network.network (expand)]
2024-10-02T10:27:57.262-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datastore.datastore (expand)" references: [data.vsphere_datacenter.datacenter (expand)]
2024-10-02T10:27:57.262-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datacenter.datacenter (expand)" references: []
2024-10-02T10:27:57.262-0400 [DEBUG] ReferenceTransformer: "data.vsphere_compute_cluster.cluster (expand)" references: [data.vsphere_datacenter.datacenter (expand)]
2024-10-02T10:27:57.262-0400 [DEBUG] ReferenceTransformer: "data.vsphere_network.network (expand)" references: [data.vsphere_datacenter.datacenter (expand)]
2024-10-02T10:27:57.263-0400 [DEBUG] Starting graph walk: walkPlan
2024-10-02T10:27:57.264-0400 [DEBUG] created provider logger: level=debug
2024-10-02T10:27:57.264-0400 [INFO]  provider: configuring client automatic mTLS
2024-10-02T10:27:57.269-0400 [DEBUG] provider: starting plugin: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere args=[/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere]
2024-10-02T10:27:57.275-0400 [DEBUG] provider: plugin started: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1332
2024-10-02T10:27:57.275-0400 [DEBUG] provider: waiting for RPC address: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere
2024-10-02T10:27:57.305-0400 [INFO]  provider.terraform-provider-vsphere: configuring server automatic mTLS: timestamp=2024-10-02T10:27:57.305-0400
2024-10-02T10:27:57.323-0400 [DEBUG] provider: using plugin: version=5
2024-10-02T10:27:57.323-0400 [DEBUG] provider.terraform-provider-vsphere: plugin address: address=/var/folders/rr/5vtqxt696ld77msj7d9v03v40000gp/T/plugin817684290 network=unix timestamp=2024-10-02T10:27:57.323-0400
2024-10-02T10:27:57.348-0400 [WARN]  ValidateProviderConfig from "provider[\"registry.terraform.io/hashicorp/vsphere\"]" changed the config value, but that value is unused
2024-10-02T10:27:57.349-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:57 [DEBUG] Cached SOAP client session data not valid or persistence not enabled, new session necessary: timestamp=2024-10-02T10:27:57.349-0400
2024-10-02T10:27:57.349-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:57 [DEBUG] Creating new SOAP API session on endpoint jbvcftervc02.vsanpe.vmware.com: timestamp=2024-10-02T10:27:57.349-0400
2024-10-02T10:27:58.002-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:58 [DEBUG] SOAP API session creation successful: timestamp=2024-10-02T10:27:58.002-0400
2024-10-02T10:27:58.002-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:58 [DEBUG] VMWare vSphere Client configured for URL: jbvcftervc02.vsanpe.vmware.com: timestamp=2024-10-02T10:27:58.002-0400
2024-10-02T10:27:58.002-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:58 [DEBUG] Setting up REST client: timestamp=2024-10-02T10:27:58.002-0400
2024-10-02T10:27:58.619-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:58 [DEBUG] CIS REST client configuration successful: timestamp=2024-10-02T10:27:58.619-0400
2024-10-02T10:27:58.739-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datacenter.datacenter" references: []
data.vsphere_datacenter.datacenter: Reading...
data.vsphere_datacenter.datacenter: Read complete after 0s [id=datacenter-3]
2024-10-02T10:27:58.980-0400 [DEBUG] ReferenceTransformer: "data.vsphere_compute_cluster.cluster" references: []
2024-10-02T10:27:58.981-0400 [DEBUG] ReferenceTransformer: "data.vsphere_datastore.datastore" references: []
2024-10-02T10:27:58.981-0400 [DEBUG] ReferenceTransformer: "data.vsphere_network.network" references: []
data.vsphere_compute_cluster.cluster: Reading...
data.vsphere_datastore.datastore: Reading...
data.vsphere_network.network: Reading...
2024-10-02T10:27:59.108-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:59 [DEBUG] Looking for cluster "terraform-test-cl01" in datacenter "/terraform-dc01": timestamp=2024-10-02T10:27:59.108-0400
2024-10-02T10:27:59.108-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:59 [DEBUG] Attempting to locate compute cluster "terraform-test-cl01" in datacenter "/terraform-dc01": timestamp=2024-10-02T10:27:59.108-0400
data.vsphere_network.network: Read complete after 1s [id=network-11]
data.vsphere_datastore.datastore: Read complete after 1s [id=datastore-12]
data.vsphere_compute_cluster.cluster: Read complete after 1s [id=domain-c13]
2024-10-02T10:27:59.849-0400 [DEBUG] ReferenceTransformer: "vsphere_virtual_machine.vm" references: []
vsphere_virtual_machine.vm: Refreshing state... [id=42289028-9b65-856c-3d06-1d6ef9d873c8]
2024-10-02T10:27:59.862-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:59 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Reading state of virtual machine: timestamp=2024-10-02T10:27:59.862-0400
2024-10-02T10:27:59.862-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:59 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:59.862-0400
2024-10-02T10:27:59.862-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:27:59 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:27:59.862-0400
2024-10-02T10:28:00.108-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:00.108-0400
2024-10-02T10:28:00.108-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] Fetching properties for VM "/terraform-dc01/vm/foo1": timestamp=2024-10-02T10:28:00.108-0400
2024-10-02T10:28:00.251-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] MOID for VM "/terraform-dc01/vm/foo1" is "vm-41": timestamp=2024-10-02T10:28:00.251-0400
2024-10-02T10:28:00.251-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] Locating vApp container with ID resgroup-14: timestamp=2024-10-02T10:28:00.251-0400
2024-10-02T10:28:00.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] Locating datastore with ID "datastore-12": timestamp=2024-10-02T10:28:00.378-0400
2024-10-02T10:28:00.498-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] Datastore with ID "datastore-12" found: timestamp=2024-10-02T10:28:00.498-0400
2024-10-02T10:28:00.744-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualMachine] and key [vm-41].: timestamp=2024-10-02T10:28:00.744-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] ReadSCSIBusType: SCSI controller layout for first 1 controllers: pvscsi-1000: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] ReadSCSIBusSharing: SCSI controller layout for first 1 controllers: pvscsi-1000: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Beginning refresh: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Found controller "controller-1000" for device "disk-1000-0": timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Found controller "controller-1000" for device "disk-1000-1": timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Found controller "controller-1000" for device "disk-1000-2": timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Disk devices located: disk-1000-0,disk-1000-1,disk-1000-2: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Current resource set from state: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Looking for freshly-created or re-assigned resources to read in: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Disk devices after created/re-assigned device search: disk-1000-0,disk-1000-1,disk-1000-2: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Resource set to write after created/re-assigned device search:: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] DiskRefreshOperation: Looking for devices known in state: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1.vmdk): Reading state: timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:00.886-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:00 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:00.886-0400
2024-10-02T10:28:01.131-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:01.131-0400
2024-10-02T10:28:01.249-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualDiskId] and key [vm-41:2000].: timestamp=2024-10-02T10:28:01.249-0400
2024-10-02T10:28:01.381-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Read finished (key and device address may have changed): timestamp=2024-10-02T10:28:01.381-0400
2024-10-02T10:28:01.381-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1.vmdk): Reading state: timestamp=2024-10-02T10:28:01.381-0400
2024-10-02T10:28:01.381-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:01.381-0400
2024-10-02T10:28:01.382-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:01.381-0400
2024-10-02T10:28:01.622-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:01.621-0400
2024-10-02T10:28:01.744-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualDiskId] and key [vm-41:2001].: timestamp=2024-10-02T10:28:01.744-0400
2024-10-02T10:28:01.873-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Read finished (key and device address may have changed): timestamp=2024-10-02T10:28:01.873-0400
2024-10-02T10:28:01.873-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2.vmdk): Reading state: timestamp=2024-10-02T10:28:01.873-0400
2024-10-02T10:28:01.873-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] Locating virtual machine with UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:01.873-0400
2024-10-02T10:28:01.873-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:01 [DEBUG] Using SearchIndex to look up UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:01.873-0400
2024-10-02T10:28:02.119-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] VM "/terraform-dc01/vm/foo1" found for UUID "42289028-9b65-856c-3d06-1d6ef9d873c8": timestamp=2024-10-02T10:28:02.119-0400
2024-10-02T10:28:02.249-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] queryAssociatedProfile: Retrieving storage policy of server object of type [virtualDiskId] and key [vm-41:2002].: timestamp=2024-10-02T10:28:02.249-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Read finished (key and device address may have changed): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskRefreshOperation: Resource set to write after known device search: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskRefreshOperation: Probable orphaned disk devices:: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskRefreshOperation: Adding orphaned devices: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskRefreshOperation: Resource set to write after adding orphaned devices: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskRefreshOperation: Final (sorted) resource set to write: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskRefreshOperation: Refresh operation complete, sending new resource set: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Beginning refresh: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Network devices located: ethernet-0: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Current resource set from state: (key 4000 at pci:0:7): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Looking for freshly-created resources to read in: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Network devices after freshly-created device search: ethernet-0: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Resource sets to write after known device search:: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Looking for devices known in state: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Reading state: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] FindVirtualDevice: Looking for device with key 4000: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] FindVirtualDevice: Device found: ethernet-0: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] Read: Adapter type not SR-IOV: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Read finished (key and device address may have changed): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Resource sets to write after known device search: (key 4000 at pci:0:7): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Probable orphaned network interfaces:: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.377-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Adding orphaned devices: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: 0 devices and a new set of length 1: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Resource set to write after adding orphaned devices: (key 4000 at pci:0:7): timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceRefreshOperation: Refresh operation complete, sending new resource set: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Beginning refresh: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: CDROM devices located:: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Current resource set from state:: timestamp=2024-10-02T10:28:02.377-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Looking for freshly-created resources to read in: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: CDROM devices after freshly-created device search:: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Resource set to write after freshly-created device search:: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Looking for devices known in state: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Resource set to write after known device search:: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Probable orphaned CDROM devices:: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Resource set to write after adding orphaned devices:: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] CdromRefreshOperation: Refresh operation complete, sending new resource set: timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.378-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] Reading tags for object "vm-41": timestamp=2024-10-02T10:28:02.378-0400
2024-10-02T10:28:02.614-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] Tags for object "vm-41":: timestamp=2024-10-02T10:28:02.614-0400
2024-10-02T10:28:02.614-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Checking guest networking state: timestamp=2024-10-02T10:28:02.614-0400
2024-10-02T10:28:02.614-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): No IP addresses found in guest state: timestamp=2024-10-02T10:28:02.614-0400
2024-10-02T10:28:02.614-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Read complete: timestamp=2024-10-02T10:28:02.614-0400
2024-10-02T10:28:02.618-0400 [WARN]  Provider "registry.terraform.io/hashicorp/vsphere" produced an unexpected new value for vsphere_virtual_machine.vm during refresh.
      - .custom_attributes: was null, but now cty.MapValEmpty(cty.String)
      - .power_state: was cty.StringVal("on"), but now cty.StringVal("off")
      - .tags: was null, but now cty.SetValEmpty(cty.String)
      - .pci_device_id: was null, but now cty.SetValEmpty(cty.String)
      - .extra_config: was null, but now cty.MapValEmpty(cty.String)
      - .disk[0].path: was cty.StringVal("foo1/foo1.vmdk"), but now cty.StringVal("foo1/foo1-000002.vmdk")
      - .disk[1].path: was cty.StringVal("foo1/foo1_1.vmdk"), but now cty.StringVal("foo1/foo1_1-000002.vmdk")
      - .disk[2].path: was cty.StringVal("foo1/foo1_2.vmdk"), but now cty.StringVal("foo1/foo1_2-000002.vmdk")
2024-10-02T10:28:02.632-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Performing diff customization and validation: timestamp=2024-10-02T10:28:02.632-0400
2024-10-02T10:28:02.632-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceDiffOperation: Beginning diff validation: timestamp=2024-10-02T10:28:02.632-0400
2024-10-02T10:28:02.632-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Beginning diff validation: timestamp=2024-10-02T10:28:02.632-0400
2024-10-02T10:28:02.632-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] network_interface.0 (key 4000 at pci:0:7): Diff validation complete: timestamp=2024-10-02T10:28:02.632-0400
2024-10-02T10:28:02.632-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] NetworkInterfaceDiffOperation: Diff validation complete: timestamp=2024-10-02T10:28:02.632-0400
2024-10-02T10:28:02.632-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskDiffOperation: Beginning disk diff customization: timestamp=2024-10-02T10:28:02.632-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskDiffOperation: Beginning collective diff validation (indexes aligned to new config): timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Beginning diff validation: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Diff validation complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Beginning diff validation: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Diff validation complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Beginning diff validation: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Diff validation complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskDiffOperation: Beginning diff validation and normalization (indexes aligned to old state): timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Beginning normalization of existing disk: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.0 (key 2000 at scsi:0:0) (foo1/foo1-000002.vmdk): Normalization of existing disk diff complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Beginning normalization of existing disk: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.1 (key 2001 at scsi:0:1) (foo1/foo1_1-000002.vmdk): Normalization of existing disk diff complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Beginning normalization of existing disk: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] disk.2 (key 2002 at scsi:0:2) (foo1/foo1_2-000002.vmdk): Normalization of existing disk diff complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskDiffOperation: New resource set post-normalization: (key 2000 at scsi:0:0),(key 2001 at scsi:0:1),(key 2002 at scsi:0:2): timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] DiskDiffOperation: Disk diff customization complete, sending new diff: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] VAppDiffOperation: Verifying configuration meets requirements for vApp transport: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] VAppDiffOperation: ISO transport is not supported on this virtual machine or multiple transport options exist: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.634-0400 [INFO]  provider.terraform-provider-vsphere: 2024/10/02 10:28:02 [DEBUG] vsphere_virtual_machine (ID = 42289028-9b65-856c-3d06-1d6ef9d873c8): Diff customization and validation complete: timestamp=2024-10-02T10:28:02.634-0400
2024-10-02T10:28:02.636-0400 [WARN]  Provider "registry.terraform.io/hashicorp/vsphere" produced an invalid plan for vsphere_virtual_machine.vm, 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:
      - .migrate_wait_timeout: planned value cty.NumberIntVal(30) for a non-computed attribute
      - .tags: planned value cty.SetValEmpty(cty.String) for a non-computed attribute
      - .alternate_guest_name: planned value cty.StringVal("") for a non-computed attribute
      - .cpu_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .extra_config_reboot_required: planned value cty.True for a non-computed attribute
      - .tools_upgrade_policy: planned value cty.StringVal("manual") for a non-computed attribute
      - .boot_retry_enabled: planned value cty.False for a non-computed attribute
      - .cpu_hot_remove_enabled: planned value cty.False for a non-computed attribute
      - .efi_secure_boot_enabled: planned value cty.False for a non-computed attribute
      - .force_power_off: planned value cty.True for a non-computed attribute
      - .run_tools_scripts_after_resume: planned value cty.True for a non-computed attribute
      - .vbs_enabled: planned value cty.False for a non-computed attribute
      - .ide_controller_count: planned value cty.NumberIntVal(2) for a non-computed attribute
      - .memory_reservation_locked_to_max: planned value cty.False for a non-computed attribute
      - .pci_device_id: planned value cty.SetValEmpty(cty.String) for a non-computed attribute
      - .enable_disk_uuid: planned value cty.False for a non-computed attribute
      - .extra_config: planned value cty.MapValEmpty(cty.String) for a non-computed attribute
      - .vvtd_enabled: planned value cty.False for a non-computed attribute
      - .enable_logging: planned value cty.False for a non-computed attribute
      - .swap_placement_policy: planned value cty.StringVal("inherit") for a non-computed attribute
      - .run_tools_scripts_after_power_on: planned value cty.True for a non-computed attribute
      - .scsi_bus_sharing: planned value cty.StringVal("noSharing") for a non-computed attribute
      - .wait_for_guest_ip_timeout: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .cpu_hot_add_enabled: planned value cty.False for a non-computed attribute
      - .sata_controller_count: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .shutdown_wait_timeout: planned value cty.NumberIntVal(3) for a non-computed attribute
      - .boot_delay: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .cpu_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .custom_attributes: planned value cty.MapValEmpty(cty.String) for a non-computed attribute
      - .num_cores_per_socket: planned value cty.NumberIntVal(1) for a non-computed attribute
      - .memory_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .scsi_controller_count: planned value cty.NumberIntVal(1) for a non-computed attribute
      - .latency_sensitivity: planned value cty.StringVal("normal") for a non-computed attribute
      - .memory_hot_add_enabled: planned value cty.False for a non-computed attribute
      - .poweron_timeout: planned value cty.NumberIntVal(300) for a non-computed attribute
      - .run_tools_scripts_before_guest_standby: planned value cty.True for a non-computed attribute
      - .boot_retry_delay: planned value cty.NumberIntVal(10000) for a non-computed attribute
      - .firmware: planned value cty.StringVal("bios") for a non-computed attribute
      - .folder: planned value cty.StringVal("") for a non-computed attribute
      - .scsi_type: planned value cty.StringVal("pvscsi") for a non-computed attribute
      - .memory_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .nested_hv_enabled: planned value cty.False for a non-computed attribute
      - .run_tools_scripts_before_guest_reboot: planned value cty.False for a non-computed attribute
      - .run_tools_scripts_before_guest_shutdown: planned value cty.True for a non-computed attribute
      - .cpu_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .cpu_performance_counters_enabled: planned value cty.False for a non-computed attribute
      - .memory_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .sync_time_with_host: planned value cty.True for a non-computed attribute
      - .sync_time_with_host_periodically: planned value cty.False for a non-computed attribute
      - .network_interface[0].bandwidth_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .network_interface[0].use_static_mac: planned value cty.False for a non-computed attribute
      - .network_interface[0].bandwidth_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .network_interface[0].bandwidth_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .network_interface[0].ovf_mapping: planned value cty.StringVal("") for a non-computed attribute
      - .network_interface[0].physical_function: planned value cty.StringVal("") for a non-computed attribute
      - .network_interface[0].adapter_type: planned value cty.StringVal("vmxnet3") for a non-computed attribute
      - .disk[0].disk_mode: planned value cty.StringVal("persistent") for a non-computed attribute
      - .disk[0].eagerly_scrub: planned value cty.False for a non-computed attribute
      - .disk[0].keep_on_remove: planned value cty.False for a non-computed attribute
      - .disk[0].write_through: planned value cty.False for a non-computed attribute
      - .disk[0].io_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .disk[0].io_share_count: planned value cty.NumberIntVal(1000) for a non-computed attribute
      - .disk[0].io_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .disk[0].thin_provisioned: planned value cty.True for a non-computed attribute
      - .disk[0].attach: planned value cty.False for a non-computed attribute
      - .disk[0].io_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .disk[0].disk_sharing: planned value cty.StringVal("sharingNone") for a non-computed attribute
      - .disk[0].controller_type: planned value cty.StringVal("scsi") for a non-computed attribute
      - .disk[1].controller_type: planned value cty.StringVal("scsi") for a non-computed attribute
      - .disk[1].disk_sharing: planned value cty.StringVal("sharingNone") for a non-computed attribute
      - .disk[1].keep_on_remove: planned value cty.False for a non-computed attribute
      - .disk[1].write_through: planned value cty.False for a non-computed attribute
      - .disk[1].disk_mode: planned value cty.StringVal("persistent") for a non-computed attribute
      - .disk[1].eagerly_scrub: planned value cty.False for a non-computed attribute
      - .disk[1].io_share_count: planned value cty.NumberIntVal(1000) for a non-computed attribute
      - .disk[1].io_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .disk[1].thin_provisioned: planned value cty.True for a non-computed attribute
      - .disk[1].attach: planned value cty.False for a non-computed attribute
      - .disk[1].io_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .disk[1].io_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
      - .disk[2].controller_type: planned value cty.StringVal("scsi") for a non-computed attribute
      - .disk[2].disk_sharing: planned value cty.StringVal("sharingNone") for a non-computed attribute
      - .disk[2].disk_mode: planned value cty.StringVal("persistent") for a non-computed attribute
      - .disk[2].eagerly_scrub: planned value cty.False for a non-computed attribute
      - .disk[2].keep_on_remove: planned value cty.False for a non-computed attribute
      - .disk[2].write_through: planned value cty.False for a non-computed attribute
      - .disk[2].attach: planned value cty.False for a non-computed attribute
      - .disk[2].io_limit: planned value cty.NumberIntVal(-1) for a non-computed attribute
      - .disk[2].io_share_count: planned value cty.NumberIntVal(1000) for a non-computed attribute
      - .disk[2].io_share_level: planned value cty.StringVal("normal") for a non-computed attribute
      - .disk[2].thin_provisioned: planned value cty.True for a non-computed attribute
      - .disk[2].io_reservation: planned value cty.NumberIntVal(0) for a non-computed attribute
2024-10-02T10:28:02.639-0400 [DEBUG] provider.stdio: received EOF, stopping recv loop: err="rpc error: code = Unavailable desc = error reading from server: EOF"
2024-10-02T10:28:02.681-0400 [DEBUG] provider: plugin process exited: path=/Users/burnsja/Documents/GitHub/terraform-provider-vsphere/terraform-provider-vsphere pid=1332
2024-10-02T10:28:02.681-0400 [DEBUG] provider: plugin exited
2024-10-02T10:28:02.687-0400 [DEBUG] no planned changes, skipping apply graph check
2024-10-02T10:28:02.687-0400 [INFO]  backend/local: refresh calling Refresh
burnsja@C02Z962BLVDQ test_snap %

after revert:

{
  "version": 4,
  "terraform_version": "1.5.7",
  "serial": 2,
  "lineage": "f4122cc6-3043-a839-2427-9c9a94df3c07",
  "outputs": {},
  "resources": [
    {
      "mode": "data",
      "type": "vsphere_compute_cluster",
      "name": "cluster",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "datacenter_id": "datacenter-3",
            "id": "domain-c13",
            "name": "terraform-test-cl01",
            "resource_pool_id": "resgroup-14"
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "data",
      "type": "vsphere_datacenter",
      "name": "datacenter",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "id": "datacenter-3",
            "name": "terraform-dc01"
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "data",
      "type": "vsphere_datastore",
      "name": "datastore",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "datacenter_id": "datacenter-3",
            "id": "datastore-12",
            "name": "Datastore",
            "stats": {
              "capacity": "321854111744",
              "free": "270752808960"
            }
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "data",
      "type": "vsphere_network",
      "name": "network",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 0,
          "attributes": {
            "datacenter_id": "datacenter-3",
            "distributed_virtual_switch_uuid": null,
            "id": "network-11",
            "name": "VM Network",
            "type": "Network"
          },
          "sensitive_attributes": []
        }
      ]
    },
    {
      "mode": "managed",
      "type": "vsphere_virtual_machine",
      "name": "vm",
      "provider": "provider[\"registry.terraform.io/hashicorp/vsphere\"]",
      "instances": [
        {
          "schema_version": 3,
          "attributes": {
            "alternate_guest_name": "",
            "annotation": "",
            "boot_delay": 0,
            "boot_retry_delay": 10000,
            "boot_retry_enabled": false,
            "cdrom": [],
            "change_version": "2024-10-02T14:21:11.006941Z",
            "clone": [],
            "cpu_hot_add_enabled": false,
            "cpu_hot_remove_enabled": false,
            "cpu_limit": -1,
            "cpu_performance_counters_enabled": false,
            "cpu_reservation": 0,
            "cpu_share_count": 2000,
            "cpu_share_level": "normal",
            "custom_attributes": {},
            "datacenter_id": null,
            "datastore_cluster_id": null,
            "datastore_id": "datastore-12",
            "default_ip_address": null,
            "disk": [
              {
                "attach": false,
                "controller_type": "scsi",
                "datastore_id": "datastore-12",
                "device_address": "scsi:0:0",
                "disk_mode": "persistent",
                "disk_sharing": "sharingNone",
                "eagerly_scrub": false,
                "io_limit": -1,
                "io_reservation": 0,
                "io_share_count": 1000,
                "io_share_level": "normal",
                "keep_on_remove": false,
                "key": 2000,
                "label": "OS-Disk",
                "path": "foo1/foo1-000002.vmdk",
                "size": 60,
                "storage_policy_id": "",
                "thin_provisioned": true,
                "unit_number": 0,
                "uuid": "6000C290-ec0d-e8d7-5f2a-76cc87b862fe",
                "write_through": false
              },
              {
                "attach": false,
                "controller_type": "scsi",
                "datastore_id": "datastore-12",
                "device_address": "scsi:0:1",
                "disk_mode": "persistent",
                "disk_sharing": "sharingNone",
                "eagerly_scrub": false,
                "io_limit": -1,
                "io_reservation": 0,
                "io_share_count": 1000,
                "io_share_level": "normal",
                "keep_on_remove": false,
                "key": 2001,
                "label": "APP-Disk",
                "path": "foo1/foo1_1-000002.vmdk",
                "size": 5,
                "storage_policy_id": "",
                "thin_provisioned": true,
                "unit_number": 1,
                "uuid": "6000C292-f2bc-4694-a2e6-af496e1e1654",
                "write_through": false
              },
              {
                "attach": false,
                "controller_type": "scsi",
                "datastore_id": "datastore-12",
                "device_address": "scsi:0:2",
                "disk_mode": "persistent",
                "disk_sharing": "sharingNone",
                "eagerly_scrub": false,
                "io_limit": -1,
                "io_reservation": 0,
                "io_share_count": 1000,
                "io_share_level": "normal",
                "keep_on_remove": false,
                "key": 2002,
                "label": "DB-Disk",
                "path": "foo1/foo1_2-000002.vmdk",
                "size": 5,
                "storage_policy_id": "",
                "thin_provisioned": true,
                "unit_number": 2,
                "uuid": "6000C292-3b64-679a-0d25-3e66eff0fadf",
                "write_through": false
              }
            ],
            "efi_secure_boot_enabled": false,
            "enable_disk_uuid": false,
            "enable_logging": false,
            "ept_rvi_mode": "automatic",
            "extra_config": {},
            "extra_config_reboot_required": true,
            "firmware": "bios",
            "folder": "",
            "force_power_off": true,
            "guest_id": "ubuntu64Guest",
            "guest_ip_addresses": [],
            "hardware_version": 19,
            "host_system_id": "host-10",
            "hv_mode": "hvAuto",
            "id": "42289028-9b65-856c-3d06-1d6ef9d873c8",
            "ide_controller_count": 2,
            "ignored_guest_ips": null,
            "imported": null,
            "latency_sensitivity": "normal",
            "memory": 1024,
            "memory_hot_add_enabled": false,
            "memory_limit": -1,
            "memory_reservation": 0,
            "memory_reservation_locked_to_max": false,
            "memory_share_count": 10240,
            "memory_share_level": "normal",
            "migrate_wait_timeout": 30,
            "moid": "vm-41",
            "name": "foo1",
            "nested_hv_enabled": false,
            "network_interface": [
              {
                "adapter_type": "vmxnet3",
                "bandwidth_limit": -1,
                "bandwidth_reservation": 0,
                "bandwidth_share_count": 50,
                "bandwidth_share_level": "normal",
                "device_address": "pci:0:7",
                "key": 4000,
                "mac_address": "00:50:56:a8:ec:7b",
                "network_id": "network-11",
                "ovf_mapping": "",
                "physical_function": "",
                "use_static_mac": false
              }
            ],
            "num_cores_per_socket": 1,
            "num_cpus": 2,
            "ovf_deploy": [],
            "pci_device_id": [],
            "power_state": "off",
            "poweron_timeout": 300,
            "reboot_required": false,
            "replace_trigger": null,
            "resource_pool_id": "resgroup-14",
            "run_tools_scripts_after_power_on": true,
            "run_tools_scripts_after_resume": true,
            "run_tools_scripts_before_guest_reboot": false,
            "run_tools_scripts_before_guest_shutdown": true,
            "run_tools_scripts_before_guest_standby": true,
            "sata_controller_count": 0,
            "scsi_bus_sharing": "noSharing",
            "scsi_controller_count": 1,
            "scsi_type": "pvscsi",
            "shutdown_wait_timeout": 3,
            "storage_policy_id": "",
            "swap_placement_policy": "inherit",
            "sync_time_with_host": true,
            "sync_time_with_host_periodically": false,
            "tags": [],
            "tools_upgrade_policy": "manual",
            "uuid": "42289028-9b65-856c-3d06-1d6ef9d873c8",
            "vapp": [],
            "vapp_transport": [],
            "vbs_enabled": false,
            "vmware_tools_status": "guestToolsNotRunning",
            "vmx_path": "foo1/foo1.vmx",
            "vvtd_enabled": false,
            "wait_for_guest_ip_timeout": 0,
            "wait_for_guest_net_routable": false,
            "wait_for_guest_net_timeout": 0
          },
          "sensitive_attributes": [],
          "private": "eyJzY2hlbWFfdmVyc2lvbiI6IjMifQ==",
          "dependencies": [
            "data.vsphere_compute_cluster.cluster",
            "data.vsphere_datacenter.datacenter",
            "data.vsphere_datastore.datastore",
            "data.vsphere_network.network"
          ]
        }
      ]
    }
  ],
  "check_results": null
}

mvandriessen commented 4 weeks ago

An update in the docs would work for us. Changing the behavior again would make it so that a lot of people would potentially have to refactor their code and update their environment again.

Thanks for the investigation!

rdrgporto commented 4 weeks ago

Hi @burnsjared0415

I noticed that a pull request has been created (#2772).

Thank you very much 😃

Regards

burnsjared0415 commented 4 weeks ago

i will do some more digging to see if it is terraform or vSphere that makes this change

rdrgporto commented 4 weeks ago

Hi @burnsjared0415,

Thank you very much for your help.

Regards

burnsjared0415 commented 4 weeks ago

it looks like it is vSphere that makes the change

rdrgporto commented 3 weeks ago

Hi @burnsjared0415,

Thank you for the information. So, can we start fix our deployments and applying Hard Disk 1 label?

Regards

github-actions[bot] commented 3 weeks ago

This functionality has been released in v2.9.3 of the Terraform Provider. Please see the Terraform documentation on provider versioning or reach out if you need any assistance upgrading.

For further feature requests or bug reports with this functionality, please create a new GitHub issue following the template. Thank you!

github-actions[bot] commented 3 weeks ago

This functionality has been released in v2.9.3 of the Terraform Provider. Please see the Terraform documentation on provider versioning or reach out if you need any assistance upgrading.

For further feature requests or bug reports with this functionality, please create a new GitHub issue following the template. Thank you!

hashicorp / terraform-provider-vsphere