snapcraft-20230615-224421.110012.log

2023-06-15 22:44:21.110 Starting Snapcraft 7.3.2
2023-06-15 22:44:21.111 lifecycle command: 'pack', arguments: Namespace(bind_ssh=False, build_for=None, debug=False, destructive_mode=False, directory=None, enable_developer_debug=False, enable_experimental_extensions=False, enable_experimental_target_arch=False, enable_experimental_ua_services=False, enable_manifest=False, http_proxy=None, https_proxy=None, manifest_image_information=None, output=None, provider=None, target_arch=None, ua_token=None, use_lxd=False)
2023-06-15 22:44:21.111 command: pack, arguments: Namespace(bind_ssh=False, build_for=None, debug=False, destructive_mode=False, directory=None, enable_developer_debug=False, enable_experimental_extensions=False, enable_experimental_target_arch=False, enable_experimental_ua_services=False, enable_manifest=False, http_proxy=None, https_proxy=None, manifest_image_information=None, output=None, provider=None, target_arch=None, ua_token=None, use_lxd=False)
2023-06-15 22:44:21.119 Skipping build-on: arm64 build-for: ['arm64'] because build-on doesn't match host arch: amd64
2023-06-15 22:44:21.119 CPU count (from process affinity): 24
2023-06-15 22:44:21.119 Invalid SNAPCRAFT_MAX_PARALLEL_BUILD_COUNT ''
2023-06-15 22:44:21.119 Running on amd64 for amd64
2023-06-15 22:44:21.121 The 'snap' directory is meant specifically for snapcraft, but it contains
the following non-snapcraft-related paths:
- snapcraft copy.yaml

This is unsupported and may cause unexpected behavior. If you must store
these files within the 'snap' directory, move them to 'snap/local'
which is ignored by snapcraft.
2023-06-15 22:44:21.121 Checking build provider availability
2023-06-15 22:44:21.128 Retrieved snap config: {}
2023-06-15 22:44:21.128 Using default provider 'lxd' on linux system.
2023-06-15 22:44:21.209 Executing on host: lxc --project default profile show local:default
2023-06-15 22:44:21.246 Using hostname 'snapcraft-fingerpaint-on-amd64-for-amd64-89665375'
2023-06-15 22:44:21.246 Launching instance...
2023-06-15 22:44:21.246 Executing on host: lxc remote list --format=yaml
2023-06-15 22:44:21.279 Remote 'craft-com.ubuntu.cloud-buildd' already exists.
2023-06-15 22:44:21.279 Deprecated: Parameter 'use_snapshots' is deprecated. Use parameter 'use_base_instance' instead.
2023-06-15 22:44:21.279 Executing on host: lxc project list local: --format=yaml
2023-06-15 22:44:21.316 Set LXD instance name to 'snapcraft-fingerpaint-on-amd64-for-amd64-89665375'
2023-06-15 22:44:21.316 Checking for instance 'snapcraft-fingerpaint-on-amd64-for-amd64-89665375' in project 'snapcraft' in remote 'local'
2023-06-15 22:44:21.316 Executing on host: lxc --project snapcraft list local: --format=yaml
2023-06-15 22:44:21.370 Executing on host: lxc --project snapcraft config get local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 raw.idmap
2023-06-15 22:44:21.401 Executing on host: lxc --project snapcraft list local: --format=yaml
2023-06-15 22:44:21.474 Instance exists and is not running. Starting instance.
2023-06-15 22:44:21.474 Executing on host: lxc --project snapcraft start local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375
2023-06-15 22:44:21.709 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 cat /etc/os-release
2023-06-15 22:44:21.770 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 test -f /etc/craft-instance.conf
2023-06-15 22:44:21.825 Executing on host: lxc --project snapcraft file pull local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375/etc/craft-instance.conf /root/tmp8yg2_3__.tmp-craft/tmp816tsxok
2023-06-15 22:44:21.878 Instance is compatible with compatibility tag 'snapcraft-buildd-base-v0.0'
2023-06-15 22:44:21.878 Waiting for environment to be ready...
2023-06-15 22:44:21.878 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 systemctl is-system-running
2023-06-15 22:44:21.917 systemctl is-system-running status: starting
2023-06-15 22:44:22.167 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 systemctl is-system-running
2023-06-15 22:44:22.255 systemctl is-system-running status: starting
2023-06-15 22:44:22.506 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 systemctl is-system-running
2023-06-15 22:44:22.582 systemctl is-system-running status: starting
2023-06-15 22:44:22.833 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 systemctl is-system-running
2023-06-15 22:44:22.925 systemctl is-system-running status: starting
2023-06-15 22:44:23.176 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 systemctl is-system-running
2023-06-15 22:44:23.262 Waiting for networking to be ready...
2023-06-15 22:44:23.263 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 getent hosts snapcraft.io
2023-06-15 22:44:23.391 Holding refreshes for snaps.
2023-06-15 22:44:23.391 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 snap set system refresh.hold=2023-06-16T22:44:23.391035Z
2023-06-15 22:44:23.633 Waiting for pending snap refreshes to complete.
2023-06-15 22:44:23.633 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 snap watch '--last=auto-refresh?'
2023-06-15 22:44:23.751 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 snap unset system proxy.http
2023-06-15 22:44:23.925 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 snap unset system proxy.https
2023-06-15 22:44:24.166 Installing snap 'snapcraft' with channel=None and classic=True
2023-06-15 22:44:24.166 Installing snap 'snapcraft' from host (classic=True)
2023-06-15 22:44:24.171 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 test -f /etc/craft-instance.conf
2023-06-15 22:44:24.270 Executing on host: lxc --project snapcraft file pull local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375/etc/craft-instance.conf /root/tmp0td5_cpo.tmp-craft/tmpv96etb2g
2023-06-15 22:44:24.319 Revisions found: host='9201', target='9201'
2023-06-15 22:44:24.319 Skipping snap injection: target is already up-to-date with revision on host
2023-06-15 22:44:24.319 Executing on host: lxc --project snapcraft config device show local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375
2023-06-15 22:44:24.360 Executing on host: lxc --project snapcraft config device add local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 disk-/root/project disk source=/home/david/fingerpaint path=/root/project
2023-06-15 22:44:24.404 Executing on host: lxc --project snapcraft file push /tmp/tmpehror_ch local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375/root/.bashrc --mode=644
2023-06-15 22:44:24.442 Executing in container: lxc --project snapcraft exec local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375 -- env PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin SNAPCRAFT_MANAGED_MODE=1 chown root:root /root/.bashrc
2023-06-15 22:44:24.484 Emitter: Pausing control of the terminal
2023-06-15 22:44:38.016 Emitter: Resuming control of the terminal
2023-06-15 22:44:38.148 Logs retrieved from managed instance:
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.887 Starting Snapcraft 7.3.2
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.887 lifecycle command: 'pack', arguments: Namespace(bind_ssh=False, build_for='amd64', debug=False, destructive_mode=False, directory=None, enable_developer_debug=False, enable_experimental_extensions=False, enable_experimental_target_arch=False, enable_experimental_ua_services=False, enable_manifest=False, http_proxy=None, https_proxy=None, manifest_image_information=None, output=None, provider=None, target_arch=None, ua_token=None, use_lxd=False)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.887 command: pack, arguments: Namespace(bind_ssh=False, build_for='amd64', debug=False, destructive_mode=False, directory=None, enable_developer_debug=False, enable_experimental_extensions=False, enable_experimental_target_arch=False, enable_experimental_ua_services=False, enable_manifest=False, http_proxy=None, https_proxy=None, manifest_image_information=None, output=None, provider=None, target_arch=None, ua_token=None, use_lxd=False)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.900 Skipping build-on: arm64 build-for: ['arm64'] because build-on doesn't match host arch: amd64
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.900 CPU count (from process affinity): 24
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.900 Invalid SNAPCRAFT_MAX_PARALLEL_BUILD_COUNT ''
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.900 Running on amd64 for amd64
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.901 Initializing parts lifecycle
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.902 is_snap: True, SNAP_NAME set to snapcraft
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.902 load state file: /root/parts/depends/state/pull
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.905 load state file: /root/parts/fingerpaint/state/pull
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.908 load state file: /root/parts/gnome/sdk/state/pull
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.910 load state file: /root/parts/depends/state/overlay
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.912 load state file: /root/parts/fingerpaint/state/overlay
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.914 load state file: /root/parts/gnome/sdk/state/overlay
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.916 load state file: /root/parts/depends/state/build
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.925 load state file: /root/parts/fingerpaint/state/build
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.933 load state file: /root/parts/gnome/sdk/state/build
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.941 load state file: /root/parts/depends/state/stage
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:26.956 load state file: /root/parts/fingerpaint/state/stage
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.050 load state file: /root/parts/gnome/sdk/state/stage
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.052 load state file: /root/parts/depends/state/prime
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.055 load state file: /root/parts/fingerpaint/state/prime
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.131 load state file: /root/parts/gnome/sdk/state/prime
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.133 process depends:Step.PULL
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.133 check if Part('depends'):Step.PULL is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.137 check if Part('depends'):Step.PULL is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.137 add action depends:Step.PULL(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.137 process fingerpaint:Step.PULL
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.137 check if Part('fingerpaint'):Step.PULL is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.137 check if Part('fingerpaint'):Step.PULL is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.137 ignore patterns: ['*.snap']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 updated files: {'snap/snapcraft.yaml'}
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 updated directories: set()
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 fingerpaint:Step.PULL is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 update step fingerpaint:Step.PULL
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 add action fingerpaint:Step.PULL(ActionType.UPDATE)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 process gnome/sdk:Step.PULL
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 check if Part('gnome/sdk'):Step.PULL is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.187 check if Part('gnome/sdk'):Step.PULL is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 ignore patterns: ['*.snap']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 updated files: set()
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 updated directories: set()
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 add action gnome/sdk:Step.PULL(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 process depends:Step.OVERLAY
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('depends'):Step.OVERLAY is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('depends'):Step.OVERLAY is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 add action depends:Step.OVERLAY(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 process fingerpaint:Step.OVERLAY
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('fingerpaint'):Step.OVERLAY is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('fingerpaint'):Step.OVERLAY is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 fingerpaint:Step.OVERLAY is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 update step fingerpaint:Step.OVERLAY
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 add action fingerpaint:Step.OVERLAY(ActionType.UPDATE)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 process gnome/sdk:Step.OVERLAY
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('gnome/sdk'):Step.OVERLAY is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('gnome/sdk'):Step.OVERLAY is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 add action gnome/sdk:Step.OVERLAY(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 process depends:Step.BUILD
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('depends'):Step.BUILD is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('depends'):Step.BUILD is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 add action depends:Step.BUILD(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 process fingerpaint:Step.BUILD
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.188 check if Part('fingerpaint'):Step.BUILD is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('fingerpaint'):Step.BUILD is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 fingerpaint:Step.BUILD is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 update step fingerpaint:Step.BUILD
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action fingerpaint:Step.BUILD(ActionType.UPDATE)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process gnome/sdk:Step.BUILD
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('gnome/sdk'):Step.BUILD is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('gnome/sdk'):Step.BUILD is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action gnome/sdk:Step.BUILD(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process depends:Step.STAGE
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('depends'):Step.STAGE is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('depends'):Step.STAGE is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action depends:Step.STAGE(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process fingerpaint:Step.STAGE
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('fingerpaint'):Step.STAGE is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('fingerpaint'):Step.STAGE is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 fingerpaint:Step.STAGE is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 rerun step fingerpaint:Step.STAGE
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action fingerpaint:Step.STAGE(ActionType.RERUN)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process gnome/sdk:Step.STAGE
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('gnome/sdk'):Step.STAGE is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('gnome/sdk'):Step.STAGE is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action gnome/sdk:Step.STAGE(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process depends:Step.PRIME
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('depends'):Step.PRIME is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('depends'):Step.PRIME is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action depends:Step.PRIME(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process fingerpaint:Step.PRIME
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action fingerpaint:Step.PRIME(ActionType.RUN)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 process gnome/sdk:Step.PRIME
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('gnome/sdk'):Step.PRIME is dirty
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 check if Part('gnome/sdk'):Step.PRIME is outdated
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 add action gnome/sdk:Step.PRIME(ActionType.SKIP)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 Executing parts lifecycle...
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 ignore patterns: ['*.snap']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.189 plugin build packages: {'findutils', 'python3-venv', 'python3-dev'}
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.190 ignore patterns: ['*.snap']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.190 plugin build packages: {'gcc', 'make'}
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.190 part build snaps: ['gnome-42-2204-sdk']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.190 Requested build-packages: ['findutils', 'gcc', 'make', 'python3-dev', 'python3-venv']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.416 Marking python3-dev (and its dependencies) to be fetched
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.416 package: python3-dev
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.419 Marking make (and its dependencies) to be fetched
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.419 package: make
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.421 Marking findutils (and its dependencies) to be fetched
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.421 package: findutils
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.423 Marking python3-venv (and its dependencies) to be fetched
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.423 package: python3-venv
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.425 Marking gcc (and its dependencies) to be fetched
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.425 package: gcc
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:27.435 Requested build-packages already installed: ['findutils', 'gcc', 'make', 'python3-dev', 'python3-venv']
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.687 verify plugin environment for part 'depends'
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.688 verify plugin environment for part 'fingerpaint'
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.688 verify plugin environment for part 'gnome/sdk'
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.689 Executing parts lifecycle: skip pull depends (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.689 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.689 execute action depends:Action(part_name='depends', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.689 Skip execution of Action(part_name='depends', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.690 Executed: skip pull depends (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.690 Executing parts lifecycle: update sources for fingerpaint (source changed)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.690 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.690 execute action fingerpaint:Action(part_name='fingerpaint', step=Step.PULL, action_type=ActionType.UPDATE, reason='source changed', project_vars=None, properties=ActionProperties(changed_files=['snap/snapcraft.yaml'], changed_dirs=[]))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.738 updated files: {'snap/snapcraft.yaml'}
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.738 updated directories: set()
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.739 load state file: /root/parts/fingerpaint/state/pull
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.745 Executed: update sources for fingerpaint (source changed)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.745 Executing parts lifecycle: skip pull gnome/sdk (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.745 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.745 execute action gnome/sdk:Action(part_name='gnome/sdk', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.745 Skip execution of Action(part_name='gnome/sdk', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.746 Executed: skip pull gnome/sdk (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.746 Executing parts lifecycle: skip overlay depends (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.746 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.747 execute action depends:Action(part_name='depends', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.747 Skip execution of Action(part_name='depends', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.748 Executed: skip overlay depends (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.748 Executing parts lifecycle: update overlay for fingerpaint ('PULL' step changed)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.748 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.748 execute action fingerpaint:Action(part_name='fingerpaint', step=Step.OVERLAY, action_type=ActionType.UPDATE, reason="'PULL' step changed", project_vars=None, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.749 Executed: update overlay for fingerpaint ('PULL' step changed)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.749 Executing parts lifecycle: skip overlay gnome/sdk (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.749 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.749 execute action gnome/sdk:Action(part_name='gnome/sdk', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.749 Skip execution of Action(part_name='gnome/sdk', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.750 Executed: skip overlay gnome/sdk (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.750 Executing parts lifecycle: skip build depends (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.750 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.750 execute action depends:Action(part_name='depends', step=Step.BUILD, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.750 Skip execution of Action(part_name='depends', step=Step.BUILD, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.752 Executed: skip build depends (already ran)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.752 Executing parts lifecycle: update build for fingerpaint ('PULL' step changed)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.752 Executing action
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.752 execute action fingerpaint:Action(part_name='fingerpaint', step=Step.BUILD, action_type=ActionType.UPDATE, reason="'PULL' step changed", project_vars=None, properties=ActionProperties(changed_files=['snap/snapcraft.yaml'], changed_dirs=[]))
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.752 ignore patterns: []
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.761 updated files: set()
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.761 updated directories: {'snap'}
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.762 remove directory /root/parts/fingerpaint/install
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.770 load state file: /root/parts/fingerpaint/state/pull
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.773 Executing PosixPath('/root/parts/fingerpaint/run/build.sh')
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:28.776 :: + python3 -m venv /root/parts/fingerpaint/install
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:30.861 :: + PARTS_PYTHON_VENV_INTERP_PATH=/root/parts/fingerpaint/install/bin/python3
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:30.861 :: + pip install -U pip setuptools wheel
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:31.573 :: Defaulting to user installation because normal site-packages is not writeable
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:31.616 :: Requirement already satisfied: pip in /snap/gnome-42-2204-sdk/167/usr/lib/python3/dist-packages (22.0.2)
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:31.935 :: Collecting pip
2023-06-15 22:44:38.148 :: 2023-06-15 19:44:31.940 ::   Using cached pip-23.1.2-py3-none-any.whl (2.1 MB)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:31.946 :: Requirement already satisfied: setuptools in /snap/gnome-42-2204-sdk/167/usr/lib/python3/dist-packages (59.6.0)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.157 :: Collecting setuptools
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.160 ::   Using cached setuptools-67.8.0-py3-none-any.whl (1.1 MB)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.162 :: Requirement already satisfied: wheel in /snap/gnome-42-2204-sdk/167/usr/lib/python3/dist-packages (0.37.1)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.265 :: Collecting wheel
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.266 ::   Using cached wheel-0.40.0-py3-none-any.whl (64 kB)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.316 :: Installing collected packages: wheel, setuptools, pip
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.334 ::   WARNING: The script wheel is installed in '/root/.local/bin' which is not on PATH.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.334 ::   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.974 ::   WARNING: The scripts pip, pip3 and pip3.10 are installed in '/root/.local/bin' which is not on PATH.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.974 ::   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.991 :: ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.991 :: fingerpaint 1.4.0 requires pycairo<=1.22.0,>=1.20.0, but you have pycairo 1.23.0 which is incompatible.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.991 :: Successfully installed pip-23.1.2 setuptools-67.8.0 wheel-0.40.0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:32.991 :: WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:33.026 :: + '[' -f setup.py ']'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:33.026 :: + pip install -U .
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:33.689 :: Defaulting to user installation because normal site-packages is not writeable
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:33.731 :: Processing /root/parts/fingerpaint/build
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:33.793 ::   Installing build dependencies: started
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.114 ::   Installing build dependencies: finished with status 'done'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.117 ::   Getting requirements to build wheel: started
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.293 ::   Getting requirements to build wheel: finished with status 'done'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.294 ::   Preparing metadata (pyproject.toml): started
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.567 ::   Preparing metadata (pyproject.toml): finished with status 'done'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.573 :: Requirement already satisfied: PyGObject<4.0.0,>=3.44.1 in /snap/gnome-42-2204-sdk/167/usr/lib/python3/dist-packages (from fingerpaint==1.4.0) (3.44.1)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.574 :: Requirement already satisfied: evdev<2.0.0,>=1.6.1 in /root/.local/lib/python3.10/site-packages (from fingerpaint==1.4.0) (1.6.1)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.574 :: Requirement already satisfied: pyudev<0.25.0,>=0.24.1 in /root/.local/lib/python3.10/site-packages (from fingerpaint==1.4.0) (0.24.1)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.850 :: Collecting pycairo<=1.22.0,>=1.20.0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:35.850 ::   Using cached pycairo-1.22.0-cp310-cp310-linux_x86_64.whl
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.037 :: Collecting setuptools<68.0.0,>=67.8.0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.039 ::   Using cached setuptools-67.8.0-py3-none-any.whl (1.1 MB)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.058 :: Building wheels for collected packages: fingerpaint
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.058 ::   Building wheel for fingerpaint (pyproject.toml): started
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.351 ::   Building wheel for fingerpaint (pyproject.toml): finished with status 'done'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.351 ::   Created wheel for fingerpaint: filename=fingerpaint-1.4.0-py3-none-any.whl size=21697 sha256=75a6a1db89af48a950f2393e2e37ca6578c2448c4338b2b36b95e06609454c63
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.351 ::   Stored in directory: /tmp/pip-ephem-wheel-cache-_22yv2dl/wheels/dc/f7/e4/87d7320e82181d0ad9f11178f4a4071056a7769d367238a8ca
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.352 :: Successfully built fingerpaint
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.386 :: Installing collected packages: setuptools, pycairo, fingerpaint
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.593 ::   Attempting uninstall: fingerpaint
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.593 ::     Found existing installation: fingerpaint 1.4.0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.596 ::     Uninstalling fingerpaint-1.4.0:
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.596 ::       Successfully uninstalled fingerpaint-1.4.0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.601 ::   WARNING: The script fingerpaint is installed in '/root/.local/bin' which is not on PATH.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.601 ::   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.606 :: Successfully installed fingerpaint-1.4.0 pycairo-1.22.0 setuptools-67.8.0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.606 :: WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.644 :: + find /root/parts/fingerpaint/install -type f -executable -print0
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.645 :: + xargs -0 sed -i '1 s|^#\!/root/parts/fingerpaint/install/bin/python3.*$|#\!/usr/bin/env python3|'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.659 :: ++ determine_link_target
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.659 :: +++ set +o +x
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.659 :: +++ grep xtrace
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.667 :: ++ opts_state='set -o xtrace'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.667 :: +++ dirname /root/parts/fingerpaint/install/bin/python3
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.668 :: ++ interp_dir=/root/parts/fingerpaint/install/bin
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.668 :: +++ which python3
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.670 :: ++ python_path=/snap/gnome-42-2204-sdk/current/usr/bin/python3
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.670 :: +++ readlink -e /snap/gnome-42-2204-sdk/current/usr/bin/python3
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.671 :: ++ python_path=/snap/gnome-42-2204-sdk/167/usr/bin/python3.10
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.671 :: ++ for dir in "/root/parts/fingerpaint/install" "/root/stage"
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.672 :: ++ echo /snap/gnome-42-2204-sdk/167/usr/bin/python3.10
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.672 :: ++ grep -q /root/parts/fingerpaint/install
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.673 :: ++ for dir in "/root/parts/fingerpaint/install" "/root/stage"
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.673 :: ++ echo /snap/gnome-42-2204-sdk/167/usr/bin/python3.10
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.673 :: ++ grep -q /root/stage
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.675 :: ++ echo /snap/gnome-42-2204-sdk/167/usr/bin/python3.10
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.675 :: ++ eval 'set -o xtrace'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.675 :: +++ set -o xtrace
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.675 :: + python_path=/snap/gnome-42-2204-sdk/167/usr/bin/python3.10
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.675 :: + ln -sf /snap/gnome-42-2204-sdk/167/usr/bin/python3.10 /root/parts/fingerpaint/install/bin/python3
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.941 Executed: update build for fingerpaint ('PULL' step changed)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.941 Executing parts lifecycle: skip build gnome/sdk (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.941 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.942 execute action gnome/sdk:Action(part_name='gnome/sdk', step=Step.BUILD, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.942 Skip execution of Action(part_name='gnome/sdk', step=Step.BUILD, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.943 Executed: skip build gnome/sdk (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.943 Executing parts lifecycle: skip stage depends (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.943 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.943 execute action depends:Action(part_name='depends', step=Step.STAGE, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.943 Skip execution of Action(part_name='depends', step=Step.STAGE, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.944 Executed: skip stage depends (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.944 Executing parts lifecycle: restage fingerpaint ('BUILD' step changed)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.944 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:36.944 execute action fingerpaint:Action(part_name='fingerpaint', step=Step.STAGE, action_type=ActionType.RERUN, reason="'BUILD' step changed", project_vars=None, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.061 clean fingerpaint:Step.STAGE
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.061 load state file: /root/parts/depends/state/stage
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.077 load state file: /root/parts/fingerpaint/state/stage
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.153 load state file: /root/parts/gnome/sdk/state/stage
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.167 clean fingerpaint:Step.PRIME
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.167 load state file: /root/parts/depends/state/prime
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.169 load state file: /root/parts/fingerpaint/state/prime
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.247 load state file: /root/parts/gnome/sdk/state/prime
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.463 Executed: restage fingerpaint ('BUILD' step changed)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.463 Executing parts lifecycle: skip stage gnome/sdk (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.463 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.463 execute action gnome/sdk:Action(part_name='gnome/sdk', step=Step.STAGE, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.463 Skip execution of Action(part_name='gnome/sdk', step=Step.STAGE, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.464 Executed: skip stage gnome/sdk (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.465 Executing parts lifecycle: skip prime depends (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.465 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.465 execute action depends:Action(part_name='depends', step=Step.PRIME, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.465 Skip execution of Action(part_name='depends', step=Step.PRIME, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.466 Executed: skip prime depends (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.466 Executing parts lifecycle: prime fingerpaint
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.466 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.466 execute action fingerpaint:Action(part_name='fingerpaint', step=Step.PRIME, action_type=ActionType.RUN, reason=None, project_vars=None, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.675 patch_elf: not enabled for part 'fingerpaint'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.676 Executed: prime fingerpaint
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.676 Executing parts lifecycle: skip prime gnome/sdk (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.676 Executing action
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.676 execute action gnome/sdk:Action(part_name='gnome/sdk', step=Step.PRIME, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None))
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.676 Skip execution of Action(part_name='gnome/sdk', step=Step.PRIME, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='1.4.0', updated=False), 'grade': ProjectVar(value='stable', updated=False)}, properties=ActionProperties(changed_files=None, changed_dirs=None)) (because already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.677 Executed: skip prime gnome/sdk (already ran)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.677 Executed parts lifecycle
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.677 Extracting and updating metadata...
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.677 Copying snap assets...
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.678 finalize icon: 'snap/gui/fingerpaint.svg'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.679 relative icon path: 'meta/gui/icon.svg'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.679 Generating snap metadata...
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.681 Generated snap metadata
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.681 Reading snap metadata...
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.683 Running linters...
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.683 Running linter: classic
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.683 Running linter: library
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.708 Extracting ELF attributes: 'usr/bin/xinput'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.710 check metadata for provider snap '/snap/gtk-common-themes/current'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.715 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.715 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.715 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.715 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.715 check metadata for provider snap '/snap/gtk-common-themes/current'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.720 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.720 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.720 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.720 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.720 check metadata for provider snap '/snap/gtk-common-themes/current'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.725 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.725 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.725 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.725 content dirs: set()
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.725 check metadata for provider snap '/snap/gnome-42-2204/current'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.726 content dirs: {PosixPath('/snap/gnome-42-2204/current')}
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.726 Getting dependencies for 'usr/bin/xinput'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.727 executing: LD_LIBRARY_PATH=/root/prime/lib:/snap/gnome-42-2204/current/lib:/snap/gnome-42-2204/current/usr/lib:/snap/gnome-42-2204/current/lib/x86_64-linux-gnu:/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu:/snap/core22/current/lib:/snap/core22/current/usr/lib:/snap/core22/current/lib/x86_64-linux-gnu:/snap/core22/current/usr/lib/x86_64-linux-gnu /usr/bin/ldd usr/bin/xinput
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.832 _ldd_resolve: 'libX11.so.6' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libX11.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.832 _ldd_resolve: 'libXi.so.6' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXi.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libXrandr.so.2' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrandr.so.2'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libXinerama.so.1' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXinerama.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libc.so.6' '/snap/core22/current/lib/x86_64-linux-gnu/libc.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libxcb.so.1' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libxcb.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libXext.so.6' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXext.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libXrender.so.1' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrender.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libXau.so.6' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXau.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libXdmcp.so.6' '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXdmcp.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libbsd.so.0' '/snap/core22/current/lib/x86_64-linux-gnu/libbsd.so.0'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 _ldd_resolve: 'libmd.so.0' '/snap/core22/current/lib/x86_64-linux-gnu/libmd.so.0'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.833 Crawling to find soname 'libX11.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.834 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libX11.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.837 libX11.so.6 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libX11.so.6 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libX11.so.6' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.837 Crawling to find soname 'libXi.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.837 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXi.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.838 libXi.so.6 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXi.so.6 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXi.so.6' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.838 Crawling to find soname 'libXrandr.so.2'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.838 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrandr.so.2'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.840 libXrandr.so.2 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrandr.so.2 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrandr.so.2' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.840 Crawling to find soname 'libXinerama.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.840 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXinerama.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.841 libXinerama.so.1 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXinerama.so.1 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXinerama.so.1' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.841 Crawling to find soname 'libc.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.841 Extracting ELF attributes: '/snap/core22/current/lib/x86_64-linux-gnu/libc.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.847 libc.so.6 with original path /snap/core22/current/lib/x86_64-linux-gnu/libc.so.6 found on '/snap/core22/current/lib/x86_64-linux-gnu/libc.so.6' in base True
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.847 Crawling to find soname 'libxcb.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.847 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libxcb.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.849 libxcb.so.1 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libxcb.so.1 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libxcb.so.1' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.849 Crawling to find soname 'libXext.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.849 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXext.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.851 libXext.so.6 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXext.so.6 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXext.so.6' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.851 Crawling to find soname 'libXrender.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.851 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrender.so.1'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.852 libXrender.so.1 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrender.so.1 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXrender.so.1' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.852 Crawling to find soname 'libXau.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.852 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXau.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.853 libXau.so.6 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXau.so.6 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXau.so.6' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.853 Crawling to find soname 'libXdmcp.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.853 Extracting ELF attributes: '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXdmcp.so.6'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.855 libXdmcp.so.6 with original path /snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXdmcp.so.6 found on '/snap/gnome-42-2204/current/usr/lib/x86_64-linux-gnu/libXdmcp.so.6' in base False
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.855 Crawling to find soname 'libbsd.so.0'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.855 Extracting ELF attributes: '/snap/core22/current/lib/x86_64-linux-gnu/libbsd.so.0'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.857 libbsd.so.0 with original path /snap/core22/current/lib/x86_64-linux-gnu/libbsd.so.0 found on '/snap/core22/current/lib/x86_64-linux-gnu/libbsd.so.0' in base True
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.857 Crawling to find soname 'libmd.so.0'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.857 Extracting ELF attributes: '/snap/core22/current/lib/x86_64-linux-gnu/libmd.so.0'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.858 libmd.so.0 with original path /snap/core22/current/lib/x86_64-linux-gnu/libmd.so.0 found on '/snap/core22/current/lib/x86_64-linux-gnu/libmd.so.0' in base True
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.860 dynamic linker name is: 'ld-linux-x86-64.so.2'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.860 pack_snap: output=None, compression='xz'
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.860 pack_snap: check skeleton
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.880 Creating snap package...
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.880 Pack command: ['snap', 'pack', '--filename', 'fingerpaint_1.4.0_amd64.snap', '--compression', 'xz', PosixPath('/root/prime'), PosixPath('/root/project')]
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.900 Cannot pack snap file: Command '['snap', 'pack', '--filename', 'fingerpaint_1.4.0_amd64.snap', '--compression', 'xz', PosixPath('/root/prime'), PosixPath('/root/project')]' returned non-zero exit status 1. (2023/06/15 19:44:37.899502 container.go:215: in snap "fingerpaint": path "bin/fingerpaint" does not exist
2023-06-15 22:44:38.149 :: error: cannot pack "/root/prime": snap is unusable due to missing files)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902 Traceback (most recent call last):
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902   File "/snap/snapcraft/9201/lib/python3.8/site-packages/snapcraft/pack.py", line 134, in pack_snap
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902     proc = subprocess.run(
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902   File "/snap/snapcraft/9201/usr/lib/python3.8/subprocess.py", line 516, in run
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902     raise CalledProcessError(retcode, process.args,
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902 subprocess.CalledProcessError: Command '['snap', 'pack', '--filename', 'fingerpaint_1.4.0_amd64.snap', '--compression', 'xz', PosixPath('/root/prime'), PosixPath('/root/project')]' returned non-zero exit status 1.
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902 During handling of the above exception, another exception occurred:
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902 Traceback (most recent call last):
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902   File "/snap/snapcraft/9201/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 294, in _run_command
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902     _run_lifecycle_and_pack(
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902   File "/snap/snapcraft/9201/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 364, in _run_lifecycle_and_pack
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902     snap_filename = pack.pack_snap(
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902   File "/snap/snapcraft/9201/lib/python3.8/site-packages/snapcraft/pack.py", line 141, in pack_snap
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902     raise errors.SnapcraftError(msg)
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902 snapcraft.errors.SnapcraftError: Cannot pack snap file: Command '['snap', 'pack', '--filename', 'fingerpaint_1.4.0_amd64.snap', '--compression', 'xz', PosixPath('/root/prime'), PosixPath('/root/project')]' returned non-zero exit status 1. (2023/06/15 19:44:37.899502 container.go:215: in snap "fingerpaint": path "bin/fingerpaint" does not exist
2023-06-15 22:44:38.149 :: 2023-06-15 19:44:37.902 error: cannot pack "/root/prime": snap is unusable due to missing files)
2023-06-15 22:44:39.320 Failed to execute pack in instance.
2023-06-15 22:44:39.320 Detailed information: Run the same command again with --debug to shell into the environment if you wish to introspect this failure.
2023-06-15 22:44:39.321 Traceback (most recent call last):
2023-06-15 22:44:39.321   File "/snap/snapcraft/9201/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 557, in _run_in_provider
2023-06-15 22:44:39.321     instance.execute_run(cmd, check=True, cwd=output_dir)
2023-06-15 22:44:39.321   File "/snap/snapcraft/9201/lib/python3.8/site-packages/craft_providers/lxd/lxd_instance.py", line 289, in execute_run
2023-06-15 22:44:39.321     return self.lxc.exec(
2023-06-15 22:44:39.321   File "/snap/snapcraft/9201/lib/python3.8/site-packages/craft_providers/lxd/lxc.py", line 410, in exec
2023-06-15 22:44:39.321     return runner(final_cmd, **kwargs)  # pylint: disable=subprocess-run-check
2023-06-15 22:44:39.321   File "/snap/snapcraft/9201/usr/lib/python3.8/subprocess.py", line 516, in run
2023-06-15 22:44:39.321     raise CalledProcessError(retcode, process.args,
2023-06-15 22:44:39.321 subprocess.CalledProcessError: Command '['lxc', '--project', 'snapcraft', 'exec', 'local:snapcraft-fingerpaint-on-amd64-for-amd64-89665375', '--cwd', '/root/project', '--', 'env', 'PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin', 'SNAPCRAFT_MANAGED_MODE=1', 'snapcraft', 'pack', '--verbosity=brief', '--build-for', 'amd64']' returned non-zero exit status 1.
2023-06-15 22:44:39.321 Full execution log: '/root/.local/state/snapcraft/log/snapcraft-20230615-224421.110012.log'