:::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:15:20 ] :: [ BEGIN ] :: Running 'pushd data' /var/tmp/tmt/run-006/plans/features/core/discover/default-0/tests/tests/core/spaces/data /var/tmp/tmt/run-006/plans/features/core/discover/default-0/tests/tests/core/spaces :: [ 05:15:20 ] :: [ PASS ] :: Command 'pushd data' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 0s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Test (local) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:15:21 ] :: [ BEGIN ] :: Running 'tmt run -arvvv provision -h local' /var/tmp/tmt/run-010 Found 1 plan. /a directory/some plans/a plan discover how: fmf name: some discover order: 50 directory: /var/tmp/tmt/run-006/plans/features/core/discover/default-0/tests/tests/core/spaces/data summary: 1 test selected /a directory/some tests/a shell test provision queued provision.provision task #1: default-0 provision.provision task #1: default-0 how: local order: 50 guest: localhost multihost name: default-0 arch: x86_64 distro: Fedora Linux 38 (Workstation Edition) kernel: 6.6.11-100.fc38.x86_64 package manager: dnf5 selinux: yes is superuser: no summary: 1 guest provisioned prepare queued push task #1: push to default-0 push task #1: push to default-0 summary: 0 preparations applied execute queued execute task #1: some discover on default-0 execute task #1: some discover on default-0 how: tmt order: 50 exit-first: false test: A simple shell test with spaces cmd: ./test.sh out: Everything's fine! 00:00:00 pass /a directory/some tests/a shell test (on default-0) [1/1] summary: 1 test executed report how: display order: 50 pass /a directory/some tests/a shell test output.txt: /var/tmp/tmt/run-010/a-directory/some-plans/a-plan/execute/data/guest/default-0/a-directory/some-tests/a-shell-test-1/output.txt content: Everything's fine! summary: 1 test passed finish Prune '/a directory/some plans/a plan' plan workdir '/var/tmp/tmt/run-010/a-directory/some-plans/a-plan'. summary: 0 tasks completed total: 1 test passed :: [ 05:15:23 ] :: [ PASS ] :: Command 'tmt run -arvvv provision -h local' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 2s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Test (local)) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Test (container) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:15:23 ] :: [ BEGIN ] :: Running 'tmt run -arvvv provision -h container' /var/tmp/tmt/run-010 Found 1 plan. /a directory/some plans/a plan discover how: fmf name: some discover order: 50 directory: /var/tmp/tmt/run-006/plans/features/core/discover/default-0/tests/tests/core/spaces/data summary: 1 test selected /a directory/some tests/a shell test provision queued provision.provision task #1: default-0 provision.provision task #1: default-0 how: container order: 50 Check for container image 'fedora'. Pull image 'fedora'. fail: Command 'podman pull -q fedora' returned 1. Pull image 'fedora'. fail: Command 'podman pull -q fedora' returned 1. Pull image 'fedora'. fail: Command 'podman pull -q fedora' returned 1. Pull image 'fedora'. fail: Command 'podman pull -q fedora' returned 1. Pull image 'fedora'. fail: Command 'podman pull -q fedora' returned 1. fail: Retries of 'Pulling 'fedora' image' unsuccessful. finish Prune '/a directory/some plans/a plan' plan workdir '/var/tmp/tmt/run-010/a-directory/some-plans/a-plan'. summary: 0 tasks completed plan failed Traceback (most recent call last): File "/home/lnie/tmt/tmt/__main__.py", line 18, in run_cli tmt.cli.main() File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/core.py", line 1157, in __call__ return self.main(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/core.py", line 1078, in main rv = self.invoke(ctx) ^^^^^^^^^^^^^^^^ File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/core.py", line 1688, in invoke return _process_result(sub_ctx.command.invoke(sub_ctx)) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/core.py", line 1720, in invoke return _process_result(rv) ^^^^^^^^^^^^^^^^^^^ File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/core.py", line 1657, in _process_result value = ctx.invoke(self._result_callback, value, **ctx.params) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/core.py", line 783, in invoke return __callback(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/.local/share/hatch/env/virtual/tmt/tNaCst1p/dev/lib/python3.11/site-packages/click/decorators.py", line 33, in new_func return f(get_current_context(), *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/cli.py", line 480, in finito click_context.obj.run.go() File "/home/lnie/tmt/tmt/base.py", line 3527, in go raise tmt.utils.GeneralError( tmt.utils.GeneralError: plan failed The exception was caused by 1 earlier exceptions Cause number 1: provision step failed Traceback (most recent call last): File "/home/lnie/tmt/tmt/base.py", line 3523, in go plan.go() File "/home/lnie/tmt/tmt/base.py", line 2261, in go step.go() File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 2211, in go raise tmt.utils.GeneralError( tmt.utils.GeneralError: provision step failed The exception was caused by 1 earlier exceptions Cause number 1: Retries of 'Pulling 'fedora' image' unsuccessful. Traceback (most recent call last): File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 1907, in go future.result() File "/usr/lib64/python3.11/concurrent/futures/_base.py", line 449, in result return self.__get_result() ^^^^^^^^^^^^^^^^^^^ File "/usr/lib64/python3.11/concurrent/futures/_base.py", line 401, in __get_result raise self._exception File "/usr/lib64/python3.11/concurrent/futures/thread.py", line 58, in run result = self.fn(*self.args, **self.kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 455, in go self._guest.start() File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 175, in start retry( File "/home/lnie/tmt/tmt/utils.py", line 6386, in retry raise RetryError(label, causes=exceptions) tmt.utils.RetryError: Retries of 'Pulling 'fedora' image' unsuccessful. The exception was caused by 5 earlier exceptions Cause number 1: Command 'podman pull -q fedora' returned 1. stderr (1 lines) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ time="2024-01-22T05:15:25-05:00" level=error msg="XDG_RUNTIME_DIR directory \"/run/user/0\" is not owned by the current user" ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "/home/lnie/tmt/tmt/utils.py", line 6377, in retry return func(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 125, in pull_image self.podman( File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 284, in podman raise err File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 278, in podman return self._run_guest_command(Command('podman') + command, silent=silent, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 836, in _run_guest_command return self.run( ^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 1384, in run return command.run( ^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 772, in run raise RunError( tmt.utils.RunError: Command 'podman pull -q fedora' returned 1. Cause number 2: Command 'podman pull -q fedora' returned 1. stderr (1 lines) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ time="2024-01-22T05:15:30-05:00" level=error msg="XDG_RUNTIME_DIR directory \"/run/user/0\" is not owned by the current user" ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "/home/lnie/tmt/tmt/utils.py", line 6377, in retry return func(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 125, in pull_image self.podman( File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 284, in podman raise err File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 278, in podman return self._run_guest_command(Command('podman') + command, silent=silent, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 836, in _run_guest_command return self.run( ^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 1384, in run return command.run( ^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 772, in run raise RunError( tmt.utils.RunError: Command 'podman pull -q fedora' returned 1. Cause number 3: Command 'podman pull -q fedora' returned 1. stderr (1 lines) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ time="2024-01-22T05:15:35-05:00" level=error msg="XDG_RUNTIME_DIR directory \"/run/user/0\" is not owned by the current user" ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "/home/lnie/tmt/tmt/utils.py", line 6377, in retry return func(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 125, in pull_image self.podman( File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 284, in podman raise err File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 278, in podman return self._run_guest_command(Command('podman') + command, silent=silent, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 836, in _run_guest_command return self.run( ^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 1384, in run return command.run( ^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 772, in run raise RunError( tmt.utils.RunError: Command 'podman pull -q fedora' returned 1. Cause number 4: Command 'podman pull -q fedora' returned 1. stderr (1 lines) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ time="2024-01-22T05:15:40-05:00" level=error msg="XDG_RUNTIME_DIR directory \"/run/user/0\" is not owned by the current user" ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "/home/lnie/tmt/tmt/utils.py", line 6377, in retry return func(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 125, in pull_image self.podman( File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 284, in podman raise err File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 278, in podman return self._run_guest_command(Command('podman') + command, silent=silent, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 836, in _run_guest_command return self.run( ^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 1384, in run return command.run( ^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 772, in run raise RunError( tmt.utils.RunError: Command 'podman pull -q fedora' returned 1. Cause number 5: Command 'podman pull -q fedora' returned 1. stderr (1 lines) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ time="2024-01-22T05:15:45-05:00" level=error msg="XDG_RUNTIME_DIR directory \"/run/user/0\" is not owned by the current user" ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "/home/lnie/tmt/tmt/utils.py", line 6377, in retry return func(*args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 125, in pull_image self.podman( File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 284, in podman raise err File "/home/lnie/tmt/tmt/steps/provision/podman.py", line 278, in podman return self._run_guest_command(Command('podman') + command, silent=silent, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/steps/provision/__init__.py", line 836, in _run_guest_command return self.run( ^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 1384, in run return command.run( ^^^^^^^^^^^^ File "/home/lnie/tmt/tmt/utils.py", line 772, in run raise RunError( tmt.utils.RunError: Command 'podman pull -q fedora' returned 1. :: [ 05:15:50 ] :: [ FAIL ] :: Command 'tmt run -arvvv provision -h container' (Expected 0, got 2) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 27s :: Assertions: 0 good, 1 bad :: RESULT: FAIL (Test (container)) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:15:51 ] :: [ BEGIN ] :: Running 'popd' /var/tmp/tmt/run-006/plans/features/core/discover/default-0/tests/tests/core/spaces :: [ 05:15:51 ] :: [ PASS ] :: Command 'popd' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:15:51 ] :: [ LOG ] :: Phases fingerprint: MY+bwdss :: [ 05:15:51 ] :: [ LOG ] :: Asserts fingerprint: MY+bwdss :: [ 05:15:51 ] :: [ LOG ] :: JOURNAL XML: /var/tmp/tmt/run-006/plans/features/core/execute/data/guest/default-0/tests/core/spaces-22/journal.xml :: [ 05:15:51 ] :: [ LOG ] :: JOURNAL TXT: /var/tmp/tmt/run-006/plans/features/core/execute/data/guest/default-0/tests/core/spaces-22/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 31s :: Phases: 3 good, 1 bad :: OVERALL RESULT: FAIL ()