Cannot override stage

Bug #1995895 reported by jarred wilson
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
Snapcraft
New
Undecided
Unassigned

Bug Description

When trying to use override-stage, an error occurs causing the process to fail as seen here:

$ snapcraft pack --verbosity debug
2022/11/07 15:15:16.175263 cmd_run.go:1046: WARNING: cannot create user data directory: cannot read symlink: readlink /home/jardon/snap/snapcraft/current: invalid argument
2022-11-07 15:15:19.418 Starting Snapcraft 7.2.6
2022-11-07 15:15:19.419 Logging execution to '/home/jardon/.cache/snapcraft/log/snapcraft-20221107-151519.417377.log'
2022-11-07 15:15:19.421 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)
2022-11-07 15:15:19.421 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)
2022-11-07 15:15:19.460 CPU count (from process affinity): 16
2022-11-07 15:15:19.461 Invalid SNAPCRAFT_MAX_PARALLEL_BUILD_COUNT value
2022-11-07 15:15:19.461 Running on amd64 for amd64
2022-11-07 15:15:19.469 Checking build provider availability
2022-11-07 15:15:19.488 Retrieved snap config: {}
2022-11-07 15:15:19.490 Using default provider 'lxd' on linux system.
2022-11-07 15:15:19.491 Emitter: Pausing control of the terminal
2022-11-07 15:15:20.287 Emitter: Resuming control of the terminal
2022-11-07 15:15:20.288 Launching instance...
2022-11-07 15:15:26.325 Emitter: Pausing control of the terminal
2022-11-07 20:15:34.332 Starting Snapcraft 7.2.6
2022-11-07 20:15:34.333 Logging execution to '/tmp/snapcraft.log'
2022-11-07 20:15:34.336 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)
2022-11-07 20:15:34.336 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)
2022-11-07 20:15:34.395 CPU count (from process affinity): 16
2022-11-07 20:15:34.395 Invalid SNAPCRAFT_MAX_PARALLEL_BUILD_COUNT value
2022-11-07 20:15:34.395 Running on amd64 for amd64
2022-11-07 20:15:34.401 Initializing parts lifecycle
2022-11-07 20:15:34.402 is_snap: True, SNAP_NAME set to snapcraft
2022-11-07 20:15:34.405 load state file: /root/parts/mysql-server-deb/state/pull
2022-11-07 20:15:34.418 load state file: /root/parts/mysql-server-deb/state/overlay
2022-11-07 20:15:34.427 load state file: /root/parts/mysql-server-deb/state/build
2022-11-07 20:15:34.463 load state file: /root/parts/mysql-server-deb/state/stage
2022-11-07 20:15:35.045 load state file: /root/parts/mysql-server-deb/state/prime
2022-11-07 20:15:35.571 process mysql-server-deb:Step.PULL
2022-11-07 20:15:35.572 check if Part('mysql-server-deb'):Step.PULL is dirty
2022-11-07 20:15:35.581 check if Part('mysql-server-deb'):Step.PULL is outdated
2022-11-07 20:15:35.582 add action mysql-server-deb:Step.PULL(ActionType.SKIP)
2022-11-07 20:15:35.582 process mysql-server-deb:Step.OVERLAY
2022-11-07 20:15:35.582 check if Part('mysql-server-deb'):Step.OVERLAY is dirty
2022-11-07 20:15:35.582 check if Part('mysql-server-deb'):Step.OVERLAY is outdated
2022-11-07 20:15:35.583 add action mysql-server-deb:Step.OVERLAY(ActionType.SKIP)
2022-11-07 20:15:35.583 process mysql-server-deb:Step.BUILD
2022-11-07 20:15:35.583 check if Part('mysql-server-deb'):Step.BUILD is dirty
2022-11-07 20:15:35.583 mysql-server-deb:Step.BUILD is dirty
2022-11-07 20:15:35.583 rerun step mysql-server-deb:Step.BUILD
2022-11-07 20:15:35.584 add action mysql-server-deb:Step.BUILD(ActionType.RERUN)
2022-11-07 20:15:35.584 process mysql-server-deb:Step.STAGE
2022-11-07 20:15:35.584 add action mysql-server-deb:Step.STAGE(ActionType.RUN)
2022-11-07 20:15:35.585 process mysql-server-deb:Step.PRIME
2022-11-07 20:15:35.585 add action mysql-server-deb:Step.PRIME(ActionType.RUN)
2022-11-07 20:15:35.585 Executing parts lifecycle...
2022-11-07 20:15:35.586 part build packages: ['mysql-server-8.0']
2022-11-07 20:15:35.586 Requested build-packages: ['mysql-server-8.0']
2022-11-07 20:15:36.715 Marking mysql-server-8.0 (and its dependencies) to be fetched
2022-11-07 20:15:36.715 package: mysql-server-8.0
2022-11-07 20:15:36.782 Requested build-packages already installed: ['mysql-server-8.0']
2022-11-07 20:15:37.730 verify plugin environment for part 'mysql-server-deb'
2022-11-07 20:15:37.733 Executing parts lifecycle: skip pull mysql-server-deb (already ran)
2022-11-07 20:15:37.733 Executing action
2022-11-07 20:15:37.750 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)})
2022-11-07 20:15:37.750 Skip execution of Action(part_name='mysql-server-deb', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)}) (because already ran)
2022-11-07 20:15:37.751 Executed: skip pull mysql-server-deb (already ran)
2022-11-07 20:15:37.751 Executing parts lifecycle: skip overlay mysql-server-deb (already ran)
2022-11-07 20:15:37.752 Executing action
2022-11-07 20:15:37.753 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)})
2022-11-07 20:15:37.753 Skip execution of Action(part_name='mysql-server-deb', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)}) (because already ran)
2022-11-07 20:15:37.754 Executed: skip overlay mysql-server-deb (already ran)
2022-11-07 20:15:37.754 Executing parts lifecycle: rebuild mysql-server-deb ('override-build' property changed)
2022-11-07 20:15:37.754 Executing action
2022-11-07 20:15:37.755 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.BUILD, action_type=ActionType.RERUN, reason="'override-build' property changed", project_vars=None)
2022-11-07 20:15:37.755 clean mysql-server-deb:Step.BUILD
2022-11-07 20:15:37.756 remove directory /root/parts/mysql-server-deb/build
2022-11-07 20:15:37.756 remove directory /root/parts/mysql-server-deb/install
2022-11-07 20:15:37.949 clean mysql-server-deb:Step.STAGE
2022-11-07 20:15:37.950 load state file: /root/parts/mysql-server-deb/state/stage
2022-11-07 20:15:38.705 clean mysql-server-deb:Step.PRIME
2022-11-07 20:15:38.707 load state file: /root/parts/mysql-server-deb/state/prime
2022-11-07 20:15:39.714 load state file: /root/parts/mysql-server-deb/state/pull
2022-11-07 20:15:42.459 fix artifacts: unpack_dir='/root/parts/mysql-server-deb/install'
2022-11-07 20:15:44.569 remove directory /root/parts/mysql-server-deb/build
2022-11-07 20:15:44.586 :: + cp -r /var/lib/mysql /var/lib/mysql-files /var/lib/mysql-keyring /var/lib/mysql-upgrade /root/parts/mysql-server-deb/install/var/lib/
2022-11-07 20:15:45.995 Executed: rebuild mysql-server-deb ('override-build' property changed)
2022-11-07 20:15:45.995 Executing parts lifecycle: stage mysql-server-deb
2022-11-07 20:15:45.995 Executing action
2022-11-07 20:15:45.996 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.STAGE, action_type=ActionType.RUN, reason=None, project_vars=None)
2022-11-07 20:15:46.761 :: + echo stage
2022-11-07 20:15:46.761 :: stage
2022-11-07 20:15:46.968 Executed: stage mysql-server-deb
2022-11-07 20:15:46.968 Executing parts lifecycle: prime mysql-server-deb
2022-11-07 20:15:46.968 Executing action
2022-11-07 20:15:46.969 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.PRIME, action_type=ActionType.RUN, reason=None, project_vars=None)
2022-11-07 20:15:47.742 /root/stage/bin: No such file or directory
2022-11-07 20:15:47.752 Traceback (most recent call last):
2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/parts.py", line 169, in run
2022-11-07 20:15:47.753 aex.execute(action, stdout=stream, stderr=stream)
2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/executor.py", line 301, in execute
2022-11-07 20:15:47.753 self._executor.execute(actions, stdout=stdout, stderr=stderr)
2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/executor.py", line 126, in execute
2022-11-07 20:15:47.753 self._run_action(act, stdout=stdout, stderr=stderr)
2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/executor.py", line 189, in _run_action
2022-11-07 20:15:47.753 handler.run_action(action, stdout=stdout, stderr=stderr)
2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/part_handler.py", line 161, in run_action
2022-11-07 20:15:47.753 state = handler(step_info, stdout=stdout, stderr=stderr)
2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/part_handler.py", line 397, in _run_prime
2022-11-07 20:15:47.754 contents = self._run_step(
2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/part_handler.py", line 477, in _run_step
2022-11-07 20:15:47.754 return step_handler.run_builtin()
2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/step_handler.py", line 106, in run_builtin
2022-11-07 20:15:47.754 return handler()
2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/step_handler.py", line 182, in _builtin_prime
2022-11-07 20:15:47.754 files, dirs = migrate_files(
2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/migration.py", line 72, in migrate_files
2022-11-07 20:15:47.754 file_utils.create_similar_directory(str(src), str(dst))
2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/utils/file_utils.py", line 238, in create_similar_directory
2022-11-07 20:15:47.754 stat = os.stat(source, follow_symlinks=False)
2022-11-07 20:15:47.755 FileNotFoundError: [Errno 2] No such file or directory: '/root/stage/bin'
2022-11-07 20:15:47.755
2022-11-07 20:15:47.755 The above exception was the direct cause of the following exception:
2022-11-07 20:15:47.755 Traceback (most recent call last):
2022-11-07 20:15:47.755 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 284, in _run_command
2022-11-07 20:15:47.755 _run_lifecycle_and_pack(
2022-11-07 20:15:47.755 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 327, in _run_lifecycle_and_pack
2022-11-07 20:15:47.755 lifecycle.run(
2022-11-07 20:15:47.755 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/parts.py", line 182, in run
2022-11-07 20:15:47.755 raise errors.PartsLifecycleError(msg) from err
2022-11-07 20:15:47.755 snapcraft.errors.PartsLifecycleError: /root/stage/bin: No such file or directory
2022-11-07 15:15:48.111 Emitter: Resuming control of the terminal
2022-11-07 15:15:48.839 Logs retrieved from managed instance:
2022-11-07 15:15:48.840 :: 2022-11-07 20:15:34.332 Starting Snapcraft 7.2.6
2022-11-07 15:15:48.840 :: 2022-11-07 20:15:34.336 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)
2022-11-07 15:15:48.840 :: 2022-11-07 20:15:34.336 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)
2022-11-07 15:15:48.840 :: 2022-11-07 20:15:34.395 CPU count (from process affinity): 16
2022-11-07 15:15:48.840 :: 2022-11-07 20:15:34.395 Invalid SNAPCRAFT_MAX_PARALLEL_BUILD_COUNT value
2022-11-07 15:15:48.840 :: 2022-11-07 20:15:34.395 Running on amd64 for amd64
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:34.401 Initializing parts lifecycle
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:34.402 is_snap: True, SNAP_NAME set to snapcraft
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:34.405 load state file: /root/parts/mysql-server-deb/state/pull
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:34.418 load state file: /root/parts/mysql-server-deb/state/overlay
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:34.427 load state file: /root/parts/mysql-server-deb/state/build
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:34.463 load state file: /root/parts/mysql-server-deb/state/stage
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:35.045 load state file: /root/parts/mysql-server-deb/state/prime
2022-11-07 15:15:48.841 :: 2022-11-07 20:15:35.571 process mysql-server-deb:Step.PULL
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.572 check if Part('mysql-server-deb'):Step.PULL is dirty
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.581 check if Part('mysql-server-deb'):Step.PULL is outdated
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.582 add action mysql-server-deb:Step.PULL(ActionType.SKIP)
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.582 process mysql-server-deb:Step.OVERLAY
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.582 check if Part('mysql-server-deb'):Step.OVERLAY is dirty
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.582 check if Part('mysql-server-deb'):Step.OVERLAY is outdated
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.583 add action mysql-server-deb:Step.OVERLAY(ActionType.SKIP)
2022-11-07 15:15:48.842 :: 2022-11-07 20:15:35.583 process mysql-server-deb:Step.BUILD
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.583 check if Part('mysql-server-deb'):Step.BUILD is dirty
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.583 mysql-server-deb:Step.BUILD is dirty
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.583 rerun step mysql-server-deb:Step.BUILD
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.584 add action mysql-server-deb:Step.BUILD(ActionType.RERUN)
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.584 process mysql-server-deb:Step.STAGE
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.584 add action mysql-server-deb:Step.STAGE(ActionType.RUN)
2022-11-07 15:15:48.843 :: 2022-11-07 20:15:35.585 process mysql-server-deb:Step.PRIME
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:35.585 add action mysql-server-deb:Step.PRIME(ActionType.RUN)
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:35.585 Executing parts lifecycle...
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:35.586 part build packages: ['mysql-server-8.0']
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:35.586 Requested build-packages: ['mysql-server-8.0']
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:36.715 Marking mysql-server-8.0 (and its dependencies) to be fetched
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:36.715 package: mysql-server-8.0
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:36.782 Requested build-packages already installed: ['mysql-server-8.0']
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:37.730 verify plugin environment for part 'mysql-server-deb'
2022-11-07 15:15:48.844 :: 2022-11-07 20:15:37.733 Executing parts lifecycle: skip pull mysql-server-deb (already ran)
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.733 Executing action
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.750 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)})
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.750 Skip execution of Action(part_name='mysql-server-deb', step=Step.PULL, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)}) (because already ran)
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.751 Executed: skip pull mysql-server-deb (already ran)
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.751 Executing parts lifecycle: skip overlay mysql-server-deb (already ran)
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.752 Executing action
2022-11-07 15:15:48.845 :: 2022-11-07 20:15:37.753 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)})
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.753 Skip execution of Action(part_name='mysql-server-deb', step=Step.OVERLAY, action_type=ActionType.SKIP, reason='already ran', project_vars={'version': ProjectVar(value='8.0', updated=False), 'grade': ProjectVar(value='devel', updated=False)}) (because already ran)
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.754 Executed: skip overlay mysql-server-deb (already ran)
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.754 Executing parts lifecycle: rebuild mysql-server-deb ('override-build' property changed)
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.754 Executing action
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.755 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.BUILD, action_type=ActionType.RERUN, reason="'override-build' property changed", project_vars=None)
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.755 clean mysql-server-deb:Step.BUILD
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.756 remove directory /root/parts/mysql-server-deb/build
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.756 remove directory /root/parts/mysql-server-deb/install
2022-11-07 15:15:48.846 :: 2022-11-07 20:15:37.949 clean mysql-server-deb:Step.STAGE
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:37.950 load state file: /root/parts/mysql-server-deb/state/stage
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:38.705 clean mysql-server-deb:Step.PRIME
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:38.707 load state file: /root/parts/mysql-server-deb/state/prime
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:39.714 load state file: /root/parts/mysql-server-deb/state/pull
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:42.459 fix artifacts: unpack_dir='/root/parts/mysql-server-deb/install'
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:44.569 remove directory /root/parts/mysql-server-deb/build
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:44.586 :: + cp -r /var/lib/mysql /var/lib/mysql-files /var/lib/mysql-keyring /var/lib/mysql-upgrade /root/parts/mysql-server-deb/install/var/lib/
2022-11-07 15:15:48.847 :: 2022-11-07 20:15:45.995 Executed: rebuild mysql-server-deb ('override-build' property changed)
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:45.995 Executing parts lifecycle: stage mysql-server-deb
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:45.995 Executing action
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:45.996 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.STAGE, action_type=ActionType.RUN, reason=None, project_vars=None)
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:46.761 :: + echo stage
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:46.761 :: stage
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:46.968 Executed: stage mysql-server-deb
2022-11-07 15:15:48.848 :: 2022-11-07 20:15:46.968 Executing parts lifecycle: prime mysql-server-deb
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:46.968 Executing action
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:46.969 execute action mysql-server-deb:Action(part_name='mysql-server-deb', step=Step.PRIME, action_type=ActionType.RUN, reason=None, project_vars=None)
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:47.742 /root/stage/bin: No such file or directory
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:47.752 Traceback (most recent call last):
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/parts.py", line 169, in run
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:47.753 aex.execute(action, stdout=stream, stderr=stream)
2022-11-07 15:15:48.849 :: 2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/executor.py", line 301, in execute
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 self._executor.execute(actions, stdout=stdout, stderr=stderr)
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/executor.py", line 126, in execute
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 self._run_action(act, stdout=stdout, stderr=stderr)
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/executor.py", line 189, in _run_action
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 handler.run_action(action, stdout=stdout, stderr=stderr)
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/part_handler.py", line 161, in run_action
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.753 state = handler(step_info, stdout=stdout, stderr=stderr)
2022-11-07 15:15:48.850 :: 2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/part_handler.py", line 397, in _run_prime
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 contents = self._run_step(
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/part_handler.py", line 477, in _run_step
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 return step_handler.run_builtin()
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/step_handler.py", line 106, in run_builtin
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 return handler()
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/step_handler.py", line 182, in _builtin_prime
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 files, dirs = migrate_files(
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/executor/migration.py", line 72, in migrate_files
2022-11-07 15:15:48.851 :: 2022-11-07 20:15:47.754 file_utils.create_similar_directory(str(src), str(dst))
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.754 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_parts/utils/file_utils.py", line 238, in create_similar_directory
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.754 stat = os.stat(source, follow_symlinks=False)
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.755 FileNotFoundError: [Errno 2] No such file or directory: '/root/stage/bin'
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.755
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.755 The above exception was the direct cause of the following exception:
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.755 Traceback (most recent call last):
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.755 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 284, in _run_command
2022-11-07 15:15:48.852 :: 2022-11-07 20:15:47.755 _run_lifecycle_and_pack(
2022-11-07 15:15:48.853 :: 2022-11-07 20:15:47.755 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 327, in _run_lifecycle_and_pack
2022-11-07 15:15:48.853 :: 2022-11-07 20:15:47.755 lifecycle.run(
2022-11-07 15:15:48.853 :: 2022-11-07 20:15:47.755 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/parts.py", line 182, in run
2022-11-07 15:15:48.853 :: 2022-11-07 20:15:47.755 raise errors.PartsLifecycleError(msg) from err
2022-11-07 15:15:48.853 :: 2022-11-07 20:15:47.755 snapcraft.errors.PartsLifecycleError: /root/stage/bin: No such file or directory
2022-11-07 15:15:51.793 Failed to execute pack in instance.
2022-11-07 15:15:51.793 Detailed information: Run the same command again with --debug to shell into the environment if you wish to introspect this failure.
2022-11-07 15:15:51.799 Traceback (most recent call last):
2022-11-07 15:15:51.803 File "/snap/snapcraft/8513/lib/python3.8/site-packages/snapcraft/parts/lifecycle.py", line 553, in _run_in_provider
2022-11-07 15:15:51.804 instance.execute_run(cmd, check=True, cwd=output_dir)
2022-11-07 15:15:51.805 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_providers/lxd/lxd_instance.py", line 289, in execute_run
2022-11-07 15:15:51.807 return self.lxc.exec(
2022-11-07 15:15:51.808 File "/snap/snapcraft/8513/lib/python3.8/site-packages/craft_providers/lxd/lxc.py", line 329, in exec
2022-11-07 15:15:51.809 return runner(final_cmd, **kwargs) # pylint: disable=subprocess-run-check
2022-11-07 15:15:51.809 File "/snap/snapcraft/8513/usr/lib/python3.8/subprocess.py", line 516, in run
2022-11-07 15:15:51.810 raise CalledProcessError(retcode, process.args,
2022-11-07 15:15:51.811 subprocess.CalledProcessError: Command '['lxc', '--project', 'snapcraft', 'exec', 'local:snapcraft-mysql-server-on-amd64-for-amd64-3801258', '--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=debug', '--build-for', 'amd64']' returned non-zero exit status 1.
2022-11-07 15:15:51.811 Full execution log: '/home/jardon/.cache/snapcraft/log/snapcraft-20221107-151519.417377.log'

To reproduce:
git clone <email address hidden>:jardon/mysql-server-snap.git
cd mysql-server-snap
git checkout stage-bug
snapcraft pack --verbosity debug

To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.