Clarification shiftfs vs. idmapped mounts

LXD info:

# lxc info
config: {}
api_extensions:
- storage_zfs_remove_snapshots
- container_host_shutdown_timeout
- container_stop_priority
- container_syscall_filtering
- auth_pki
- container_last_used_at
- etag
- patch
- usb_devices
- https_allowed_credentials
- image_compression_algorithm
- directory_manipulation
- container_cpu_time
- storage_zfs_use_refquota
- storage_lvm_mount_options
- network
- profile_usedby
- container_push
- container_exec_recording
- certificate_update
- container_exec_signal_handling
- gpu_devices
- container_image_properties
- migration_progress
- id_map
- network_firewall_filtering
- network_routes
- storage
- file_delete
- file_append
- network_dhcp_expiry
- storage_lvm_vg_rename
- storage_lvm_thinpool_rename
- network_vlan
- image_create_aliases
- container_stateless_copy
- container_only_migration
- storage_zfs_clone_copy
- unix_device_rename
- storage_lvm_use_thinpool
- storage_rsync_bwlimit
- network_vxlan_interface
- storage_btrfs_mount_options
- entity_description
- image_force_refresh
- storage_lvm_lv_resizing
- id_map_base
- file_symlinks
- container_push_target
- network_vlan_physical
- storage_images_delete
- container_edit_metadata
- container_snapshot_stateful_migration
- storage_driver_ceph
- storage_ceph_user_name
- resource_limits
- storage_volatile_initial_source
- storage_ceph_force_osd_reuse
- storage_block_filesystem_btrfs
- resources
- kernel_limits
- storage_api_volume_rename
- macaroon_authentication
- network_sriov
- console
- restrict_devlxd
- migration_pre_copy
- infiniband
- maas_network
- devlxd_events
- proxy
- network_dhcp_gateway
- file_get_symlink
- network_leases
- unix_device_hotplug
- storage_api_local_volume_handling
- operation_description
- clustering
- event_lifecycle
- storage_api_remote_volume_handling
- nvidia_runtime
- container_mount_propagation
- container_backup
- devlxd_images
- container_local_cross_pool_handling
- proxy_unix
- proxy_udp
- clustering_join
- proxy_tcp_udp_multi_port_handling
- network_state
- proxy_unix_dac_properties
- container_protection_delete
- unix_priv_drop
- pprof_http
- proxy_haproxy_protocol
- network_hwaddr
- proxy_nat
- network_nat_order
- container_full
- candid_authentication
- backup_compression
- candid_config
- nvidia_runtime_config
- storage_api_volume_snapshots
- storage_unmapped
- projects
- candid_config_key
- network_vxlan_ttl
- container_incremental_copy
- usb_optional_vendorid
- snapshot_scheduling
- snapshot_schedule_aliases
- container_copy_project
- clustering_server_address
- clustering_image_replication
- container_protection_shift
- snapshot_expiry
- container_backup_override_pool
- snapshot_expiry_creation
- network_leases_location
- resources_cpu_socket
- resources_gpu
- resources_numa
- kernel_features
- id_map_current
- event_location
- storage_api_remote_volume_snapshots
- network_nat_address
- container_nic_routes
- rbac
- cluster_internal_copy
- seccomp_notify
- lxc_features
- container_nic_ipvlan
- network_vlan_sriov
- storage_cephfs
- container_nic_ipfilter
- resources_v2
- container_exec_user_group_cwd
- container_syscall_intercept
- container_disk_shift
- storage_shifted
- resources_infiniband
- daemon_storage
- instances
- image_types
- resources_disk_sata
- clustering_roles
- images_expiry
- resources_network_firmware
- backup_compression_algorithm
- ceph_data_pool_name
- container_syscall_intercept_mount
- compression_squashfs
- container_raw_mount
- container_nic_routed
- container_syscall_intercept_mount_fuse
- container_disk_ceph
- virtual-machines
- image_profiles
- clustering_architecture
- resources_disk_id
- storage_lvm_stripes
- vm_boot_priority
- unix_hotplug_devices
- api_filtering
- instance_nic_network
- clustering_sizing
- firewall_driver
- projects_limits
- container_syscall_intercept_hugetlbfs
- limits_hugepages
- container_nic_routed_gateway
- projects_restrictions
- custom_volume_snapshot_expiry
- volume_snapshot_scheduling
- trust_ca_certificates
- snapshot_disk_usage
- clustering_edit_roles
- container_nic_routed_host_address
- container_nic_ipvlan_gateway
- resources_usb_pci
- resources_cpu_threads_numa
- resources_cpu_core_die
- api_os
- container_nic_routed_host_table
- container_nic_ipvlan_host_table
- container_nic_ipvlan_mode
- resources_system
- images_push_relay
- network_dns_search
- container_nic_routed_limits
- instance_nic_bridged_vlan
- network_state_bond_bridge
- usedby_consistency
- custom_block_volumes
- clustering_failure_domains
- resources_gpu_mdev
- console_vga_type
- projects_limits_disk
- network_type_macvlan
- network_type_sriov
- container_syscall_intercept_bpf_devices
- network_type_ovn
- projects_networks
- projects_networks_restricted_uplinks
- custom_volume_backup
- backup_override_name
- storage_rsync_compression
- network_type_physical
- network_ovn_external_subnets
- network_ovn_nat
- network_ovn_external_routes_remove
- tpm_device_type
- storage_zfs_clone_copy_rebase
- gpu_mdev
- resources_pci_iommu
- resources_network_usb
- resources_disk_address
- network_physical_ovn_ingress_mode
- network_ovn_dhcp
- network_physical_routes_anycast
- projects_limits_instances
- network_state_vlan
- instance_nic_bridged_port_isolation
- instance_bulk_state_change
- network_gvrp
- instance_pool_move
- gpu_sriov
- pci_device_type
- storage_volume_state
- network_acl
- migration_stateful
- disk_state_quota
- storage_ceph_features
- projects_compression
- projects_images_remote_cache_expiry
- certificate_project
- network_ovn_acl
- projects_images_auto_update
- projects_restricted_cluster_target
- images_default_architecture
- network_ovn_acl_defaults
- gpu_mig
- project_usage
- network_bridge_acl
- warnings
- projects_restricted_backups_and_snapshots
- clustering_join_token
- clustering_description
- server_trusted_proxy
- clustering_update_cert
- storage_api_project
- server_instance_driver_operational
- server_supported_storage_drivers
- event_lifecycle_requestor_address
- resources_gpu_usb
- clustering_evacuation
- network_ovn_nat_address
- network_bgp
- network_forward
- custom_volume_refresh
- network_counters_errors_dropped
- metrics
- image_source_project
- clustering_config
- network_peer
- linux_sysctl
- network_dns
- ovn_nic_acceleration
- certificate_self_renewal
- instance_project_move
- storage_volume_project_move
- cloud_init
- network_dns_nat
- database_leader
- instance_all_projects
- clustering_groups
- ceph_rbd_du
- instance_get_full
- qemu_metrics
- gpu_mig_uuid
- event_project
- clustering_evacuation_live
- instance_allow_inconsistent_copy
- network_state_ovn
- storage_volume_api_filtering
- image_restrictions
- storage_zfs_export
- network_dns_records
- storage_zfs_reserve_space
- network_acl_log
- storage_zfs_blocksize
- metrics_cpu_seconds
- instance_snapshot_never
- certificate_token
- instance_nic_routed_neighbor_probe
- event_hub
- agent_nic_config
- projects_restricted_intercept
- metrics_authentication
- images_target_project
- cluster_migration_inconsistent_copy
- cluster_ovn_chassis
- container_syscall_intercept_sched_setscheduler
- storage_lvm_thinpool_metadata_size
- storage_volume_state_total
- instance_file_head
- instances_nic_host_name
- image_copy_profile
- container_syscall_intercept_sysinfo
- clustering_evacuation_mode
- resources_pci_vpd
- qemu_raw_conf
- storage_cephfs_fscache
- network_load_balancer
- vsock_api
- instance_ready_state
- network_bgp_holdtime
- storage_volumes_all_projects
- metrics_memory_oom_total
- storage_buckets
- storage_buckets_create_credentials
- metrics_cpu_effective_total
- projects_networks_restricted_access
- storage_buckets_local
- loki
- acme
- internal_metrics
- cluster_join_token_expiry
- remote_token_expiry
- init_preseed
- storage_volumes_created_at
- cpu_hotplug
- projects_networks_zones
- network_txqueuelen
- cluster_member_state
- instances_placement_scriptlet
- storage_pool_source_wipe
- zfs_block_mode
- instance_generation_id
- disk_io_cache
- amd_sev
- storage_pool_loop_resize
- migration_vm_live
- ovn_nic_nesting
- oidc
- network_ovn_l3only
- ovn_nic_acceleration_vdpa
- cluster_healing
- instances_state_total
- auth_user
- security_csm
- instances_rebuild
- numa_cpu_placement
api_status: stable
api_version: "1.0"
auth: trusted
public: false
auth_methods:
- tls
auth_user_name: root
auth_user_method: unix
environment:
  addresses: []
  architectures:
  - x86_64
  - i686
  certificate: |
    -----BEGIN CERTIFICATE-----
    -----END CERTIFICATE-----
  certificate_fingerprint: af209b4d143705216083f43aae6cbf9b3f90443b3f473cef481527aa6d071db3
  driver: lxc | qemu
  driver_version: 5.0.2 | 8.0.0
  firewall: nftables
  kernel: Linux
  kernel_architecture: x86_64
  kernel_features:
    idmapped_mounts: "true"
    netnsid_getifaddrs: "true"
    seccomp_listener: "true"
    seccomp_listener_continue: "true"
    shiftfs: "false"
    uevent_injection: "true"
    unpriv_fscaps: "true"
  kernel_version: 5.19.0-45-generic
  lxc_features:
    cgroup2: "true"
    core_scheduling: "true"
    devpts_fd: "true"
    idmapped_mounts_v2: "true"
    mount_injection_file: "true"
    network_gateway_device_route: "true"
    network_ipvlan: "true"
    network_l2proxy: "true"
    network_phys_macvlan_mtu: "true"
    network_veth_router: "true"
    pidfd: "true"
    seccomp_allow_deny_syntax: "true"
    seccomp_notify: "true"
    seccomp_proxy_send_notify_fd: "true"
  os_name: Ubuntu
  os_version: "22.04"
  project: default
  server: lxd
  server_clustered: false
  server_event_mode: full-mesh
  server_name: 65.109.20.140
  server_pid: 881325
  server_version: "5.15"
  storage: zfs | btrfs
  storage_version: 2.1.5-1ubuntu6 | 5.16.2
  storage_supported_drivers:
  - name: lvm
    version: 2.03.11(2) (2021-01-08) / 1.02.175 (2021-01-08) / 4.47.0
    remote: false
  - name: zfs
    version: 2.1.5-1ubuntu6
    remote: false
  - name: btrfs
    version: 5.16.2
    remote: false
  - name: ceph
    version: 17.2.5
    remote: true
  - name: cephfs
    version: 17.2.5
    remote: true
  - name: cephobject
    version: 17.2.5
    remote: true
  - name: dir
    version: "1"
    remote: false

Hello,

I still have a hard time trying to figure out the difference between shiftfs and idmapped mounts or when each one is used. As you can see from the lxd info output above, I am running kernel 5.19.0-45 on ubuntu 22.04 and LXD 5.15 on a ext4 filesystem. As far as I understood this should be enough for idmapped mounts to work and in the kernel features I see idmapped_mounts: "true". And this post suggests, that indeed idmapped mounts should be used:

So in this case shiftfs should not be needed, as idmapped mounts are preferred over shiftfs, correct?
When I launch a container with the default snap set lxd shiftfs.enable=false it still takes a while until it’s really started because the filesystem is remapped. After everything I read I was under the impression, that remapping of the filesystem would not be neccessary when idmapped mounts are available - did I get that wrong?

When I set snap set lxd shiftfs.enable=true containers start in an instant, but I see issues in very specific cases (see here for details).

So either I did get the whole shiftfs vs idmapped mounts wrong, or I am missing a requirement to actually use idmapped mounts over shiftsfs?!

Would be great when someone could shed some light on this.

idmapped mounts are the preferred option, and are used when the underlying storage filesystem supports it in the kernel.

However not all filesystems support it, notably ZFS, so then LXD fallsback to using shiftfs (if enabled) or static shifting if not.

Thank you! So the “issue” is then that we are using ZFS in LXD? Because the underlying filesystem is an ext4 filesystem:

# mount
...
/dev/md2 on / type ext4 (rw,relatime,stripe=32)
...

# lxc storage list
+---------+--------+--------------------------------------------+-------------+---------+---------+
|  NAME   | DRIVER |                   SOURCE                   | DESCRIPTION | USED BY |  STATE  |
+---------+--------+--------------------------------------------+-------------+---------+---------+
| default | zfs    | /var/snap/lxd/common/lxd/disks/default.img |             | 28      | CREATED |
+---------+--------+--------------------------------------------+-------------+---------+---------+

Thats correct. The files in your containers are on ZFS datasets. The ZFS pool itself is stored in an image file on your ext4 filesystem (but that is not relevant in this case).

It would be interesting to see if using zfs.block_mode=true on your ZFS pool would help with new instances, as that would then use a ZFS block volume (inside your ZFS pool ontop of ext4) and then create an ext4 filesystem inside of the ZFS block volume.

All these layers aren’t going to help I/O performance ofcourse, but might enable the use of idmapping, as the container’s filesystem won’t see its on ZFS anymore.

See https://linuxcontainers.org/lxd/docs/latest/reference/storage_zfs/#storage-volume-configuration

This looks like it’s working! After adding the above config the image was retrieved again when a new instance was launched, but no remapping of the filesystem happened. Also a quick test showed that this resolves the issue with fuse-overlayfs we were seeing with docker https://discuss.linuxcontainers.org/t/docker-container-fails-sometimes-with-symlink-proc-mounts-file-exists-with-fuse-overlayfs/.
I’ll test some more later and report back, thank you!

1 Like

So after setting this on a production LXD installation, I am unable to start containers anymore. I receive a lot of "No space left on device " errors after issuing an lxc launch command:

...
tar: rootfs/etc/alternatives/ALTER_FOREIGN_DATA_WRAPPER.7.gz: Cannot create symlink to ‘/usr/share/postgresql/14/man/man7/ALTER_FOREIGN_DATA_WRAPPER.7.gz’: No space left on device
tar: rootfs/etc/alternatives/ALTER_DEFAULT_PRIVILEGES.7.gz: Cannot create symlink to ‘/usr/share/postgresql/14/man/man7/ALTER_DEFAULT_PRIVILEGES.7.gz’: No space left on device
...

This happens after it finishes retrieving the image.

# lxc storage info default
info:
  description: ""
  driver: zfs
  name: default
  space used: 40.43GiB
  total space: 492.25GiB
used by:
  images:
  - 549a42b59cab0e73421759479633d5a9356f6dea2ff1f26c9ff52bf6fc59e6ef
  - 72565f3fbae414d317b90569b6d7aa308c482fdf562aaf0c2eaa6e50fa39747b
  - 8b150d2b1813da98b1fe2984a8ad7f9531c57c4a0689bbabf1f8322e853c4032
  instances:
  - docker-registry
  profiles:
  - default
# lxc storage volume list default
+-----------+------------------------------------------------------------------+-------------+--------------+---------+
|   TYPE    |                               NAME                               | DESCRIPTION | CONTENT-TYPE | USED BY |
+-----------+------------------------------------------------------------------+-------------+--------------+---------+
| container | docker-registry                                                  |             | filesystem   | 1       |
+-----------+------------------------------------------------------------------+-------------+--------------+---------+
| image     | 8b150d2b1813da98b1fe2984a8ad7f9531c57c4a0689bbabf1f8322e853c4032 |             | block        | 1       |
+-----------+------------------------------------------------------------------+-------------+--------------+---------+
| image     | 549a42b59cab0e73421759479633d5a9356f6dea2ff1f26c9ff52bf6fc59e6ef |             | filesystem   | 1       |
+-----------+------------------------------------------------------------------+-------------+--------------+---------+
| image     | 72565f3fbae414d317b90569b6d7aa308c482fdf562aaf0c2eaa6e50fa39747b |             | filesystem   | 1       |
+-----------+------------------------------------------------------------------+-------------+--------------+---------+

Anything I am missing here when using this block_mode?
The image itself is around 65GB in size, but given that there’s enough free space, that shouldn’t be an issue, right?

When I am launching a default “ubuntu:22.04” image, this works fine.

Can you show the output of lxc monitor --pretty in another terminal while you run the command that is failing?

Sure, here:

# lxc monitor --pretty
DEBUG  [2023-06-30T13:06:34Z] Event listener server handler started         id=ea3363dc-d45c-45e4-9ca7-3a8ff01b8db6 local=/var/snap/lxd/common/lxd/unix.socket remote=@
DEBUG  [2023-06-30T13:06:42Z] Handling API request                          ip=@ method=GET protocol=unix url=/1.0 username=root
DEBUG  [2023-06-30T13:06:42Z] Handling API request                          ip=@ method=GET protocol=unix url="/1.0/images?filter=&recursion=1" username=root
DEBUG  [2023-06-30T13:07:05Z] Handling API request                          ip=@ method=GET protocol=unix url=/1.0 username=root
DEBUG  [2023-06-30T13:07:05Z] Handling API request                          ip=@ method=GET protocol=unix url=/1.0/images/aliases/gh-ubuntu22-20230507-2.305.0-fuseoverlay username=root
DEBUG  [2023-06-30T13:07:05Z] Handling API request                          ip=@ method=GET protocol=unix url=/1.0/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 username=root
DEBUG  [2023-06-30T13:07:05Z] Handling API request                          ip=@ method=GET protocol=unix url=/1.0/events username=root
DEBUG  [2023-06-30T13:07:05Z] Event listener server handler started         id=8ea4265e-0a93-410b-af1c-fd7686f5fcec local=/var/snap/lxd/common/lxd/unix.socket remote=@
DEBUG  [2023-06-30T13:07:05Z] Handling API request                          ip=@ method=POST protocol=unix url=/1.0/instances username=root
DEBUG  [2023-06-30T13:07:05Z] Responding to instance create                
DEBUG  [2023-06-30T13:07:05Z] New operation                                 class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:05Z] Started operation                             class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:05Z] Acquiring lock for image                      fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:07:05Z] Lock acquired for image                       fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:07:05Z] Instance operation lock created               action=create instance=t project=default reusable=false
INFO   [2023-06-30T13:07:05Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:05.838564515 +0000 UTC"
INFO   [2023-06-30T13:07:05Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Pending StatusCode=Pending UpdatedAt="2023-06-30 13:07:05.838564515 +0000 UTC"
DEBUG  [2023-06-30T13:07:05Z] Handling API request                          ip=@ method=GET protocol=unix url=/1.0/operations/9d249450-9fbd-446c-a11b-d41db67b84ac username=root
INFO   [2023-06-30T13:07:05Z] Creating instance                             ephemeral=false instance=t instanceType=container project=default
DEBUG  [2023-06-30T13:07:05Z] Adding device                                 device=eth0 instance=t instanceType=container project=default type=nic
INFO   [2023-06-30T13:07:05Z] Action: instance-created, Source: /1.0/instances/t  location=none storage-pool=default type=container
INFO   [2023-06-30T13:07:05Z] Created instance                              ephemeral=false instance=t instanceType=container project=default
DEBUG  [2023-06-30T13:07:05Z] Adding device                                 device=root instance=t instanceType=container project=default type=disk
DEBUG  [2023-06-30T13:07:05Z] CreateInstanceFromImage started               driver=zfs instance=t pool=default project=default
DEBUG  [2023-06-30T13:07:05Z] EnsureImage started                           driver=zfs fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default
DEBUG  [2023-06-30T13:07:05Z] Block mode has changed, regenerating image volume  driver=zfs fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default
DEBUG  [2023-06-30T13:07:05Z] DeleteImage started                           driver=zfs fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default
INFO   [2023-06-30T13:07:05Z] Action: storage-volume-deleted, Source: /1.0/storage-pools/default/volumes/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23, Requestor: unix/root (@) 
DEBUG  [2023-06-30T13:07:05Z] DeleteImage finished                          driver=zfs fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default
DEBUG  [2023-06-30T13:07:07Z] Activated ZFS volume                          dev=default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23_ext4 driver=zfs pool=default volName=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
INFO   [2023-06-30T13:07:07Z] Image unpack started                          imageFile=/var/snap/lxd/common/lxd/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 volName=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:07:07Z] Mounted ZFS volume                            dev=default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23_ext4 driver=zfs path=/var/snap/lxd/common/lxd/storage-pools/default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default volName=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:07:07Z] Running filler function                       dev= driver=zfs path=/var/snap/lxd/common/lxd/storage-pools/default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default
INFO   [2023-06-30T13:07:08Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 1% (1.07GB/s) progress:map[percent:1 speed:1065128776 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:08.25961855 +0000 UTC"
DEBUG  [2023-06-30T13:07:08Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:09Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:09Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 2% (842.75MB/s) progress:map[percent:2 speed:842747288 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:09.151355594 +0000 UTC"
DEBUG  [2023-06-30T13:07:09Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:09Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 3% (887.62MB/s) progress:map[percent:3 speed:887617438 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:09.777175274 +0000 UTC"
DEBUG  [2023-06-30T13:07:10Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:10Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 4% (996.24MB/s) progress:map[percent:4 speed:996236145 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:10.172131003 +0000 UTC"
INFO   [2023-06-30T13:07:10Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 5% (1.09GB/s) progress:map[percent:5 speed:1088252770 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:10.532355158 +0000 UTC"
DEBUG  [2023-06-30T13:07:10Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:11Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:11Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 6% (1.11GB/s) progress:map[percent:6 speed:1109940277 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:11.036666918 +0000 UTC"
DEBUG  [2023-06-30T13:07:11Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:11Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 7% (1.04GB/s) progress:map[percent:7 speed:1042385554 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:11.850901866 +0000 UTC"
DEBUG  [2023-06-30T13:07:12Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:12Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 8% (1.10GB/s) progress:map[percent:8 speed:1101026186 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:12.193201472 +0000 UTC"
DEBUG  [2023-06-30T13:07:15Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:15Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 9% (759.38MB/s) progress:map[percent:9 speed:759380490 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:15.044256824 +0000 UTC"
DEBUG  [2023-06-30T13:07:16Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:16Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 10% (744.84MB/s) progress:map[percent:10 speed:744844811 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:16.022710142 +0000 UTC"
DEBUG  [2023-06-30T13:07:17Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:17Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 10% (691.38MB/s) progress:map[percent:10 speed:691383343 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:17.567367201 +0000 UTC"
DEBUG  [2023-06-30T13:07:18Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:18Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 11% (701.76MB/s) progress:map[percent:11 speed:701755338 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:18.30708774 +0000 UTC"
INFO   [2023-06-30T13:07:19Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 12% (667.92MB/s) progress:map[percent:12 speed:667917795 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:19.776492752 +0000 UTC"
DEBUG  [2023-06-30T13:07:19Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:20Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:20Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 13% (654.57MB/s) progress:map[percent:13 speed:654567051 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:20.973101454 +0000 UTC"
DEBUG  [2023-06-30T13:07:21Z] Handling API request                          ip=@ method=PUT protocol=unix url="/1.0/instances/garm-zajwYNpmIRKW/state?project=default" username=garm
INFO   [2023-06-30T13:07:21Z] ID: 446018a1-f977-4549-84f4-9b89528dc198, Class: task, Description: Stopping instance  CreatedAt="2023-06-30 13:07:21.957569947 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[instances:[/1.0/instances/garm-zajwYNpmIRKW]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:21.957569947 +0000 UTC"
DEBUG  [2023-06-30T13:07:21Z] New operation                                 class=task description="Stopping instance" operation=446018a1-f977-4549-84f4-9b89528dc198 project=default
INFO   [2023-06-30T13:07:21Z] ID: 446018a1-f977-4549-84f4-9b89528dc198, Class: task, Description: Stopping instance  CreatedAt="2023-06-30 13:07:21.957569947 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[instances:[/1.0/instances/garm-zajwYNpmIRKW]]" Status=Pending StatusCode=Pending UpdatedAt="2023-06-30 13:07:21.957569947 +0000 UTC"
DEBUG  [2023-06-30T13:07:21Z] Started operation                             class=task description="Stopping instance" operation=446018a1-f977-4549-84f4-9b89528dc198 project=default
DEBUG  [2023-06-30T13:07:21Z] Stop started                                  instance=garm-zajwYNpmIRKW instanceType=container project=default stateful=false
DEBUG  [2023-06-30T13:07:21Z] Instance operation lock created               action=stop instance=garm-zajwYNpmIRKW project=default reusable=false
INFO   [2023-06-30T13:07:21Z] Stopping instance                             action=stop created="2023-06-30 12:57:41.823260822 +0000 UTC" ephemeral=false instance=garm-zajwYNpmIRKW instanceType=container project=default stateful=false used="2023-06-30 12:57:43.792481317 +0000 UTC"
DEBUG  [2023-06-30T13:07:21Z] Handling API request                          ip=@ method=GET protocol=unix url="/1.0/operations/446018a1-f977-4549-84f4-9b89528dc198?project=default" username=garm
DEBUG  [2023-06-30T13:07:22Z] Handling API request                          ip=@ method=GET protocol=unix url="/internal/containers/garm-zajwYNpmIRKW/onstopns?netns=%2Fproc%2F2943719%2Ffd%2F4&project=default&target=stop" username=root
DEBUG  [2023-06-30T13:07:22Z] Instance operation lock inherited for stop    action=stop instance=garm-zajwYNpmIRKW instanceType=container project=default
DEBUG  [2023-06-30T13:07:22Z] Stopping device                               device=eth0 instance=garm-zajwYNpmIRKW instanceType=container project=default type=nic
DEBUG  [2023-06-30T13:07:22Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:22Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 14% (619.38MB/s) progress:map[percent:14 speed:619379093 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:22.73231208 +0000 UTC"
DEBUG  [2023-06-30T13:07:23Z] Handling API request                          ip=@ method=GET protocol=unix url="/internal/containers/garm-zajwYNpmIRKW/onstop?project=default&target=stop" username=root
DEBUG  [2023-06-30T13:07:23Z] Instance operation lock inherited for stop    action=stop instance=garm-zajwYNpmIRKW instanceType=container project=default
DEBUG  [2023-06-30T13:07:23Z] Instance stopped, cleaning up                 instance=garm-zajwYNpmIRKW instanceType=container project=default
DEBUG  [2023-06-30T13:07:23Z] Stopping device                               device=root instance=garm-zajwYNpmIRKW instanceType=container project=default type=disk
DEBUG  [2023-06-30T13:07:23Z] UnmountInstance started                       driver=zfs instance=garm-zajwYNpmIRKW pool=default project=default
DEBUG  [2023-06-30T13:07:24Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:24Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 15% (601.97MB/s) progress:map[percent:15 speed:601970329 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:24.200515277 +0000 UTC"
DEBUG  [2023-06-30T13:07:24Z] Unmounted ZFS dataset                         dev=default/containers/garm-zajwYNpmIRKW driver=zfs path=/var/snap/lxd/common/lxd/storage-pools/default/containers/garm-zajwYNpmIRKW pool=default volName=garm-zajwYNpmIRKW
DEBUG  [2023-06-30T13:07:24Z] UnmountInstance finished                      driver=zfs instance=garm-zajwYNpmIRKW pool=default project=default
INFO   [2023-06-30T13:07:25Z] Action: instance-stopped, Source: /1.0/instances/garm-zajwYNpmIRKW, Requestor: unix/garm (@) 
DEBUG  [2023-06-30T13:07:25Z] Scheduler: container garm-zajwYNpmIRKW stopped: re-balancing 
DEBUG  [2023-06-30T13:07:25Z] Instance operation lock finished              action=stop err="<nil>" instance=garm-zajwYNpmIRKW project=default reusable=false
DEBUG  [2023-06-30T13:07:25Z] Stop finished                                 instance=garm-zajwYNpmIRKW instanceType=container project=default stateful=false
DEBUG  [2023-06-30T13:07:25Z] Success for operation                         class=task description="Stopping instance" operation=446018a1-f977-4549-84f4-9b89528dc198 project=default
INFO   [2023-06-30T13:07:25Z] ID: 446018a1-f977-4549-84f4-9b89528dc198, Class: task, Description: Stopping instance  CreatedAt="2023-06-30 13:07:21.957569947 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[instances:[/1.0/instances/garm-zajwYNpmIRKW]]" Status=Success StatusCode=Success UpdatedAt="2023-06-30 13:07:21.957569947 +0000 UTC"
DEBUG  [2023-06-30T13:07:25Z] Handling API request                          ip=@ method=DELETE protocol=unix url="/1.0/instances/garm-zajwYNpmIRKW?project=default" username=garm
INFO   [2023-06-30T13:07:25Z] ID: a3c65e09-96af-4e73-9547-c2599a879d19, Class: task, Description: Deleting instance  CreatedAt="2023-06-30 13:07:25.028314033 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[containers:[/1.0/instances/garm-zajwYNpmIRKW] instances:[/1.0/instances/garm-zajwYNpmIRKW]]" Status=Pending StatusCode=Pending UpdatedAt="2023-06-30 13:07:25.028314033 +0000 UTC"
DEBUG  [2023-06-30T13:07:25Z] New operation                                 class=task description="Deleting instance" operation=a3c65e09-96af-4e73-9547-c2599a879d19 project=default
INFO   [2023-06-30T13:07:25Z] Deleting instance                             created="2023-06-30 12:57:41.823260822 +0000 UTC" ephemeral=false instance=garm-zajwYNpmIRKW instanceType=container project=default used="2023-06-30 12:57:43.792481317 +0000 UTC"
DEBUG  [2023-06-30T13:07:25Z] Started operation                             class=task description="Deleting instance" operation=a3c65e09-96af-4e73-9547-c2599a879d19 project=default
INFO   [2023-06-30T13:07:25Z] ID: a3c65e09-96af-4e73-9547-c2599a879d19, Class: task, Description: Deleting instance  CreatedAt="2023-06-30 13:07:25.028314033 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[containers:[/1.0/instances/garm-zajwYNpmIRKW] instances:[/1.0/instances/garm-zajwYNpmIRKW]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:25.028314033 +0000 UTC"
DEBUG  [2023-06-30T13:07:25Z] Instance operation lock created               action=delete instance=garm-zajwYNpmIRKW project=default reusable=false
DEBUG  [2023-06-30T13:07:25Z] Handling API request                          ip=@ method=GET protocol=unix url="/1.0/operations/a3c65e09-96af-4e73-9547-c2599a879d19?project=default" username=garm
DEBUG  [2023-06-30T13:07:25Z] DeleteInstance started                        driver=zfs instance=garm-zajwYNpmIRKW pool=default project=default
DEBUG  [2023-06-30T13:07:25Z] Deleting instance volume                      driver=zfs instance=garm-zajwYNpmIRKW pool=default project=default volName=garm-zajwYNpmIRKW
DEBUG  [2023-06-30T13:07:25Z] DeleteInstance finished                       driver=zfs instance=garm-zajwYNpmIRKW pool=default project=default
DEBUG  [2023-06-30T13:07:25Z] Removing device                               device=root instance=garm-zajwYNpmIRKW instanceType=container project=default type=disk
DEBUG  [2023-06-30T13:07:25Z] Removing device                               device=eth0 instance=garm-zajwYNpmIRKW instanceType=container project=default type=nic
INFO   [2023-06-30T13:07:25Z] Action: instance-deleted, Source: /1.0/instances/garm-zajwYNpmIRKW 
DEBUG  [2023-06-30T13:07:25Z] Success for operation                         class=task description="Deleting instance" operation=a3c65e09-96af-4e73-9547-c2599a879d19 project=default
DEBUG  [2023-06-30T13:07:25Z] Instance operation lock finished              action=delete err="<nil>" instance=garm-zajwYNpmIRKW project=default reusable=false
INFO   [2023-06-30T13:07:25Z] Deleted instance                              created="2023-06-30 12:57:41.823260822 +0000 UTC" ephemeral=false instance=garm-zajwYNpmIRKW instanceType=container project=default used="2023-06-30 12:57:43.792481317 +0000 UTC"
INFO   [2023-06-30T13:07:25Z] ID: a3c65e09-96af-4e73-9547-c2599a879d19, Class: task, Description: Deleting instance  CreatedAt="2023-06-30 13:07:25.028314033 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[]" Resources="map[containers:[/1.0/instances/garm-zajwYNpmIRKW] instances:[/1.0/instances/garm-zajwYNpmIRKW]]" Status=Success StatusCode=Success UpdatedAt="2023-06-30 13:07:25.028314033 +0000 UTC"
DEBUG  [2023-06-30T13:07:25Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:25Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 16% (597.67MB/s) progress:map[percent:16 speed:597667495 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:25.359728594 +0000 UTC"
DEBUG  [2023-06-30T13:07:26Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:26Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 17% (609.54MB/s) progress:map[percent:17 speed:609541133 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:26.035186742 +0000 UTC"
INFO   [2023-06-30T13:07:26Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 18% (633.70MB/s) progress:map[percent:18 speed:633702932 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:26.316285411 +0000 UTC"
DEBUG  [2023-06-30T13:07:26Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:27Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:27Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 19% (643.25MB/s) progress:map[percent:19 speed:643245369 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:27.006246384 +0000 UTC"
DEBUG  [2023-06-30T13:07:27Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:27Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 19% (665.78MB/s) progress:map[percent:19 speed:665780767 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:27.28577132 +0000 UTC"
DEBUG  [2023-06-30T13:07:27Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:27Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 20% (687.90MB/s) progress:map[percent:20 speed:687896266 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:27.559087793 +0000 UTC"
DEBUG  [2023-06-30T13:07:27Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:27Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 21% (707.61MB/s) progress:map[percent:21 speed:707607584 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:27.883817809 +0000 UTC"
INFO   [2023-06-30T13:07:28Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 22% (723.88MB/s) progress:map[percent:22 speed:723883761 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:28.288307545 +0000 UTC"
DEBUG  [2023-06-30T13:07:28Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:28Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:28Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 23% (743.62MB/s) progress:map[percent:23 speed:743622285 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:28.577222522 +0000 UTC"
DEBUG  [2023-06-30T13:07:28Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:28Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 24% (763.26MB/s) progress:map[percent:24 speed:763255571 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:28.85412235 +0000 UTC"
DEBUG  [2023-06-30T13:07:29Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:29Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 25% (770.91MB/s) progress:map[percent:25 speed:770912598 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:29.450223234 +0000 UTC"
DEBUG  [2023-06-30T13:07:29Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:29Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 26% (785.13MB/s) progress:map[percent:26 speed:785133250 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:29.847688394 +0000 UTC"
DEBUG  [2023-06-30T13:07:30Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:30Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 27% (803.23MB/s) progress:map[percent:27 speed:803229531 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:30.122178341 +0000 UTC"
DEBUG  [2023-06-30T13:07:30Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:30Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 28% (819.80MB/s) progress:map[percent:28 speed:819802455 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:30.426785057 +0000 UTC"
DEBUG  [2023-06-30T13:07:30Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:30Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 28% (833.76MB/s) progress:map[percent:28 speed:833764204 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:30.791464398 +0000 UTC"
INFO   [2023-06-30T13:07:31Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 29% (847.23MB/s) progress:map[percent:29 speed:847229884 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:31.15788213 +0000 UTC"
DEBUG  [2023-06-30T13:07:31Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:31Z] Event listener server handler stopped         listener=fb6a0a31-b528-4b0d-84db-dffd23b97f5c local=/var/snap/lxd/common/lxd/unix.socket remote=@
DEBUG  [2023-06-30T13:07:31Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:31Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 30% (852.06MB/s) progress:map[percent:30 speed:852055861 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:31.754587071 +0000 UTC"
INFO   [2023-06-30T13:07:32Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 31% (868.46MB/s) progress:map[percent:31 speed:868456520 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:32.015704646 +0000 UTC"
DEBUG  [2023-06-30T13:07:32Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:32Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:32Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 32% (883.98MB/s) progress:map[percent:32 speed:883977382 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:32.291640933 +0000 UTC"
DEBUG  [2023-06-30T13:07:32Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:32Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 33% (899.00MB/s) progress:map[percent:33 speed:899002775 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:32.571784524 +0000 UTC"
DEBUG  [2023-06-30T13:07:32Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:32Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 34% (914.38MB/s) progress:map[percent:34 speed:914377245 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:32.833101686 +0000 UTC"
DEBUG  [2023-06-30T13:07:33Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:33Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 35% (928.70MB/s) progress:map[percent:35 speed:928695737 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:33.114680096 +0000 UTC"
DEBUG  [2023-06-30T13:07:33Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:33Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 36% (942.58MB/s) progress:map[percent:36 speed:942579467 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:33.399559579 +0000 UTC"
DEBUG  [2023-06-30T13:07:33Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:33Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 37% (956.96MB/s) progress:map[percent:37 speed:956962994 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:33.662589103 +0000 UTC"
DEBUG  [2023-06-30T13:07:33Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:33Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 37% (971.20MB/s) progress:map[percent:37 speed:971197743 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:33.92184956 +0000 UTC"
INFO   [2023-06-30T13:07:34Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 38% (984.35MB/s) progress:map[percent:38 speed:984345981 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:34.202867832 +0000 UTC"
DEBUG  [2023-06-30T13:07:34Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:34Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:34Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 39% (993.62MB/s) progress:map[percent:39 speed:993619340 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:34.580990173 +0000 UTC"
DEBUG  [2023-06-30T13:07:34Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:34Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 40% (1.01GB/s) progress:map[percent:40 speed:1006888390 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:34.843883049 +0000 UTC"
DEBUG  [2023-06-30T13:07:35Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:35Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 41% (1.02GB/s) progress:map[percent:41 speed:1020101564 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:35.10144585 +0000 UTC"
DEBUG  [2023-06-30T13:07:35Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:35Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 42% (1.03GB/s) progress:map[percent:42 speed:1030518363 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:35.427509249 +0000 UTC"
INFO   [2023-06-30T13:07:35Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 43% (1.04GB/s) progress:map[percent:43 speed:1042321255 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:35.709728848 +0000 UTC"
DEBUG  [2023-06-30T13:07:35Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:36Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:36Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 44% (1.05GB/s) progress:map[percent:44 speed:1051523096 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:36.055646016 +0000 UTC"
DEBUG  [2023-06-30T13:07:36Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:36Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 45% (1.06GB/s) progress:map[percent:45 speed:1063571883 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:36.318685708 +0000 UTC"
DEBUG  [2023-06-30T13:07:36Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:36Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 46% (1.08GB/s) progress:map[percent:46 speed:1075232817 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:36.586265142 +0000 UTC"
INFO   [2023-06-30T13:07:36Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 46% (1.09GB/s) progress:map[percent:46 speed:1085079067 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:36.896881351 +0000 UTC"
DEBUG  [2023-06-30T13:07:36Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:37Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:37Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 47% (1.09GB/s) progress:map[percent:47 speed:1093673260 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:37.235720433 +0000 UTC"
DEBUG  [2023-06-30T13:07:37Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:37Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 48% (1.10GB/s) progress:map[percent:48 speed:1101961145 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:37.577582292 +0000 UTC"
INFO   [2023-06-30T13:07:37Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 49% (1.11GB/s) progress:map[percent:49 speed:1108634955 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:37.958364593 +0000 UTC"
DEBUG  [2023-06-30T13:07:37Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:38Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 50% (1.12GB/s) progress:map[percent:50 speed:1118798079 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:38.238972497 +0000 UTC"
DEBUG  [2023-06-30T13:07:38Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:38Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 51% (1.13GB/s) progress:map[percent:51 speed:1129081112 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:38.511252476 +0000 UTC"
DEBUG  [2023-06-30T13:07:38Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:38Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:38Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 52% (1.14GB/s) progress:map[percent:52 speed:1138223931 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:38.809774657 +0000 UTC"
DEBUG  [2023-06-30T13:07:39Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:39Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 53% (1.15GB/s) progress:map[percent:53 speed:1147241516 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:39.106972557 +0000 UTC"
INFO   [2023-06-30T13:07:39Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 54% (1.16GB/s) progress:map[percent:54 speed:1157487373 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:39.365865407 +0000 UTC"
DEBUG  [2023-06-30T13:07:39Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:39Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:39Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 55% (1.17GB/s) progress:map[percent:55 speed:1166542105 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:39.652835614 +0000 UTC"
DEBUG  [2023-06-30T13:07:39Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:39Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 55% (1.18GB/s) progress:map[percent:55 speed:1175951837 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:39.925649771 +0000 UTC"
DEBUG  [2023-06-30T13:07:40Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:40Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 56% (1.18GB/s) progress:map[percent:56 speed:1184310023 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:40.223001421 +0000 UTC"
DEBUG  [2023-06-30T13:07:40Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:40Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 57% (1.19GB/s) progress:map[percent:57 speed:1193749837 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:40.486430465 +0000 UTC"
DEBUG  [2023-06-30T13:07:40Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:40Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 58% (1.20GB/s) progress:map[percent:58 speed:1201803640 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:40.783865903 +0000 UTC"
DEBUG  [2023-06-30T13:07:42Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:42Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 59% (1.17GB/s) progress:map[percent:59 speed:1166971692 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:42.304866325 +0000 UTC"
DEBUG  [2023-06-30T13:07:43Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:43Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 60% (1.16GB/s) progress:map[percent:60 speed:1157950826 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:43.111510662 +0000 UTC"
DEBUG  [2023-06-30T13:07:44Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:44Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 61% (1.14GB/s) progress:map[percent:61 speed:1138657547 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:44.257925693 +0000 UTC"
DEBUG  [2023-06-30T13:07:44Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:44Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 62% (1.14GB/s) progress:map[percent:62 speed:1137599148 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:44.838464971 +0000 UTC"
DEBUG  [2023-06-30T13:07:45Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:45Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 63% (1.14GB/s) progress:map[percent:63 speed:1144004019 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:45.173852559 +0000 UTC"
INFO   [2023-06-30T13:07:45Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 64% (1.15GB/s) progress:map[percent:64 speed:1150378860 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:45.50650408 +0000 UTC"
DEBUG  [2023-06-30T13:07:45Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:45Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:45Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 64% (1.16GB/s) progress:map[percent:64 speed:1158086587 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:45.791553777 +0000 UTC"
DEBUG  [2023-06-30T13:07:46Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:46Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 65% (1.16GB/s) progress:map[percent:65 speed:1159694234 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:46.274811321 +0000 UTC"
DEBUG  [2023-06-30T13:07:46Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:46Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 66% (1.16GB/s) progress:map[percent:66 speed:1162288194 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:46.723597814 +0000 UTC"
DEBUG  [2023-06-30T13:07:47Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:47Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 67% (1.17GB/s) progress:map[percent:67 speed:1169147604 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:47.026236643 +0000 UTC"
DEBUG  [2023-06-30T13:07:47Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:47Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 68% (1.18GB/s) progress:map[percent:68 speed:1175925373 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:47.328129733 +0000 UTC"
DEBUG  [2023-06-30T13:07:47Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:47Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 69% (1.18GB/s) progress:map[percent:69 speed:1182708510 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:47.626370287 +0000 UTC"
DEBUG  [2023-06-30T13:07:47Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:47Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 70% (1.19GB/s) progress:map[percent:70 speed:1190077683 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:47.901401916 +0000 UTC"
DEBUG  [2023-06-30T13:07:48Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:48Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 71% (1.19GB/s) progress:map[percent:71 speed:1185505865 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:48.580950363 +0000 UTC"
DEBUG  [2023-06-30T13:07:49Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:49Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 72% (1.18GB/s) progress:map[percent:72 speed:1177427470 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:49.389623149 +0000 UTC"
DEBUG  [2023-06-30T13:07:49Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:49Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 73% (1.18GB/s) progress:map[percent:73 speed:1184489555 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:49.665797264 +0000 UTC"
DEBUG  [2023-06-30T13:07:49Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:49Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 73% (1.19GB/s) progress:map[percent:73 speed:1191559233 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:49.938422378 +0000 UTC"
DEBUG  [2023-06-30T13:07:50Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:50Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 74% (1.20GB/s) progress:map[percent:74 speed:1195359955 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:50.32414682 +0000 UTC"
DEBUG  [2023-06-30T13:07:50Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:50Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 75% (1.20GB/s) progress:map[percent:75 speed:1198732038 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:50.722811648 +0000 UTC"
INFO   [2023-06-30T13:07:51Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 76% (1.18GB/s) progress:map[percent:76 speed:1180443790 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:51.916444147 +0000 UTC"
DEBUG  [2023-06-30T13:07:51Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:52Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:52Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 77% (1.18GB/s) progress:map[percent:77 speed:1177600326 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:52.551181925 +0000 UTC"
DEBUG  [2023-06-30T13:07:52Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:52Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 78% (1.18GB/s) progress:map[percent:78 speed:1181697433 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:52.921695624 +0000 UTC"
DEBUG  [2023-06-30T13:07:53Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:53Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 79% (1.18GB/s) progress:map[percent:79 speed:1182381578 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:53.421323892 +0000 UTC"
DEBUG  [2023-06-30T13:07:53Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:53Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 80% (1.18GB/s) progress:map[percent:80 speed:1181875813 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:53.966929536 +0000 UTC"
INFO   [2023-06-30T13:07:54Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 81% (1.19GB/s) progress:map[percent:81 speed:1185941515 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:54.33246276 +0000 UTC"
DEBUG  [2023-06-30T13:07:54Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:54Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:54Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 82% (1.19GB/s) progress:map[percent:82 speed:1186443616 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:54.836727802 +0000 UTC"
INFO   [2023-06-30T13:07:55Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 82% (1.18GB/s) progress:map[percent:82 speed:1183299045 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:55.487457783 +0000 UTC"
DEBUG  [2023-06-30T13:07:55Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:55Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:55Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 83% (1.19GB/s) progress:map[percent:83 speed:1187132501 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:55.856771283 +0000 UTC"
DEBUG  [2023-06-30T13:07:56Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:56Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 84% (1.19GB/s) progress:map[percent:84 speed:1185926941 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:56.429991687 +0000 UTC"
DEBUG  [2023-06-30T13:07:56Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:56Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 85% (1.19GB/s) progress:map[percent:85 speed:1185870466 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:56.956568657 +0000 UTC"
DEBUG  [2023-06-30T13:07:57Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:57Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 86% (1.19GB/s) progress:map[percent:86 speed:1189941136 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:57.310454375 +0000 UTC"
INFO   [2023-06-30T13:07:57Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 87% (1.19GB/s) progress:map[percent:87 speed:1188797740 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:57.881165358 +0000 UTC"
DEBUG  [2023-06-30T13:07:57Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:07:58Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:58Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 88% (1.19GB/s) progress:map[percent:88 speed:1190863833 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:58.316131925 +0000 UTC"
DEBUG  [2023-06-30T13:07:58Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:58Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 89% (1.19GB/s) progress:map[percent:89 speed:1189064070 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:58.915629844 +0000 UTC"
DEBUG  [2023-06-30T13:07:59Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:59Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 90% (1.19GB/s) progress:map[percent:90 speed:1192813085 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:59.275792522 +0000 UTC"
DEBUG  [2023-06-30T13:07:59Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:07:59Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 91% (1.19GB/s) progress:map[percent:91 speed:1190314346 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:07:59.906419919 +0000 UTC"
DEBUG  [2023-06-30T13:08:00Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:00Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 91% (1.19GB/s) progress:map[percent:91 speed:1190769335 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:00.408565763 +0000 UTC"
DEBUG  [2023-06-30T13:08:00Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:00Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 92% (1.19GB/s) progress:map[percent:92 speed:1190747990 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:00.931626289 +0000 UTC"
DEBUG  [2023-06-30T13:08:01Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:01Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 93% (1.19GB/s) progress:map[percent:93 speed:1193258865 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:01.34058316 +0000 UTC"
INFO   [2023-06-30T13:08:01Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 94% (1.19GB/s) progress:map[percent:94 speed:1190744111 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:01.976035335 +0000 UTC"
DEBUG  [2023-06-30T13:08:01Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
DEBUG  [2023-06-30T13:08:02Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:02Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 95% (1.19GB/s) progress:map[percent:95 speed:1193827542 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:02.356553457 +0000 UTC"
DEBUG  [2023-06-30T13:08:02Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:02Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 96% (1.19GB/s) progress:map[percent:96 speed:1194864484 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:02.829429431 +0000 UTC"
DEBUG  [2023-06-30T13:08:03Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:03Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 97% (1.19GB/s) progress:map[percent:97 speed:1194315528 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:03.37532486 +0000 UTC"
DEBUG  [2023-06-30T13:08:03Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:03Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 98% (1.20GB/s) progress:map[percent:98 speed:1196518787 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:03.792359071 +0000 UTC"
DEBUG  [2023-06-30T13:08:05Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:05Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 99% (1.16GB/s) progress:map[percent:99 speed:1163534963 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:05.917494453 +0000 UTC"
DEBUG  [2023-06-30T13:08:06Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:06Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 100% (1.16GB/s) progress:map[percent:100 speed:1160412638 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:06.60994721 +0000 UTC"
DEBUG  [2023-06-30T13:08:07Z] Updated metadata for operation                class=task description="Creating instance" operation=9d249450-9fbd-446c-a11b-d41db67b84ac project=default
INFO   [2023-06-30T13:08:07Z] ID: 9d249450-9fbd-446c-a11b-d41db67b84ac, Class: task, Description: Creating instance  CreatedAt="2023-06-30 13:07:05.838564515 +0000 UTC" Err= Location=none MayCancel=false Metadata="map[create_instance_from_image_unpack_progress:Unpack: 100% (1.16GB/s) progress:map[percent:100 speed:1157505960 stage:create_instance_from_image_unpack]]" Resources="map[containers:[/1.0/instances/t] instances:[/1.0/instances/t]]" Status=Running StatusCode=Running UpdatedAt="2023-06-30 13:08:07.295043536 +0000 UTC"
INFO   [2023-06-30T13:08:26Z] Image unpack stopped                          imageFile=/var/snap/lxd/common/lxd/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 volName=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:08:27Z] Unmounted ZFS volume                          dev=default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23_ext4 driver=zfs path=/var/snap/lxd/common/lxd/storage-pools/default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default volName=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:08:27Z] Deactivated ZFS volume                        dev=default/images/494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23_ext4 driver=zfs pool=default volName=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23
DEBUG  [2023-06-30T13:08:28Z] EnsureImage finished                          driver=zfs fingerprint=494acb202e4e78d8c665122ccf410da354869e915d87bda7899c422ff1956b23 pool=default
DEBUG  [2023-06-30T13:08:28Z] CreateInstanceFromImage finished              driver=zfs instance=t pool=default project=default
DEBUG  [2023-06-30T13:08:28Z] Removing device                               device=eth0 instance=t instanceType=container project=default type=nic
DEBUG  [2023-06-30T13:08:28Z] Removing device                               device=root instance=t instanceType=container project=default type=disk
DEBUG  [2023-06-30T13:08:28Z] Instance operation lock finished              action=create err="<nil>" instance=t project=default reusable=false
Error: websocket: close 1006 (abnormal closure): unexpected EOF

After Error: websocket: close 1006 (abnormal closure): unexpected EOF on the monitoring console the errors about “No space left on device” started to spam on the first console with the launch command.

That’s what I did:

lxc storage set default volume.zfs.block_mode=true
snap set lxd shiftfs.enable=false
systemctl reload snap.lxd.daemon
lxc launch <image> t

Btw looks like ZFS 2.2 should bring some improvements to idmapped mounts and overlay support: https://github.com/openzfs/zfs/releases/tag/zfs-2.2.0-rc1

Yeah, I’ve been using an experimental build of ZFS with idmapped mounts support on my systems lately and it’s been really good!

1 Like

This sounds really promising, looking forward to the new ZFS release!

Does anyone in the meantime has an idea why the test above with the block volume fails?