CI run for vmdb2

Radicle repository id rad:z2kxCtBwDQMPcaf9vGTNH5nYkp9qk

Table of Contents

Run log

[2025-09-06T05:25:42Z INFO  ambient] ambient starts
[=3h[=3hBdsDxe: failed to load Boot0001 "UEFI QEMU DVD-ROM QM00005 " from PciRoot(0x0)/Pci(0x1F,0x2)/Sata(0x2,0xFFFF,0x0): Not Found
BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)
BdsDxe: starting Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)
Welcome to GRUB!


INFO: Block devices
NAME   MAJ:MIN RM  SIZE RO TYPE MOUNTPOINTS
sr0     11:0    1  370K  0 rom  
vda    254:0    0  100G  0 disk 
├─vda1 254:1    0  953M  0 part 
└─vda2 254:2    0 99.1G  0 part /
vdb    254:16   0  2.9M  1 disk 
vdc    254:32   0  3.1M  1 disk 
vdd    254:48   0  9.3G  0 disk 
vde    254:64   0 46.6G  0 disk 
vdf    254:80   0   10K  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-09-06 05:25 ./
-rw-r--r-- _rad/_rad      1396 2025-09-06 05:25 ./plan.yaml
-rwxr-xr-x _rad/_rad   3011224 2025-09-06 05:25 ./run-ci
INFO: Extracted files:
  4587530      4 drwxr-xr-x   2 1001     1001         4096 Sep  6 05:25 .
  4587526      4 -rw-r--r--   1 1001     1001         1396 Sep  6 05:25 ./plan.yaml
  4587527   2944 -rwxr-xr-x   1 1001     1001      3011224 Sep  6 05:25 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-09-06T05:25:23Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-09-06T05:25:23Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-09-06T05:25:23Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-09-06T05:25:23Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-09-06T05:25:23Z ERROR ambient_execute_plan] ambient-execute-plan starts
RunnablePlan::from_file: filename=plan.yaml
steps:
- action: mkdir
  pathname: /workspace
- action: mkdir
  pathname: /workspace/artifacts
- action: tar_extract
  archive: /dev/vdc
  directory: /workspace/src
- action: tar_extract
  archive: /dev/vdf
  directory: /workspace/deps
- action: tar_extract
  archive: /dev/vde
  directory: /workspace/cache
- action: spawn
  argv:
  - find
  - /workspace
  - '-maxdepth'
  - '2'
  - '-ls'
- action: shell
  shell: |
    ./format.sh
    cp vmdb2.html /workspace/artifacts/index.html
- action: shell
  shell: |
    export DEBEMAIL=liw@liw.fi
    export DEBFULLNAME="Lars Wirzenius"
    export HOME=/root
    export PATH="/root/.cargo/bin:/bin:/sbin"
    export SOPASS_LOG=trace
    export CARGO_TARGET_DIR=/workspace/cache
    export CARGO_HOME=/workspace/deps

    ./check

    git clean -fdx

    V="$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')"
    T="$(date -u "+%Y%m%dT%H%M%S")"
    version="$V.ci$T-1"
    dch -v "$version" "CI build under Ambient."
    dch -r ''
- action: deb
- action: tar_create
  archive: /dev/vde
  directory: /workspace/cache
- action: tar_create
  archive: /dev/vdd
  directory: /workspace/artifacts
executor_drive: /dev/vdb
source_drive: /dev/vdc
artifact_drive: /dev/vdd
cache_drive: /dev/vde
deps_drive: /dev/vdf
workspace_dir: /workspace
source_dir: /workspace/src
deps_dir: /workspace/deps
cache_dir: /workspace/cache
artifacts_dir: /workspace/artifacts


RUN: Action Mkdir {
    pathname: "/workspace",
}
[2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace/artifacts",
    }
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdc",
    directory: "/workspace/src",
}
[2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
  2621441      4 drwxr-xr-x   6 root     root         4096 Sep  6 05:25 /workspace
  2622078      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/deps
  2621443      4 drwxr-xr-x   7 root     root         4096 Sep  6 05:25 /workspace/src
  2621456      4 -rwxr-xr-x   1 root     root         1393 Sep  6 05:25 /workspace/src/check
  2621465      4 -rw-r--r--   1 root     root         1271 Sep  6 05:25 /workspace/src/lvm2.vmdb
  2622070      4 -rw-r--r--   1 root     root            8 Sep  6 05:25 /workspace/src/lvm2.lukskey
  2621482      4 drwxr-xr-x   8 root     root         4096 Sep  6 05:25 /workspace/src/.git
  2621459      4 -rwxr-xr-x   1 root     root         1174 Sep  6 05:25 /workspace/src/setup.py
  2622077      4 -rw-r--r--   1 root     root         1264 Sep  6 05:25 /workspace/src/smoke-ppc64el.vmdb
  2621481      4 -rw-r--r--   1 root     root         1057 Sep  6 05:25 /workspace/src/without-tests
  2621464      4 -rw-r--r--   1 root     root          235 Sep  6 05:25 /workspace/src/vmdb2.subplot
  2621479      8 -rw-r--r--   1 root     root         7962 Sep  6 05:25 /workspace/src/README.md
  2621448      8 -rwxr-xr-x   1 root     root         5713 Sep  6 05:25 /workspace/src/check-one
  2621445      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/src/.radicle
  2621449      4 drwxr-xr-x   4 root     root         4096 Sep  6 05:25 /workspace/src/roles
  2621467      4 drwxr-xr-x   3 root     root         4096 Sep  6 05:25 /workspace/src/debian
  2622074      4 -rw-r--r--   1 root     root         2055 Sep  6 05:25 /workspace/src/vmdb2.1
  2621480      4 -rw-r--r--   1 root     root           12 Sep  6 05:25 /workspace/src/.gitignore
  2622073      4 -rw-r--r--   1 root     root          504 Sep  6 05:25 /workspace/src/vmdb2.css
  2621457      4 -rw-r--r--   1 root     root          162 Sep  6 05:25 /workspace/src/vmdb2.py
  2621461      4 -rw-r--r--   1 root     root         1976 Sep  6 05:25 /workspace/src/smoke-ppc64el.yarn
  2621997     12 -rw-r--r--   1 root     root        10708 Sep  6 05:25 /workspace/src/vmdb2.md
  2621466      4 -rwxr-xr-x   1 root     root          101 Sep  6 05:25 /workspace/src/smoke-ppc64el.sh
  2622075      4 -rwxr-xr-x   1 root     root         1083 Sep  6 05:25 /workspace/src/check-images
  2622071      4 -rw-r--r--   1 root     root          211 Sep  6 05:25 /workspace/src/vmdb2.yaml
  2621444      4 -rwxr-xr-x   1 root     root           76 Sep  6 05:25 /workspace/src/vmdb2
  2621463      4 -rwxr-xr-x   1 root     root          308 Sep  6 05:25 /workspace/src/check-all
  2622069      4 -rw-r--r--   1 root     root          143 Sep  6 05:25 /workspace/src/ansible.yml
  2621460     24 -rw-r--r--   1 root     root        23398 Sep  6 05:25 /workspace/src/NEWS
  2622076      4 -rw-r--r--   1 root     root         3218 Sep  6 05:25 /workspace/src/code-of-conduct.md
  2621998      4 drwxr-xr-x   3 root     root         4096 Sep  6 05:25 /workspace/src/vmdb
  2622072      4 -rwxr-xr-x   1 root     root          304 Sep  6 05:25 /workspace/src/format.sh
  2621458      4 -rw-r--r--   1 root     root         1153 Sep  6 05:25 /workspace/src/base.vmdb
  2621447      4 -rw-r--r--   1 root     root          985 Sep  6 05:25 /workspace/src/ppc64el.vmdb
  2621462      4 -rwxr-xr-x   1 root     root          516 Sep  6 05:25 /workspace/src/check-external
  2621442      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/artifacts
  2622079      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/cache
RUN: Action finished OK
RUN: Action Shell {
    shell: "./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n",
}
[2025-09-06T05:25:24Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\n./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ ./format.sh
+ cp vmdb2.html /workspace/artifacts/index.html
RUN: Action finished OK
RUN: Action Shell {
    shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
}
[2025-09-06T05:25:24Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
+ export HOME=/root
+ HOME=/root
+ export PATH=/root/.cargo/bin:/bin:/sbin
+ PATH=/root/.cargo/bin:/bin:/sbin
+ export SOPASS_LOG=trace
+ SOPASS_LOG=trace
+ export CARGO_TARGET_DIR=/workspace/cache
+ CARGO_TARGET_DIR=/workspace/cache
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
+ ./check
Running unit tests ============================================
/usr/lib/python3/dist-packages/CoverageTestRunner.py:22: DeprecationWarning: the imp module is deprecated in favour of importlib and slated for removal in Python 3.12; see the module's documentation for alternative uses
  import imp
Running test 1/52: test_adds_builder_mount_point (tags_tests.TagsTest                                                                     Running test 2/52: test_builder_mount_point_can_be_made_cached (tags_                                                                     Running test 3/52: test_builder_mount_point_is_uncached_by_default (t                                                                     Running test 4/52: test_get_dev_raises_error_for_unknown_tag (tags_te                                                                     Running test 5/52: test_getting_builder_mount_point_raises_error_for_                                                                     Running test 6/52: test_lists_no_tags_initially (tags_tests.TagsTests                                                                     Running test 7/52: test_raises_error_for_reused_tag (tags_tests.TagsT                                                                     Running test 8/52: test_raises_error_if_both_mount_points_not_set (ta                                                                     Running test 9/52: test_remembers_order (tags_tests.TagsTests.test_re                                                                     Running test 10/52: test_returns_builder_when_given_target_mount_point                                                                      Running test 11/52: test_set_builder_mount_point_raises_error_for_doub                                                                      Running test 12/52: test_set_builder_mount_point_raises_error_for_unkn                                                                      Running test 13/52: test_set_dev_raises_error_for_double_dev (tags_tes                                                                      Running test 14/52: test_set_dev_raises_error_for_unknown_tag (tags_te                                                                      Running test 15/52: test_set_dm (tags_tests.TagsTests.test_set_dm)                                                                  Running test 16/52: test_set_dm_raises_error_for_double_fstype (tags_t                                                                      Running test 17/52: test_set_fstype (tags_tests.TagsTests.test_set_fst                                                                      Running test 18/52: test_set_fstype_raises_error_for_double_fstype (ta                                                                      Running test 19/52: test_set_fsuuid (tags_tests.TagsTests.test_set_fsu                                                                      Running test 20/52: test_set_fsuuid_raises_error_for_double_fstype (ta                                                                      Running test 21/52: test_set_luksuuid (tags_tests.TagsTests.test_set_l                                                                      Running test 22/52: test_set_luksuuid_raises_error_for_double_fstype (                                                                      Running test 23/52: test_set_target_mount_point (tags_tests.TagsTests.                                                                      Running test 24/52: test_set_target_mount_point_raises_error_for_doubl                                                                      Running test 25/52: test_sets_dev (tags_tests.TagsTests.test_sets_dev)                                                                      Running test 26/52: test_tells_if_tag_exists (tags_tests.TagsTests.tes                                                                      Running test 27/52: test_expands_dict_of_templates (spec_tests.ExpandT                                                                      Running test 28/52: test_expands_list_of_templates (spec_tests.ExpandT                                                                      Running test 29/52: test_expands_simple_string_template (spec_tests.Ex                                                                      Running test 30/52: test_raises_assert_if_given_incomprehensible_value                                                                      Running test 31/52: test_returns_same_given_string_without_template (s                                                                      Running test 32/52: test_expands_templates (spec_tests.SpecTests.test_                                                                      Running test 33/52: test_loads_spec (spec_tests.SpecTests.test_loads_s                                                                      Running test 34/52: tests_finds_no_plugin_when_file_has_none (plugin_t                                                                      Running test 35/52: tests_finds_no_plugins_in_empty_directory (plugin_                                                                      Running test 36/52: tests_finds_no_plugins_when_there_are_other_files                                                                       Running test 37/52: tests_finds_plugin_when_there_is_one (plugin_tests                                                                      Running test 38/52: test_sets_app (plugin_tests.PluginTests.test_sets_                                                                      Running test 39/52: test_raises_error_for_missing_mandatory_key (step_                                                                      Running test 40/52: test_raises_error_for_wrong_type_of_value_for_mand                                                                      Running test 41/52: test_raises_error_for_wrong_type_of_value_for_opti                                                                      Running test 42/52: test_returns_actual_value_for_optional_key (step_l                                                                      Running test 43/52: test_returns_default_value_for_missing_optional_ke                                                                      Running test 44/52: test_returns_values_from_step_for_mandatory_keys (                                                                      Running test 45/52: test_adds_a_runner (step_list_tests.StepRunnerList                                                                      Running test 46/52: test_finds_correct_runner (step_list_tests.StepRun                                                                      Running test 47/52: test_is_empty_initially (step_list_tests.StepRunne                                                                      Running test 48/52: test_raises_error_if_runner_not_found (step_list_t                                                                      Running test 49/52: test_raises_error_if_wrong_step_key_values (step_l                                                                      Running test 50/52: test_raises_error_if_not_mounted (unmount_tests.Un                                                                      Running test 51/52: test_unmounts_mounted_dir (unmount_tests.UnmountTe                                                                      Running test 52/52: test_unmounts_mounted_dir_with_submounts (unmount_

OK
54 excluded statements
33 excluded modules
Time: 0.4 s

Checking every plugin looks OK ============================================

Running Subplot ============================================
 INFO Starting Subplot
 INFO Subplot finished successfully
srcdir /workspace/src
datadir /tmp/tmpgrxxx2n_
scenario: Jinja2 templating in specification file values
  step: given an installed vmdb2
  step: given file j2.vmdb
  step: when I run vmdb2 -v j2.vmdb --output=foo.img
  step: then exit code is 0
  step: then stdout contains "image is foo.img\nbar"
scenario: A happy path
  step: given an installed vmdb2
  step: given file happy.vmdb
  step: when I run vmdb2 -v happy.vmdb --output=happy.img
  step: then exit code is 0
  step: then stdout contains "foo\nbar\nbar_teardown\n"
scenario: Error handling
  step: given an installed vmdb2
  step: given file unhappy.vmdb
  step: when I try to run vmdb2 -v unhappy.vmdb --output=unhappy.img
  step: then exit code is 1
  step: then stdout contains "foo\nyikes\n"
  step: then stdout contains "WAT?!\n"
  step: then stdout contains "foo_teardown\n"
  step: then stdout doesn't contain "bar_step"
  step: then stdout contains "bar_teardown"
scenario: Checking the version
  step: given an installed vmdb2
  step: when I run vmdb2 --version
  step: then exit code is 0
  step: then stdout matches regex ^\\d+\\.\\d+$
OK, all scenarios finished successfully

Formatting docs ========================================
+ git clean -fdx
Removing .coverage
Removing test.log
Removing test.py
Removing vmdb/__pycache__/
Removing vmdb/plugins/__pycache__/
Removing vmdb2.html
++ dpkg-parsechangelog -SVersion
++ sed 's/-[^-]*$//'
+ V=0.41
++ date -u +%Y%m%dT%H%M%S
+ T=20250906T052527
+ version=0.41.ci20250906T052527-1
+ dch -v 0.41.ci20250906T052527-1 'CI build under Ambient.'
+ dch -r ''
RUN: Action finished OK
RUN: Action Deb
[2025-09-06T05:25:31Z DEBUG ambient_ci::action] Plan::execute: Deb
SPAWN: argv=["/bin/bash", "-c", "#!/bin/bash\nset -xeuo pipefail\n\necho \"PATH at start: $PATH\"\nexport PATH=\"/root/.cargo/bin:$PATH\"\nexport CARGO_HOME=/workspace/deps\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n/bin/env\n\ncommand -v cargo\ncommand -v rustc\n\ncargo --version\nrustc --version\n\n# Get name and version of source package.\nname=\"$(dpkg-parsechangelog -SSource)\"\nversion=\"$(dpkg-parsechangelog -SVersion)\"\n\n# Get upstream version: everything before the last dash.\nuv=\"$(echo \"$version\" | sed 's/-[^-]*$//')\"\n\n# Files that will be created.\narch=\"$(dpkg --print-architecture)\"\norig=\"../${name}_${uv}.orig.tar.xz\"\ndeb=\"../${name}_${version}_${arch}.deb\"\nchanges=\"../${name}_${version}_${arch}.changes\"\n\n# Create \"upstream tarball\".\ngit archive HEAD | xz >\"$orig\"\n\n# Build package.\ndpkg-buildpackage -us -uc\n\n# Dump some information to make it easier to visually verify\n# everything looks OK. Also, test the package with the lintian tool.\n\nls -l ..\nfor x in ../*.deb; do dpkg -c \"$x\"; done\n# FIXME: disabled while this prevents radicle-native-ci deb from being built.\n# lintian -i --allow-root --fail-on warning ../*.changes\n\n# Move files to artifacts directory.\nmv ../*_* /workspace/artifacts\n        "]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
+ echo 'PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ export PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
+ /bin/env
DEBFULLNAME=Lars Wirzenius
CARGO_TARGET_DIR=/workspace/cache
PWD=/workspace/src
SYSTEMD_EXEC_PID=301
LANG=C.UTF-8
CARGO_HOME=/workspace/deps
DEBEMAIL=liw@liw.fi
INVOCATION_ID=fbe3848952dd4032918b50e9078813ca
SHLVL=2
JOURNAL_STREAM=8:13572
PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
OLDPWD=/
_=/bin/env
+ command -v cargo
/root/.cargo/bin/cargo
+ command -v rustc
/root/.cargo/bin/rustc
+ cargo --version
cargo 1.88.0 (873a06493 2025-05-10)
+ rustc --version
rustc 1.88.0 (6b00bc388 2025-06-23)
++ dpkg-parsechangelog -SSource
+ name=vmdb2
++ dpkg-parsechangelog -SVersion
+ version=0.41.ci20250906T052527-1
++ echo 0.41.ci20250906T052527-1
++ sed 's/-[^-]*$//'
+ uv=0.41.ci20250906T052527
++ dpkg --print-architecture
+ arch=amd64
+ orig=../vmdb2_0.41.ci20250906T052527.orig.tar.xz
+ deb=../vmdb2_0.41.ci20250906T052527-1_amd64.deb
+ changes=../vmdb2_0.41.ci20250906T052527-1_amd64.changes
+ git archive HEAD
+ xz
+ dpkg-buildpackage -us -uc
dpkg-buildpackage: info: source package vmdb2
dpkg-buildpackage: info: source version 0.41.ci20250906T052527-1
dpkg-buildpackage: info: source distribution unstable
dpkg-buildpackage: info: source changed by Lars Wirzenius <liw@liw.fi>
dpkg-buildpackage: info: host architecture amd64
 dpkg-source --before-build .
 debian/rules clean
dh clean --with=python3 --buildsystem=pybuild
   dh_auto_clean -O--buildsystem=pybuild
I: pybuild base:240: python3.11 setup.py clean 
/workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
  from distutils.core import setup
running clean
removing '/workspace/src/.pybuild/cpython3_3.11_vmdb2/build' (and everything under it)
'build/bdist.linux-x86_64' does not exist -- can't clean it
'build/scripts-3.11' does not exist -- can't clean it
   dh_autoreconf_clean -O--buildsystem=pybuild
   dh_clean -O--buildsystem=pybuild
 dpkg-source -b .
dpkg-source: info: using source format '3.0 (quilt)'
dpkg-source: info: building vmdb2 using existing ./vmdb2_0.41.ci20250906T052527.orig.tar.xz
dpkg-source: info: building vmdb2 in vmdb2_0.41.ci20250906T052527-1.debian.tar.xz
dpkg-source: info: building vmdb2 in vmdb2_0.41.ci20250906T052527-1.dsc
 debian/rules build
dh build --with=python3 --buildsystem=pybuild
   dh_update_autotools_config -O--buildsystem=pybuild
   dh_autoreconf -O--buildsystem=pybuild
   dh_auto_configure -O--buildsystem=pybuild
I: pybuild base:240: python3.11 setup.py config 
/workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
  from distutils.core import setup
running config
   dh_auto_build -O--buildsystem=pybuild
I: pybuild base:240: /usr/bin/python3 setup.py build 
/workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
  from distutils.core import setup
running build
running build_py
creating /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/step_list.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/tags.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/tags_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/spec.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/state.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/spec_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/plugin_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/step_list_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/__init__.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/version.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/runcmd.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/unmount_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/app.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/unmount.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
copying vmdb/plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
creating /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/mkpart_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/create_file_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/create_dir_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/resize_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/lvcreate_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/copy_dir_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/grub_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/vgcreate_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/mklabel_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/chroot_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/ansible_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/cache_rootfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/copy_file_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/mkfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/mount_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/mkimg_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/unpack_rootfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/debootstrap_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/apt_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/shell_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/virtualfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/echo_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/lvscan_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/cryptsetup_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/set_part_flag_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/fstab_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/error_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
copying vmdb/plugins/kpartx_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
running build_scripts
creating build
creating build/scripts-3.11
copying and adjusting vmdb2 -> build/scripts-3.11
changing mode of build/scripts-3.11/vmdb2 from 644 to 755
   debian/rules override_dh_auto_test
make[1]: Entering directory '/workspace/src'
./check
Running unit tests ============================================
/usr/lib/python3/dist-packages/CoverageTestRunner.py:22: DeprecationWarning: the imp module is deprecated in favour of importlib and slated for removal in Python 3.12; see the module's documentation for alternative uses
  import imp
Running test 1/52: test_adds_builder_mount_point (tags_tests.TagsTest                                                                     Running test 2/52: test_builder_mount_point_can_be_made_cached (tags_                                                                     Running test 3/52: test_builder_mount_point_is_uncached_by_default (t                                                                     Running test 4/52: test_get_dev_raises_error_for_unknown_tag (tags_te                                                                     Running test 5/52: test_getting_builder_mount_point_raises_error_for_                                                                     Running test 6/52: test_lists_no_tags_initially (tags_tests.TagsTests                                                                     Running test 7/52: test_raises_error_for_reused_tag (tags_tests.TagsT                                                                     Running test 8/52: test_raises_error_if_both_mount_points_not_set (ta                                                                     Running test 9/52: test_remembers_order (tags_tests.TagsTests.test_re                                                                     Running test 10/52: test_returns_builder_when_given_target_mount_point                                                                      Running test 11/52: test_set_builder_mount_point_raises_error_for_doub                                                                      Running test 12/52: test_set_builder_mount_point_raises_error_for_unkn                                                                      Running test 13/52: test_set_dev_raises_error_for_double_dev (tags_tes                                                                      Running test 14/52: test_set_dev_raises_error_for_unknown_tag (tags_te                                                                      Running test 15/52: test_set_dm (tags_tests.TagsTests.test_set_dm)                                                                  Running test 16/52: test_set_dm_raises_error_for_double_fstype (tags_t                                                                      Running test 17/52: test_set_fstype (tags_tests.TagsTests.test_set_fst                                                                      Running test 18/52: test_set_fstype_raises_error_for_double_fstype (ta                                                                      Running test 19/52: test_set_fsuuid (tags_tests.TagsTests.test_set_fsu                                                                      Running test 20/52: test_set_fsuuid_raises_error_for_double_fstype (ta                                                                      Running test 21/52: test_set_luksuuid (tags_tests.TagsTests.test_set_l                                                                      Running test 22/52: test_set_luksuuid_raises_error_for_double_fstype (                                                                      Running test 23/52: test_set_target_mount_point (tags_tests.TagsTests.                                                                      Running test 24/52: test_set_target_mount_point_raises_error_for_doubl                                                                      Running test 25/52: test_sets_dev (tags_tests.TagsTests.test_sets_dev)                                                                      Running test 26/52: test_tells_if_tag_exists (tags_tests.TagsTests.tes                                                                      Running test 27/52: test_expands_dict_of_templates (spec_tests.ExpandT                                                                      Running test 28/52: test_expands_list_of_templates (spec_tests.ExpandT                                                                      Running test 29/52: test_expands_simple_string_template (spec_tests.Ex                                                                      Running test 30/52: test_raises_assert_if_given_incomprehensible_value                                                                      Running test 31/52: test_returns_same_given_string_without_template (s                                                                      Running test 32/52: test_expands_templates (spec_tests.SpecTests.test_                                                                      Running test 33/52: test_loads_spec (spec_tests.SpecTests.test_loads_s                                                                      Running test 34/52: tests_finds_no_plugin_when_file_has_none (plugin_t                                                                      Running test 35/52: tests_finds_no_plugins_in_empty_directory (plugin_                                                                      Running test 36/52: tests_finds_no_plugins_when_there_are_other_files                                                                       Running test 37/52: tests_finds_plugin_when_there_is_one (plugin_tests                                                                      Running test 38/52: test_sets_app (plugin_tests.PluginTests.test_sets_                                                                      Running test 39/52: test_raises_error_for_missing_mandatory_key (step_                                                                      Running test 40/52: test_raises_error_for_wrong_type_of_value_for_mand                                                                      Running test 41/52: test_raises_error_for_wrong_type_of_value_for_opti                                                                      Running test 42/52: test_returns_actual_value_for_optional_key (step_l                                                                      Running test 43/52: test_returns_default_value_for_missing_optional_ke                                                                      Running test 44/52: test_returns_values_from_step_for_mandatory_keys (                                                                      Running test 45/52: test_adds_a_runner (step_list_tests.StepRunnerList                                                                      Running test 46/52: test_finds_correct_runner (step_list_tests.StepRun                                                                      Running test 47/52: test_is_empty_initially (step_list_tests.StepRunne                                                                      Running test 48/52: test_raises_error_if_runner_not_found (step_list_t                                                                      Running test 49/52: test_raises_error_if_wrong_step_key_values (step_l                                                                      Running test 50/52: test_raises_error_if_not_mounted (unmount_tests.Un                                                                      Running test 51/52: test_unmounts_mounted_dir (unmount_tests.UnmountTe                                                                      Running test 52/52: test_unmounts_mounted_dir_with_submounts (unmount_

OK
54 excluded statements
33 excluded modules
Time: 0.3 s

Checking every plugin looks OK ============================================

Running Subplot ============================================
 INFO Starting Subplot
 INFO Subplot finished successfully
srcdir /workspace/src
datadir /tmp/tmpdu8iz0pm
scenario: Checking the version
  step: given an installed vmdb2
  step: when I run vmdb2 --version
  step: then exit code is 0
  step: then stdout matches regex ^\\d+\\.\\d+$
scenario: A happy path
  step: given an installed vmdb2
  step: given file happy.vmdb
  step: when I run vmdb2 -v happy.vmdb --output=happy.img
  step: then exit code is 0
  step: then stdout contains "foo\nbar\nbar_teardown\n"
scenario: Error handling
  step: given an installed vmdb2
  step: given file unhappy.vmdb
  step: when I try to run vmdb2 -v unhappy.vmdb --output=unhappy.img
  step: then exit code is 1
  step: then stdout contains "foo\nyikes\n"
  step: then stdout contains "WAT?!\n"
  step: then stdout contains "foo_teardown\n"
  step: then stdout doesn't contain "bar_step"
  step: then stdout contains "bar_teardown"
scenario: Jinja2 templating in specification file values
  step: given an installed vmdb2
  step: given file j2.vmdb
  step: when I run vmdb2 -v j2.vmdb --output=foo.img
  step: then exit code is 0
  step: then stdout contains "image is foo.img\nbar"
OK, all scenarios finished successfully

Formatting docs ========================================
make[1]: Leaving directory '/workspace/src'
   create-stamp debian/debhelper-build-stamp
 debian/rules binary
dh binary --with=python3 --buildsystem=pybuild
   dh_testroot -O--buildsystem=pybuild
   dh_prep -O--buildsystem=pybuild
   dh_auto_install --destdir=debian/vmdb2/ -O--buildsystem=pybuild
I: pybuild base:240: /usr/bin/python3 setup.py install --root /workspace/src/debian/vmdb2 
/workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
  from distutils.core import setup
running install
running build
running build_py
package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
running build_scripts
running install_lib
creating /workspace/src/debian/vmdb2/usr
creating /workspace/src/debian/vmdb2/usr/lib
creating /workspace/src/debian/vmdb2/usr/lib/python3.11
creating /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages
creating /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/step_list.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/tags.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/tags_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/spec.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/state.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/spec_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
creating /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mkpart_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/create_file_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/create_dir_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/resize_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/lvcreate_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/copy_dir_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/grub_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/vgcreate_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mklabel_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/chroot_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/ansible_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/cache_rootfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/copy_file_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mkfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mount_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mkimg_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/unpack_rootfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/debootstrap_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/apt_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/shell_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/virtualfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/echo_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/lvscan_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/cryptsetup_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/set_part_flag_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/fstab_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/error_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/kpartx_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugin_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/step_list_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/__init__.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/version.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/runcmd.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/unmount_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/app.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/unmount.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/step_list.py to step_list.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/tags.py to tags.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/tags_tests.py to tags_tests.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/spec.py to spec.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/state.py to state.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/spec_tests.py to spec_tests.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mkpart_plugin.py to mkpart_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/create_file_plugin.py to create_file_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/create_dir_plugin.py to create_dir_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/resize_plugin.py to resize_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/lvcreate_plugin.py to lvcreate_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/copy_dir_plugin.py to copy_dir_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/grub_plugin.py to grub_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/vgcreate_plugin.py to vgcreate_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mklabel_plugin.py to mklabel_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/chroot_plugin.py to chroot_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/ansible_plugin.py to ansible_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/cache_rootfs_plugin.py to cache_rootfs_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/copy_file_plugin.py to copy_file_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mkfs_plugin.py to mkfs_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mount_plugin.py to mount_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mkimg_plugin.py to mkimg_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/unpack_rootfs_plugin.py to unpack_rootfs_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/debootstrap_plugin.py to debootstrap_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/apt_plugin.py to apt_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/shell_plugin.py to shell_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/virtualfs_plugin.py to virtualfs_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/echo_plugin.py to echo_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/lvscan_plugin.py to lvscan_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/cryptsetup_plugin.py to cryptsetup_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/set_part_flag_plugin.py to set_part_flag_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/fstab_plugin.py to fstab_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/error_plugin.py to error_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/kpartx_plugin.py to kpartx_plugin.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugin_tests.py to plugin_tests.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/step_list_tests.py to step_list_tests.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/__init__.py to __init__.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/version.py to version.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/runcmd.py to runcmd.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/unmount_tests.py to unmount_tests.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/app.py to app.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/unmount.py to unmount.cpython-311.pyc
byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugin.py to plugin.cpython-311.pyc
running install_scripts
creating /workspace/src/debian/vmdb2/usr/bin
copying build/scripts-3.11/vmdb2 -> /workspace/src/debian/vmdb2/usr/bin
changing mode of /workspace/src/debian/vmdb2/usr/bin/vmdb2 to 755
running install_data
creating /workspace/src/debian/vmdb2/usr/share
creating /workspace/src/debian/vmdb2/usr/share/man
creating /workspace/src/debian/vmdb2/usr/share/man/man1
copying vmdb2.1 -> /workspace/src/debian/vmdb2/usr/share/man/man1
running install_egg_info
Writing /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb2-0.41.egg-info
   dh_installdocs -O--buildsystem=pybuild
   dh_installchangelogs -O--buildsystem=pybuild
   dh_installman -O--buildsystem=pybuild
   dh_systemd_enable -O--buildsystem=pybuild
   dh_python3 -O--buildsystem=pybuild
   dh_installinit -O--buildsystem=pybuild
   dh_systemd_start -O--buildsystem=pybuild
   dh_perl -O--buildsystem=pybuild
   dh_link -O--buildsystem=pybuild
   dh_strip_nondeterminism -O--buildsystem=pybuild
   dh_compress -O--buildsystem=pybuild
   dh_fixperms -O--buildsystem=pybuild
   dh_missing -O--buildsystem=pybuild
   dh_installdeb -O--buildsystem=pybuild
   dh_gencontrol -O--buildsystem=pybuild
   dh_md5sums -O--buildsystem=pybuild
   dh_builddeb -O--buildsystem=pybuild
dpkg-deb: building package 'vmdb2' in '../vmdb2_0.41.ci20250906T052527-1_all.deb'.
 dpkg-genbuildinfo -O../vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo
 dpkg-genchanges -O../vmdb2_0.41.ci20250906T052527-1_amd64.changes
dpkg-genchanges: info: including full source code in upload
 dpkg-source --after-build .
dpkg-buildpackage: info: full upload (original source is included)
+ ls -l ..
total 148
drwxr-xr-x 2 root root  4096 Sep  6 05:25 artifacts
drwxr-xr-x 2 root root  4096 Sep  6 05:25 cache
drwxr-xr-x 2 root root  4096 Sep  6 05:25 deps
drwxr-xr-x 9 root root  4096 Sep  6 05:25 src
-rw-r--r-- 1 root root 23668 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1.debian.tar.xz
-rw-r--r-- 1 root root   977 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1.dsc
-rw-r--r-- 1 root root 26852 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1_all.deb
-rw-r--r-- 1 root root  6108 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo
-rw-r--r-- 1 root root  1956 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1_amd64.changes
-rw-r--r-- 1 root root 64380 Sep  6 05:25 vmdb2_0.41.ci20250906T052527.orig.tar.xz
+ for x in ../*.deb
+ dpkg -c ../vmdb2_0.41.ci20250906T052527-1_all.deb
drwxr-xr-x root/root         0 2025-09-06 05:25 ./
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/bin/
-rwxr-xr-x root/root        76 2025-09-06 05:25 ./usr/bin/vmdb2
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/dist-packages/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/
-rw-r--r-- root/root      1488 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/__init__.py
-rw-r--r-- root/root      8321 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/app.py
-rw-r--r-- root/root      1687 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugin.py
-rw-r--r-- root/root      1922 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugin_tests.py
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/
-rw-r--r-- root/root      3286 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/ansible_plugin.py
-rw-r--r-- root/root      2578 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/apt_plugin.py
-rw-r--r-- root/root      2419 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/cache_rootfs_plugin.py
-rw-r--r-- root/root      1186 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/chroot_plugin.py
-rw-r--r-- root/root      3984 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/copy_dir_plugin.py
-rw-r--r-- root/root      1795 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/copy_file_plugin.py
-rw-r--r-- root/root      1463 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/create_dir_plugin.py
-rw-r--r-- root/root      1612 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/create_file_plugin.py
-rw-r--r-- root/root      4805 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/cryptsetup_plugin.py
-rw-r--r-- root/root      4942 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/debootstrap_plugin.py
-rw-r--r-- root/root      1256 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/echo_plugin.py
-rw-r--r-- root/root      1438 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/error_plugin.py
-rw-r--r-- root/root      2920 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/fstab_plugin.py
-rw-r--r-- root/root     15713 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/grub_plugin.py
-rw-r--r-- root/root      3513 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/kpartx_plugin.py
-rw-r--r-- root/root      1520 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/lvcreate_plugin.py
-rw-r--r-- root/root      2336 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/lvscan_plugin.py
-rw-r--r-- root/root      3108 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mkfs_plugin.py
-rw-r--r-- root/root      1395 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mkimg_plugin.py
-rw-r--r-- root/root      1139 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mklabel_plugin.py
-rw-r--r-- root/root      4654 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mkpart_plugin.py
-rw-r--r-- root/root      4046 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mount_plugin.py
-rw-r--r-- root/root      3295 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/resize_plugin.py
-rw-r--r-- root/root      1915 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/set_part_flag_plugin.py
-rw-r--r-- root/root      1210 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/shell_plugin.py
-rw-r--r-- root/root      1582 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/unpack_rootfs_plugin.py
-rw-r--r-- root/root      1539 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/vgcreate_plugin.py
-rw-r--r-- root/root      2546 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/virtualfs_plugin.py
-rw-r--r-- root/root      3201 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/runcmd.py
-rw-r--r-- root/root      1488 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/spec.py
-rw-r--r-- root/root      2687 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/spec_tests.py
-rw-r--r-- root/root       973 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/state.py
-rw-r--r-- root/root      4277 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/step_list.py
-rw-r--r-- root/root      3642 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/step_list_tests.py
-rw-r--r-- root/root      5356 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/tags.py
-rw-r--r-- root/root      6873 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/tags_tests.py
-rw-r--r-- root/root      2444 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/unmount.py
-rw-r--r-- root/root      2109 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/unmount_tests.py
-rw-r--r-- root/root        48 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/version.py
-rw-r--r-- root/root       241 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb2-0.41.egg-info
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/doc/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/doc/vmdb2/
-rw-r--r-- root/root       687 2025-09-06 05:25 ./usr/share/doc/vmdb2/changelog.Debian.gz
-rw-r--r-- root/root       979 2025-09-06 05:25 ./usr/share/doc/vmdb2/copyright
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/man/
drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/man/man1/
-rw-r--r-- root/root      1047 2025-09-06 05:25 ./usr/share/man/man1/vmdb2.1.gz
+ mv ../vmdb2_0.41.ci20250906T052527-1.debian.tar.xz ../vmdb2_0.41.ci20250906T052527-1.dsc ../vmdb2_0.41.ci20250906T052527-1_all.deb ../vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo ../vmdb2_0.41.ci20250906T052527-1_amd64.changes ../vmdb2_0.41.ci20250906T052527.orig.tar.xz /workspace/artifacts
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-09-06T05:25:38Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vde",
        ),
        root: Some(
            "/workspace/cache",
        ),
        size: None,
    }
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] create archive file /dev/vde
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vdd",
    directory: "/workspace/artifacts",
}
[2025-09-06T05:25:38Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vdd",
        ),
        root: Some(
            "/workspace/artifacts",
        ),
        size: None,
    }
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
[2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
RUN: Action finished OK
ambient-execute-plan ends
EXIT CODE: 0
[2025-09-06T05:25:42Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:z2kxCtBwDQMPcaf9vGTNH5nYkp9qk",
    "name": "vmdb2",
    "description": "Debian virtual machine image builder",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "pusher": {
    "id": "did:key:z6MkkYez6QAFWvmdudjrfMXySu1NTpSrraJotCJFwGgkmgx8",
    "alias": "ci0"
  },
  "before": "af6685a991008df668d5c7eb1c7df7a9efcabcec",
  "after": "af6685a991008df668d5c7eb1c7df7a9efcabcec",
  "branch": "main",
  "commits": [
    "af6685a991008df668d5c7eb1c7df7a9efcabcec"
  ]
}

Ambient stdout

[2025-09-06T05:25:15Z INFO  ambient] ambient starts
[2025-09-06T05:25:15Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-09-06T05:25:15Z DEBUG ambient] load files named with --config: /tmp/.tmpVQMyCF/ambient_extra_values.yaml
[2025-09-06T05:25:15Z DEBUG ambient] complete configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: Some(
            "ci@http.liw.fi:/srv/http/vmdb2-manual.liw.fi",
        ),
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: Some(
            "apt.liw.fi",
        ),
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-09-06T05:25:15Z DEBUG ambient] configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: Some(
            "ci@http.liw.fi:/srv/http/vmdb2-manual.liw.fi",
        ),
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: Some(
            "apt.liw.fi",
        ),
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-09-06T05:25:15Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/vmdb2/meta.yaml
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] no latest commit stored
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] is a git repository
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] git repository is clean
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] current (HEAD) commit: af6685a991008df668d5c7eb1c7df7a9efcabcec
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] no dry run requested
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] forced run requested
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] run? true
[2025-09-06T05:25:15Z INFO  ambient_ci::run] project vmdb2: running CI
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpQm9Lmv/src.tar
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpQm9Lmv/deps.tar
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] create_executor_vdrive: plan=RunnablePlan {
        steps: [
            Mkdir {
                pathname: "/workspace",
            },
            Mkdir {
                pathname: "/workspace/artifacts",
            },
            TarExtract {
                archive: "/dev/vdc",
                directory: "/workspace/src",
            },
            TarExtract {
                archive: "/dev/vdf",
                directory: "/workspace/deps",
            },
            TarExtract {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
            Spawn {
                argv: [
                    "find",
                    "/workspace",
                    "-maxdepth",
                    "2",
                    "-ls",
                ],
            },
            Shell {
                shell: "./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n",
            },
            Shell {
                shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
            },
            Deb,
            TarCreate {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
            TarCreate {
                archive: "/dev/vdd",
                directory: "/workspace/artifacts",
            },
        ],
        executor_drive: Some(
            "/dev/vdb",
        ),
        source_drive: Some(
            "/dev/vdc",
        ),
        artifact_drive: Some(
            "/dev/vdd",
        ),
        cache_drive: Some(
            "/dev/vde",
        ),
        deps_drive: Some(
            "/dev/vdf",
        ),
        workspace_dir: Some(
            "/workspace",
        ),
        source_dir: Some(
            "/workspace/src",
        ),
        deps_dir: Some(
            "/workspace/deps",
        ),
        cache_dir: Some(
            "/workspace/cache",
        ),
        artifacts_dir: Some(
            "/workspace/artifacts",
        ),
    }
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpQm9Lmv/ambient-execute-plan/run-ci
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] copy OK: true
[2025-09-06T05:25:15Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpQm9Lmv/executor.tar
[2025-09-06T05:25:15Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/vmdb2/run.log
[2025-09-06T05:25:15Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/vmdb2, exists? true
[2025-09-06T05:25:15Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/vmdb2/run.log
[2025-09-06T05:25:15Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/vmdb2/run.log OK
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] with_runcmd called: "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n"
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] LocalDataStoreBuilder: LocalDataStoreBuilder {
        hostname: Some(
            "ambient",
        ),
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu] run QEMU
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] LocalDataStore: LocalDataStore {
        hostname: "ambient",
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpcSaqB6/meta-data
[2025-09-06T05:25:15Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpcSaqB6/user-data
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/console.log
[2025-09-06T05:25:15Z DEBUG ambient_ci::util] create file /srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/console.log
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/vmdb2/run.log
[2025-09-06T05:25:15Z DEBUG ambient_ci::util] create file /srv/ambient-state/vmdb2/run.log
[2025-09-06T05:25:15Z DEBUG ambient_ci::qemu] run QEMU: QemuArgs {
        args: [
            "-m",
            "7629",
            "-smp",
            "cpus=4",
            "-cpu",
            "kvm64",
            "-machine",
            "type=q35,accel=kvm,usb=off",
            "-uuid",
            "a85c9de7-edc0-4e54-bead-112e5733582c",
            "-boot",
            "strict=on",
            "-name",
            "ambient-ci-vm",
            "-rtc",
            "base=utc,driftfix=slew",
            "-display",
            "none",
            "-device",
            "virtio-rng-pci",
            "-serial",
            "file:/srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/console.log",
            "-serial",
            "file:/srv/ambient-state/vmdb2/run.log",
            "-drive",
            "if=pflash,format=raw,unit=0,file=/usr/share/ovmf/OVMF.fd,readonly=on",
            "-drive",
            "if=pflash,format=raw,unit=1,file=/srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpQm9Lmv/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmp6JXP0P/.tmpW3Hx8d/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpQm9Lmv/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpQm9Lmv/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpQm9Lmv/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmpQm9Lmv/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-09-06T05:25:41Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-09-06T05:25:41Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-09-06T05:25:23Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-09-06T05:25:23Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-09-06T05:25:23Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-09-06T05:25:23Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-09-06T05:25:23Z ERROR ambient_execute_plan] ambient-execute-plan starts
    RunnablePlan::from_file: filename=plan.yaml
    steps:
    - action: mkdir
      pathname: /workspace
    - action: mkdir
      pathname: /workspace/artifacts
    - action: tar_extract
      archive: /dev/vdc
      directory: /workspace/src
    - action: tar_extract
      archive: /dev/vdf
      directory: /workspace/deps
    - action: tar_extract
      archive: /dev/vde
      directory: /workspace/cache
    - action: spawn
      argv:
      - find
      - /workspace
      - '-maxdepth'
      - '2'
      - '-ls'
    - action: shell
      shell: |
        ./format.sh
        cp vmdb2.html /workspace/artifacts/index.html
    - action: shell
      shell: |
        export DEBEMAIL=liw@liw.fi
        export DEBFULLNAME="Lars Wirzenius"
        export HOME=/root
        export PATH="/root/.cargo/bin:/bin:/sbin"
        export SOPASS_LOG=trace
        export CARGO_TARGET_DIR=/workspace/cache
        export CARGO_HOME=/workspace/deps
    
        ./check
    
        git clean -fdx
    
        V="$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')"
        T="$(date -u "+%Y%m%dT%H%M%S")"
        version="$V.ci$T-1"
        dch -v "$version" "CI build under Ambient."
        dch -r ''
    - action: deb
    - action: tar_create
      archive: /dev/vde
      directory: /workspace/cache
    - action: tar_create
      archive: /dev/vdd
      directory: /workspace/artifacts
    executor_drive: /dev/vdb
    source_drive: /dev/vdc
    artifact_drive: /dev/vdd
    cache_drive: /dev/vde
    deps_drive: /dev/vdf
    workspace_dir: /workspace
    source_dir: /workspace/src
    deps_dir: /workspace/deps
    cache_dir: /workspace/cache
    artifacts_dir: /workspace/artifacts
    
    
    RUN: Action Mkdir {
        pathname: "/workspace",
    }
    [2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace/artifacts",
        }
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
    [2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-09-06T05:25:23Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-09-06T05:25:23Z DEBUG ambient_ci::action] Plan::execute: Spawn {
            argv: [
                "find",
                "/workspace",
                "-maxdepth",
                "2",
                "-ls",
            ],
        }
    SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
      2621441      4 drwxr-xr-x   6 root     root         4096 Sep  6 05:25 /workspace
      2622078      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/deps
      2621443      4 drwxr-xr-x   7 root     root         4096 Sep  6 05:25 /workspace/src
      2621456      4 -rwxr-xr-x   1 root     root         1393 Sep  6 05:25 /workspace/src/check
      2621465      4 -rw-r--r--   1 root     root         1271 Sep  6 05:25 /workspace/src/lvm2.vmdb
      2622070      4 -rw-r--r--   1 root     root            8 Sep  6 05:25 /workspace/src/lvm2.lukskey
      2621482      4 drwxr-xr-x   8 root     root         4096 Sep  6 05:25 /workspace/src/.git
      2621459      4 -rwxr-xr-x   1 root     root         1174 Sep  6 05:25 /workspace/src/setup.py
      2622077      4 -rw-r--r--   1 root     root         1264 Sep  6 05:25 /workspace/src/smoke-ppc64el.vmdb
      2621481      4 -rw-r--r--   1 root     root         1057 Sep  6 05:25 /workspace/src/without-tests
      2621464      4 -rw-r--r--   1 root     root          235 Sep  6 05:25 /workspace/src/vmdb2.subplot
      2621479      8 -rw-r--r--   1 root     root         7962 Sep  6 05:25 /workspace/src/README.md
      2621448      8 -rwxr-xr-x   1 root     root         5713 Sep  6 05:25 /workspace/src/check-one
      2621445      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/src/.radicle
      2621449      4 drwxr-xr-x   4 root     root         4096 Sep  6 05:25 /workspace/src/roles
      2621467      4 drwxr-xr-x   3 root     root         4096 Sep  6 05:25 /workspace/src/debian
      2622074      4 -rw-r--r--   1 root     root         2055 Sep  6 05:25 /workspace/src/vmdb2.1
      2621480      4 -rw-r--r--   1 root     root           12 Sep  6 05:25 /workspace/src/.gitignore
      2622073      4 -rw-r--r--   1 root     root          504 Sep  6 05:25 /workspace/src/vmdb2.css
      2621457      4 -rw-r--r--   1 root     root          162 Sep  6 05:25 /workspace/src/vmdb2.py
      2621461      4 -rw-r--r--   1 root     root         1976 Sep  6 05:25 /workspace/src/smoke-ppc64el.yarn
      2621997     12 -rw-r--r--   1 root     root        10708 Sep  6 05:25 /workspace/src/vmdb2.md
      2621466      4 -rwxr-xr-x   1 root     root          101 Sep  6 05:25 /workspace/src/smoke-ppc64el.sh
      2622075      4 -rwxr-xr-x   1 root     root         1083 Sep  6 05:25 /workspace/src/check-images
      2622071      4 -rw-r--r--   1 root     root          211 Sep  6 05:25 /workspace/src/vmdb2.yaml
      2621444      4 -rwxr-xr-x   1 root     root           76 Sep  6 05:25 /workspace/src/vmdb2
      2621463      4 -rwxr-xr-x   1 root     root          308 Sep  6 05:25 /workspace/src/check-all
      2622069      4 -rw-r--r--   1 root     root          143 Sep  6 05:25 /workspace/src/ansible.yml
      2621460     24 -rw-r--r--   1 root     root        23398 Sep  6 05:25 /workspace/src/NEWS
      2622076      4 -rw-r--r--   1 root     root         3218 Sep  6 05:25 /workspace/src/code-of-conduct.md
      2621998      4 drwxr-xr-x   3 root     root         4096 Sep  6 05:25 /workspace/src/vmdb
      2622072      4 -rwxr-xr-x   1 root     root          304 Sep  6 05:25 /workspace/src/format.sh
      2621458      4 -rw-r--r--   1 root     root         1153 Sep  6 05:25 /workspace/src/base.vmdb
      2621447      4 -rw-r--r--   1 root     root          985 Sep  6 05:25 /workspace/src/ppc64el.vmdb
      2621462      4 -rwxr-xr-x   1 root     root          516 Sep  6 05:25 /workspace/src/check-external
      2621442      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/artifacts
      2622079      4 drwxr-xr-x   2 root     root         4096 Sep  6 05:25 /workspace/cache
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n",
    }
    [2025-09-06T05:25:24Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\n./format.sh\ncp vmdb2.html /workspace/artifacts/index.html\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + ./format.sh
    + cp vmdb2.html /workspace/artifacts/index.html
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
    }
    [2025-09-06T05:25:24Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\n./check\n\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    + export HOME=/root
    + HOME=/root
    + export PATH=/root/.cargo/bin:/bin:/sbin
    + PATH=/root/.cargo/bin:/bin:/sbin
    + export SOPASS_LOG=trace
    + SOPASS_LOG=trace
    + export CARGO_TARGET_DIR=/workspace/cache
    + CARGO_TARGET_DIR=/workspace/cache
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    + ./check
    Running unit tests ============================================
    /usr/lib/python3/dist-packages/CoverageTestRunner.py:22: DeprecationWarning: the imp module is deprecated in favour of importlib and slated for removal in Python 3.12; see the module's documentation for alternative uses
      import imp
    Running test 1/52: test_adds_builder_mount_point (tags_tests.TagsTest                                                                     Running test 2/52: test_builder_mount_point_can_be_made_cached (tags_                                                                     Running test 3/52: test_builder_mount_point_is_uncached_by_default (t                                                                     Running test 4/52: test_get_dev_raises_error_for_unknown_tag (tags_te                                                                     Running test 5/52: test_getting_builder_mount_point_raises_error_for_                                                                     Running test 6/52: test_lists_no_tags_initially (tags_tests.TagsTests                                                                     Running test 7/52: test_raises_error_for_reused_tag (tags_tests.TagsT                                                                     Running test 8/52: test_raises_error_if_both_mount_points_not_set (ta                                                                     Running test 9/52: test_remembers_order (tags_tests.TagsTests.test_re                                                                     Running test 10/52: test_returns_builder_when_given_target_mount_point                                                                      Running test 11/52: test_set_builder_mount_point_raises_error_for_doub                                                                      Running test 12/52: test_set_builder_mount_point_raises_error_for_unkn                                                                      Running test 13/52: test_set_dev_raises_error_for_double_dev (tags_tes                                                                      Running test 14/52: test_set_dev_raises_error_for_unknown_tag (tags_te                                                                      Running test 15/52: test_set_dm (tags_tests.TagsTests.test_set_dm)                                                                  Running test 16/52: test_set_dm_raises_error_for_double_fstype (tags_t                                                                      Running test 17/52: test_set_fstype (tags_tests.TagsTests.test_set_fst                                                                      Running test 18/52: test_set_fstype_raises_error_for_double_fstype (ta                                                                      Running test 19/52: test_set_fsuuid (tags_tests.TagsTests.test_set_fsu                                                                      Running test 20/52: test_set_fsuuid_raises_error_for_double_fstype (ta                                                                      Running test 21/52: test_set_luksuuid (tags_tests.TagsTests.test_set_l                                                                      Running test 22/52: test_set_luksuuid_raises_error_for_double_fstype (                                                                      Running test 23/52: test_set_target_mount_point (tags_tests.TagsTests.                                                                      Running test 24/52: test_set_target_mount_point_raises_error_for_doubl                                                                      Running test 25/52: test_sets_dev (tags_tests.TagsTests.test_sets_dev)                                                                      Running test 26/52: test_tells_if_tag_exists (tags_tests.TagsTests.tes                                                                      Running test 27/52: test_expands_dict_of_templates (spec_tests.ExpandT                                                                      Running test 28/52: test_expands_list_of_templates (spec_tests.ExpandT                                                                      Running test 29/52: test_expands_simple_string_template (spec_tests.Ex                                                                      Running test 30/52: test_raises_assert_if_given_incomprehensible_value                                                                      Running test 31/52: test_returns_same_given_string_without_template (s                                                                      Running test 32/52: test_expands_templates (spec_tests.SpecTests.test_                                                                      Running test 33/52: test_loads_spec (spec_tests.SpecTests.test_loads_s                                                                      Running test 34/52: tests_finds_no_plugin_when_file_has_none (plugin_t                                                                      Running test 35/52: tests_finds_no_plugins_in_empty_directory (plugin_                                                                      Running test 36/52: tests_finds_no_plugins_when_there_are_other_files                                                                       Running test 37/52: tests_finds_plugin_when_there_is_one (plugin_tests                                                                      Running test 38/52: test_sets_app (plugin_tests.PluginTests.test_sets_                                                                      Running test 39/52: test_raises_error_for_missing_mandatory_key (step_                                                                      Running test 40/52: test_raises_error_for_wrong_type_of_value_for_mand                                                                      Running test 41/52: test_raises_error_for_wrong_type_of_value_for_opti                                                                      Running test 42/52: test_returns_actual_value_for_optional_key (step_l                                                                      Running test 43/52: test_returns_default_value_for_missing_optional_ke                                                                      Running test 44/52: test_returns_values_from_step_for_mandatory_keys (                                                                      Running test 45/52: test_adds_a_runner (step_list_tests.StepRunnerList                                                                      Running test 46/52: test_finds_correct_runner (step_list_tests.StepRun                                                                      Running test 47/52: test_is_empty_initially (step_list_tests.StepRunne                                                                      Running test 48/52: test_raises_error_if_runner_not_found (step_list_t                                                                      Running test 49/52: test_raises_error_if_wrong_step_key_values (step_l                                                                      Running test 50/52: test_raises_error_if_not_mounted (unmount_tests.Un                                                                      Running test 51/52: test_unmounts_mounted_dir (unmount_tests.UnmountTe                                                                      Running test 52/52: test_unmounts_mounted_dir_with_submounts (unmount_
    
    OK
    54 excluded statements
    33 excluded modules
    Time: 0.4 s
    
    Checking every plugin looks OK ============================================
    
    Running Subplot ============================================
     INFO Starting Subplot
     INFO Subplot finished successfully
    srcdir /workspace/src
    datadir /tmp/tmpgrxxx2n_
    scenario: Jinja2 templating in specification file values
      step: given an installed vmdb2
      step: given file j2.vmdb
      step: when I run vmdb2 -v j2.vmdb --output=foo.img
      step: then exit code is 0
      step: then stdout contains "image is foo.img\nbar"
    scenario: A happy path
      step: given an installed vmdb2
      step: given file happy.vmdb
      step: when I run vmdb2 -v happy.vmdb --output=happy.img
      step: then exit code is 0
      step: then stdout contains "foo\nbar\nbar_teardown\n"
    scenario: Error handling
      step: given an installed vmdb2
      step: given file unhappy.vmdb
      step: when I try to run vmdb2 -v unhappy.vmdb --output=unhappy.img
      step: then exit code is 1
      step: then stdout contains "foo\nyikes\n"
      step: then stdout contains "WAT?!\n"
      step: then stdout contains "foo_teardown\n"
      step: then stdout doesn't contain "bar_step"
      step: then stdout contains "bar_teardown"
    scenario: Checking the version
      step: given an installed vmdb2
      step: when I run vmdb2 --version
      step: then exit code is 0
      step: then stdout matches regex ^\\d+\\.\\d+$
    OK, all scenarios finished successfully
    
    Formatting docs ========================================
    + git clean -fdx
    Removing .coverage
    Removing test.log
    Removing test.py
    Removing vmdb/__pycache__/
    Removing vmdb/plugins/__pycache__/
    Removing vmdb2.html
    ++ dpkg-parsechangelog -SVersion
    ++ sed 's/-[^-]*$//'
    + V=0.41
    ++ date -u +%Y%m%dT%H%M%S
    + T=20250906T052527
    + version=0.41.ci20250906T052527-1
    + dch -v 0.41.ci20250906T052527-1 'CI build under Ambient.'
    + dch -r ''
    RUN: Action finished OK
    RUN: Action Deb
    [2025-09-06T05:25:31Z DEBUG ambient_ci::action] Plan::execute: Deb
    SPAWN: argv=["/bin/bash", "-c", "#!/bin/bash\nset -xeuo pipefail\n\necho \"PATH at start: $PATH\"\nexport PATH=\"/root/.cargo/bin:$PATH\"\nexport CARGO_HOME=/workspace/deps\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n/bin/env\n\ncommand -v cargo\ncommand -v rustc\n\ncargo --version\nrustc --version\n\n# Get name and version of source package.\nname=\"$(dpkg-parsechangelog -SSource)\"\nversion=\"$(dpkg-parsechangelog -SVersion)\"\n\n# Get upstream version: everything before the last dash.\nuv=\"$(echo \"$version\" | sed 's/-[^-]*$//')\"\n\n# Files that will be created.\narch=\"$(dpkg --print-architecture)\"\norig=\"../${name}_${uv}.orig.tar.xz\"\ndeb=\"../${name}_${version}_${arch}.deb\"\nchanges=\"../${name}_${version}_${arch}.changes\"\n\n# Create \"upstream tarball\".\ngit archive HEAD | xz >\"$orig\"\n\n# Build package.\ndpkg-buildpackage -us -uc\n\n# Dump some information to make it easier to visually verify\n# everything looks OK. Also, test the package with the lintian tool.\n\nls -l ..\nfor x in ../*.deb; do dpkg -c \"$x\"; done\n# FIXME: disabled while this prevents radicle-native-ci deb from being built.\n# lintian -i --allow-root --fail-on warning ../*.changes\n\n# Move files to artifacts directory.\nmv ../*_* /workspace/artifacts\n        "]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    + echo 'PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
    PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + export PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    + /bin/env
    DEBFULLNAME=Lars Wirzenius
    CARGO_TARGET_DIR=/workspace/cache
    PWD=/workspace/src
    SYSTEMD_EXEC_PID=301
    LANG=C.UTF-8
    CARGO_HOME=/workspace/deps
    DEBEMAIL=liw@liw.fi
    INVOCATION_ID=fbe3848952dd4032918b50e9078813ca
    SHLVL=2
    JOURNAL_STREAM=8:13572
    PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    OLDPWD=/
    _=/bin/env
    + command -v cargo
    /root/.cargo/bin/cargo
    + command -v rustc
    /root/.cargo/bin/rustc
    + cargo --version
    cargo 1.88.0 (873a06493 2025-05-10)
    + rustc --version
    rustc 1.88.0 (6b00bc388 2025-06-23)
    ++ dpkg-parsechangelog -SSource
    + name=vmdb2
    ++ dpkg-parsechangelog -SVersion
    + version=0.41.ci20250906T052527-1
    ++ echo 0.41.ci20250906T052527-1
    ++ sed 's/-[^-]*$//'
    + uv=0.41.ci20250906T052527
    ++ dpkg --print-architecture
    + arch=amd64
    + orig=../vmdb2_0.41.ci20250906T052527.orig.tar.xz
    + deb=../vmdb2_0.41.ci20250906T052527-1_amd64.deb
    + changes=../vmdb2_0.41.ci20250906T052527-1_amd64.changes
    + git archive HEAD
    + xz
    + dpkg-buildpackage -us -uc
    dpkg-buildpackage: info: source package vmdb2
    dpkg-buildpackage: info: source version 0.41.ci20250906T052527-1
    dpkg-buildpackage: info: source distribution unstable
    dpkg-buildpackage: info: source changed by Lars Wirzenius <liw@liw.fi>
    dpkg-buildpackage: info: host architecture amd64
     dpkg-source --before-build .
     debian/rules clean
    dh clean --with=python3 --buildsystem=pybuild
       dh_auto_clean -O--buildsystem=pybuild
    I: pybuild base:240: python3.11 setup.py clean 
    /workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
      from distutils.core import setup
    running clean
    removing '/workspace/src/.pybuild/cpython3_3.11_vmdb2/build' (and everything under it)
    'build/bdist.linux-x86_64' does not exist -- can't clean it
    'build/scripts-3.11' does not exist -- can't clean it
       dh_autoreconf_clean -O--buildsystem=pybuild
       dh_clean -O--buildsystem=pybuild
     dpkg-source -b .
    dpkg-source: info: using source format '3.0 (quilt)'
    dpkg-source: info: building vmdb2 using existing ./vmdb2_0.41.ci20250906T052527.orig.tar.xz
    dpkg-source: info: building vmdb2 in vmdb2_0.41.ci20250906T052527-1.debian.tar.xz
    dpkg-source: info: building vmdb2 in vmdb2_0.41.ci20250906T052527-1.dsc
     debian/rules build
    dh build --with=python3 --buildsystem=pybuild
       dh_update_autotools_config -O--buildsystem=pybuild
       dh_autoreconf -O--buildsystem=pybuild
       dh_auto_configure -O--buildsystem=pybuild
    I: pybuild base:240: python3.11 setup.py config 
    /workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
      from distutils.core import setup
    running config
       dh_auto_build -O--buildsystem=pybuild
    I: pybuild base:240: /usr/bin/python3 setup.py build 
    /workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
      from distutils.core import setup
    running build
    running build_py
    creating /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/step_list.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/tags.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/tags_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/spec.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/state.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/spec_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/plugin_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/step_list_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/__init__.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/version.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/runcmd.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/unmount_tests.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/app.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/unmount.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    copying vmdb/plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb
    package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
    creating /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/mkpart_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/create_file_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/create_dir_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/resize_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/lvcreate_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/copy_dir_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/grub_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/vgcreate_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/mklabel_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/chroot_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/ansible_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/cache_rootfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/copy_file_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/mkfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/mount_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/mkimg_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/unpack_rootfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/debootstrap_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/apt_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/shell_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/virtualfs_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/echo_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/lvscan_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/cryptsetup_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/set_part_flag_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/fstab_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/error_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    copying vmdb/plugins/kpartx_plugin.py -> /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins
    package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
    running build_scripts
    creating build
    creating build/scripts-3.11
    copying and adjusting vmdb2 -> build/scripts-3.11
    changing mode of build/scripts-3.11/vmdb2 from 644 to 755
       debian/rules override_dh_auto_test
    make[1]: Entering directory '/workspace/src'
    ./check
    Running unit tests ============================================
    /usr/lib/python3/dist-packages/CoverageTestRunner.py:22: DeprecationWarning: the imp module is deprecated in favour of importlib and slated for removal in Python 3.12; see the module's documentation for alternative uses
      import imp
    Running test 1/52: test_adds_builder_mount_point (tags_tests.TagsTest                                                                     Running test 2/52: test_builder_mount_point_can_be_made_cached (tags_                                                                     Running test 3/52: test_builder_mount_point_is_uncached_by_default (t                                                                     Running test 4/52: test_get_dev_raises_error_for_unknown_tag (tags_te                                                                     Running test 5/52: test_getting_builder_mount_point_raises_error_for_                                                                     Running test 6/52: test_lists_no_tags_initially (tags_tests.TagsTests                                                                     Running test 7/52: test_raises_error_for_reused_tag (tags_tests.TagsT                                                                     Running test 8/52: test_raises_error_if_both_mount_points_not_set (ta                                                                     Running test 9/52: test_remembers_order (tags_tests.TagsTests.test_re                                                                     Running test 10/52: test_returns_builder_when_given_target_mount_point                                                                      Running test 11/52: test_set_builder_mount_point_raises_error_for_doub                                                                      Running test 12/52: test_set_builder_mount_point_raises_error_for_unkn                                                                      Running test 13/52: test_set_dev_raises_error_for_double_dev (tags_tes                                                                      Running test 14/52: test_set_dev_raises_error_for_unknown_tag (tags_te                                                                      Running test 15/52: test_set_dm (tags_tests.TagsTests.test_set_dm)                                                                  Running test 16/52: test_set_dm_raises_error_for_double_fstype (tags_t                                                                      Running test 17/52: test_set_fstype (tags_tests.TagsTests.test_set_fst                                                                      Running test 18/52: test_set_fstype_raises_error_for_double_fstype (ta                                                                      Running test 19/52: test_set_fsuuid (tags_tests.TagsTests.test_set_fsu                                                                      Running test 20/52: test_set_fsuuid_raises_error_for_double_fstype (ta                                                                      Running test 21/52: test_set_luksuuid (tags_tests.TagsTests.test_set_l                                                                      Running test 22/52: test_set_luksuuid_raises_error_for_double_fstype (                                                                      Running test 23/52: test_set_target_mount_point (tags_tests.TagsTests.                                                                      Running test 24/52: test_set_target_mount_point_raises_error_for_doubl                                                                      Running test 25/52: test_sets_dev (tags_tests.TagsTests.test_sets_dev)                                                                      Running test 26/52: test_tells_if_tag_exists (tags_tests.TagsTests.tes                                                                      Running test 27/52: test_expands_dict_of_templates (spec_tests.ExpandT                                                                      Running test 28/52: test_expands_list_of_templates (spec_tests.ExpandT                                                                      Running test 29/52: test_expands_simple_string_template (spec_tests.Ex                                                                      Running test 30/52: test_raises_assert_if_given_incomprehensible_value                                                                      Running test 31/52: test_returns_same_given_string_without_template (s                                                                      Running test 32/52: test_expands_templates (spec_tests.SpecTests.test_                                                                      Running test 33/52: test_loads_spec (spec_tests.SpecTests.test_loads_s                                                                      Running test 34/52: tests_finds_no_plugin_when_file_has_none (plugin_t                                                                      Running test 35/52: tests_finds_no_plugins_in_empty_directory (plugin_                                                                      Running test 36/52: tests_finds_no_plugins_when_there_are_other_files                                                                       Running test 37/52: tests_finds_plugin_when_there_is_one (plugin_tests                                                                      Running test 38/52: test_sets_app (plugin_tests.PluginTests.test_sets_                                                                      Running test 39/52: test_raises_error_for_missing_mandatory_key (step_                                                                      Running test 40/52: test_raises_error_for_wrong_type_of_value_for_mand                                                                      Running test 41/52: test_raises_error_for_wrong_type_of_value_for_opti                                                                      Running test 42/52: test_returns_actual_value_for_optional_key (step_l                                                                      Running test 43/52: test_returns_default_value_for_missing_optional_ke                                                                      Running test 44/52: test_returns_values_from_step_for_mandatory_keys (                                                                      Running test 45/52: test_adds_a_runner (step_list_tests.StepRunnerList                                                                      Running test 46/52: test_finds_correct_runner (step_list_tests.StepRun                                                                      Running test 47/52: test_is_empty_initially (step_list_tests.StepRunne                                                                      Running test 48/52: test_raises_error_if_runner_not_found (step_list_t                                                                      Running test 49/52: test_raises_error_if_wrong_step_key_values (step_l                                                                      Running test 50/52: test_raises_error_if_not_mounted (unmount_tests.Un                                                                      Running test 51/52: test_unmounts_mounted_dir (unmount_tests.UnmountTe                                                                      Running test 52/52: test_unmounts_mounted_dir_with_submounts (unmount_
    
    OK
    54 excluded statements
    33 excluded modules
    Time: 0.3 s
    
    Checking every plugin looks OK ============================================
    
    Running Subplot ============================================
     INFO Starting Subplot
     INFO Subplot finished successfully
    srcdir /workspace/src
    datadir /tmp/tmpdu8iz0pm
    scenario: Checking the version
      step: given an installed vmdb2
      step: when I run vmdb2 --version
      step: then exit code is 0
      step: then stdout matches regex ^\\d+\\.\\d+$
    scenario: A happy path
      step: given an installed vmdb2
      step: given file happy.vmdb
      step: when I run vmdb2 -v happy.vmdb --output=happy.img
      step: then exit code is 0
      step: then stdout contains "foo\nbar\nbar_teardown\n"
    scenario: Error handling
      step: given an installed vmdb2
      step: given file unhappy.vmdb
      step: when I try to run vmdb2 -v unhappy.vmdb --output=unhappy.img
      step: then exit code is 1
      step: then stdout contains "foo\nyikes\n"
      step: then stdout contains "WAT?!\n"
      step: then stdout contains "foo_teardown\n"
      step: then stdout doesn't contain "bar_step"
      step: then stdout contains "bar_teardown"
    scenario: Jinja2 templating in specification file values
      step: given an installed vmdb2
      step: given file j2.vmdb
      step: when I run vmdb2 -v j2.vmdb --output=foo.img
      step: then exit code is 0
      step: then stdout contains "image is foo.img\nbar"
    OK, all scenarios finished successfully
    
    Formatting docs ========================================
    make[1]: Leaving directory '/workspace/src'
       create-stamp debian/debhelper-build-stamp
     debian/rules binary
    dh binary --with=python3 --buildsystem=pybuild
       dh_testroot -O--buildsystem=pybuild
       dh_prep -O--buildsystem=pybuild
       dh_auto_install --destdir=debian/vmdb2/ -O--buildsystem=pybuild
    I: pybuild base:240: /usr/bin/python3 setup.py install --root /workspace/src/debian/vmdb2 
    /workspace/src/setup.py:18: DeprecationWarning: The distutils package is deprecated and slated for removal in Python 3.12. Use setuptools or check PEP 632 for potential alternatives
      from distutils.core import setup
    running install
    running build
    running build_py
    package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
    package init file 'vmdb/plugins/__init__.py' not found (or not a regular file)
    running build_scripts
    running install_lib
    creating /workspace/src/debian/vmdb2/usr
    creating /workspace/src/debian/vmdb2/usr/lib
    creating /workspace/src/debian/vmdb2/usr/lib/python3.11
    creating /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages
    creating /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/step_list.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/tags.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/tags_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/spec.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/state.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/spec_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    creating /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mkpart_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/create_file_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/create_dir_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/resize_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/lvcreate_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/copy_dir_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/grub_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/vgcreate_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mklabel_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/chroot_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/ansible_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/cache_rootfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/copy_file_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mkfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mount_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/mkimg_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/unpack_rootfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/debootstrap_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/apt_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/shell_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/virtualfs_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/echo_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/lvscan_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/cryptsetup_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/set_part_flag_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/fstab_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/error_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugins/kpartx_plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugin_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/step_list_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/__init__.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/version.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/runcmd.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/unmount_tests.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/app.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/unmount.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    copying /workspace/src/.pybuild/cpython3_3.11_vmdb2/build/vmdb/plugin.py -> /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/step_list.py to step_list.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/tags.py to tags.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/tags_tests.py to tags_tests.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/spec.py to spec.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/state.py to state.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/spec_tests.py to spec_tests.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mkpart_plugin.py to mkpart_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/create_file_plugin.py to create_file_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/create_dir_plugin.py to create_dir_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/resize_plugin.py to resize_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/lvcreate_plugin.py to lvcreate_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/copy_dir_plugin.py to copy_dir_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/grub_plugin.py to grub_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/vgcreate_plugin.py to vgcreate_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mklabel_plugin.py to mklabel_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/chroot_plugin.py to chroot_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/ansible_plugin.py to ansible_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/cache_rootfs_plugin.py to cache_rootfs_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/copy_file_plugin.py to copy_file_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mkfs_plugin.py to mkfs_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mount_plugin.py to mount_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/mkimg_plugin.py to mkimg_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/unpack_rootfs_plugin.py to unpack_rootfs_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/debootstrap_plugin.py to debootstrap_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/apt_plugin.py to apt_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/shell_plugin.py to shell_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/virtualfs_plugin.py to virtualfs_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/echo_plugin.py to echo_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/lvscan_plugin.py to lvscan_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/cryptsetup_plugin.py to cryptsetup_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/set_part_flag_plugin.py to set_part_flag_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/fstab_plugin.py to fstab_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/error_plugin.py to error_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugins/kpartx_plugin.py to kpartx_plugin.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugin_tests.py to plugin_tests.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/step_list_tests.py to step_list_tests.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/__init__.py to __init__.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/version.py to version.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/runcmd.py to runcmd.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/unmount_tests.py to unmount_tests.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/app.py to app.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/unmount.py to unmount.cpython-311.pyc
    byte-compiling /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb/plugin.py to plugin.cpython-311.pyc
    running install_scripts
    creating /workspace/src/debian/vmdb2/usr/bin
    copying build/scripts-3.11/vmdb2 -> /workspace/src/debian/vmdb2/usr/bin
    changing mode of /workspace/src/debian/vmdb2/usr/bin/vmdb2 to 755
    running install_data
    creating /workspace/src/debian/vmdb2/usr/share
    creating /workspace/src/debian/vmdb2/usr/share/man
    creating /workspace/src/debian/vmdb2/usr/share/man/man1
    copying vmdb2.1 -> /workspace/src/debian/vmdb2/usr/share/man/man1
    running install_egg_info
    Writing /workspace/src/debian/vmdb2/usr/lib/python3.11/dist-packages/vmdb2-0.41.egg-info
       dh_installdocs -O--buildsystem=pybuild
       dh_installchangelogs -O--buildsystem=pybuild
       dh_installman -O--buildsystem=pybuild
       dh_systemd_enable -O--buildsystem=pybuild
       dh_python3 -O--buildsystem=pybuild
       dh_installinit -O--buildsystem=pybuild
       dh_systemd_start -O--buildsystem=pybuild
       dh_perl -O--buildsystem=pybuild
       dh_link -O--buildsystem=pybuild
       dh_strip_nondeterminism -O--buildsystem=pybuild
       dh_compress -O--buildsystem=pybuild
       dh_fixperms -O--buildsystem=pybuild
       dh_missing -O--buildsystem=pybuild
       dh_installdeb -O--buildsystem=pybuild
       dh_gencontrol -O--buildsystem=pybuild
       dh_md5sums -O--buildsystem=pybuild
       dh_builddeb -O--buildsystem=pybuild
    dpkg-deb: building package 'vmdb2' in '../vmdb2_0.41.ci20250906T052527-1_all.deb'.
     dpkg-genbuildinfo -O../vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo
     dpkg-genchanges -O../vmdb2_0.41.ci20250906T052527-1_amd64.changes
    dpkg-genchanges: info: including full source code in upload
     dpkg-source --after-build .
    dpkg-buildpackage: info: full upload (original source is included)
    + ls -l ..
    total 148
    drwxr-xr-x 2 root root  4096 Sep  6 05:25 artifacts
    drwxr-xr-x 2 root root  4096 Sep  6 05:25 cache
    drwxr-xr-x 2 root root  4096 Sep  6 05:25 deps
    drwxr-xr-x 9 root root  4096 Sep  6 05:25 src
    -rw-r--r-- 1 root root 23668 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1.debian.tar.xz
    -rw-r--r-- 1 root root   977 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1.dsc
    -rw-r--r-- 1 root root 26852 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1_all.deb
    -rw-r--r-- 1 root root  6108 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo
    -rw-r--r-- 1 root root  1956 Sep  6 05:25 vmdb2_0.41.ci20250906T052527-1_amd64.changes
    -rw-r--r-- 1 root root 64380 Sep  6 05:25 vmdb2_0.41.ci20250906T052527.orig.tar.xz
    + for x in ../*.deb
    + dpkg -c ../vmdb2_0.41.ci20250906T052527-1_all.deb
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/bin/
    -rwxr-xr-x root/root        76 2025-09-06 05:25 ./usr/bin/vmdb2
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/dist-packages/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/
    -rw-r--r-- root/root      1488 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/__init__.py
    -rw-r--r-- root/root      8321 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/app.py
    -rw-r--r-- root/root      1687 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugin.py
    -rw-r--r-- root/root      1922 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugin_tests.py
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/
    -rw-r--r-- root/root      3286 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/ansible_plugin.py
    -rw-r--r-- root/root      2578 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/apt_plugin.py
    -rw-r--r-- root/root      2419 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/cache_rootfs_plugin.py
    -rw-r--r-- root/root      1186 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/chroot_plugin.py
    -rw-r--r-- root/root      3984 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/copy_dir_plugin.py
    -rw-r--r-- root/root      1795 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/copy_file_plugin.py
    -rw-r--r-- root/root      1463 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/create_dir_plugin.py
    -rw-r--r-- root/root      1612 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/create_file_plugin.py
    -rw-r--r-- root/root      4805 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/cryptsetup_plugin.py
    -rw-r--r-- root/root      4942 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/debootstrap_plugin.py
    -rw-r--r-- root/root      1256 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/echo_plugin.py
    -rw-r--r-- root/root      1438 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/error_plugin.py
    -rw-r--r-- root/root      2920 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/fstab_plugin.py
    -rw-r--r-- root/root     15713 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/grub_plugin.py
    -rw-r--r-- root/root      3513 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/kpartx_plugin.py
    -rw-r--r-- root/root      1520 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/lvcreate_plugin.py
    -rw-r--r-- root/root      2336 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/lvscan_plugin.py
    -rw-r--r-- root/root      3108 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mkfs_plugin.py
    -rw-r--r-- root/root      1395 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mkimg_plugin.py
    -rw-r--r-- root/root      1139 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mklabel_plugin.py
    -rw-r--r-- root/root      4654 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mkpart_plugin.py
    -rw-r--r-- root/root      4046 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/mount_plugin.py
    -rw-r--r-- root/root      3295 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/resize_plugin.py
    -rw-r--r-- root/root      1915 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/set_part_flag_plugin.py
    -rw-r--r-- root/root      1210 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/shell_plugin.py
    -rw-r--r-- root/root      1582 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/unpack_rootfs_plugin.py
    -rw-r--r-- root/root      1539 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/vgcreate_plugin.py
    -rw-r--r-- root/root      2546 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/plugins/virtualfs_plugin.py
    -rw-r--r-- root/root      3201 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/runcmd.py
    -rw-r--r-- root/root      1488 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/spec.py
    -rw-r--r-- root/root      2687 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/spec_tests.py
    -rw-r--r-- root/root       973 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/state.py
    -rw-r--r-- root/root      4277 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/step_list.py
    -rw-r--r-- root/root      3642 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/step_list_tests.py
    -rw-r--r-- root/root      5356 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/tags.py
    -rw-r--r-- root/root      6873 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/tags_tests.py
    -rw-r--r-- root/root      2444 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/unmount.py
    -rw-r--r-- root/root      2109 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/unmount_tests.py
    -rw-r--r-- root/root        48 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb/version.py
    -rw-r--r-- root/root       241 2025-09-06 05:25 ./usr/lib/python3/dist-packages/vmdb2-0.41.egg-info
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/doc/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/doc/vmdb2/
    -rw-r--r-- root/root       687 2025-09-06 05:25 ./usr/share/doc/vmdb2/changelog.Debian.gz
    -rw-r--r-- root/root       979 2025-09-06 05:25 ./usr/share/doc/vmdb2/copyright
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/man/
    drwxr-xr-x root/root         0 2025-09-06 05:25 ./usr/share/man/man1/
    -rw-r--r-- root/root      1047 2025-09-06 05:25 ./usr/share/man/man1/vmdb2.1.gz
    + mv ../vmdb2_0.41.ci20250906T052527-1.debian.tar.xz ../vmdb2_0.41.ci20250906T052527-1.dsc ../vmdb2_0.41.ci20250906T052527-1_all.deb ../vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo ../vmdb2_0.41.ci20250906T052527-1_amd64.changes ../vmdb2_0.41.ci20250906T052527.orig.tar.xz /workspace/artifacts
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-09-06T05:25:38Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vde",
            ),
            root: Some(
                "/workspace/cache",
            ),
            size: None,
        }
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] create archive file /dev/vde
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
    [2025-09-06T05:25:38Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        }
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vdd",
            ),
            root: Some(
                "/workspace/artifacts",
            ),
            size: None,
        }
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
    [2025-09-06T05:25:38Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
    RUN: Action finished OK
    ambient-execute-plan ends
    EXIT CODE: 0
    
    ====================
[2025-09-06T05:25:41Z DEBUG ambient_ci::qemu] QEMU exit code 0
[2025-09-06T05:25:41Z DEBUG ambient_ci::run] remove old cache
[2025-09-06T05:25:41Z DEBUG ambient_ci::run] extract cache
[2025-09-06T05:25:41Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-09-06T05:25:41Z DEBUG ambient_ci::action] Plan::execute: Dput {
        artifactsdir: "/srv/ambient-state/vmdb2/artifacts",
        dput_target: Some(
            "apt.liw.fi",
        ),
    }
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527-1.debian.tar.xz
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527-1_amd64.changes
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527.orig.tar.xz
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527-1_all.deb
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/index.html
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527-1_amd64.buildinfo
[2025-09-06T05:25:41Z DEBUG ambient_ci::util] found /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527-1.dsc
[2025-09-06T05:25:41Z INFO  ambient_ci::util] dput apt.liw.fi /srv/ambient-state/vmdb2/artifacts/vmdb2_0.41.ci20250906T052527-1_amd64.changes
[2025-09-06T05:25:42Z DEBUG ambient_ci::action] Plan::execute: Rsync {
        artifactsdir: "/srv/ambient-state/vmdb2/artifacts",
        rsync_target: Some(
            "ci@http.liw.fi:/srv/http/vmdb2-manual.liw.fi",
        ),
    }
[2025-09-06T05:25:42Z INFO  ambient_ci::util] rsync /srv/ambient-state/vmdb2/artifacts/. -> ci@http.liw.fi:/srv/http/vmdb2-manual.liw.fi/.
[2025-09-06T05:25:42Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/vmdb2/meta.yaml
[2025-09-06T05:25:42Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>