CI: SUCCESS sop-run

Plan, inside VM without network

After 0.00 seconds at 2026-03-15 09:03:29ZProgram: ambient-execute-plan
Version: 0.13.1@52633e5

After 0.00 seconds at 2026-03-15 09:03:29Z

steps:
- action: mkdir
  pathname: /ci
- action: mkdir
  pathname: /ci/artifacts
- action: tar_extract
  archive: /dev/vdc
  directory: /ci/src
- action: tar_extract
  archive: /dev/vdf
  directory: /ci/deps
- action: tar_extract
  archive: /dev/vde
  directory: /ci/cache
- action: shell
  shell: ln -sf /ci /workspace
- action: shell
  shell: git config --global user.name 'Ambient CI'
- action: shell
  shell: git config --global user.email ambient@example.com
- action: cargo_fmt
- action: cargo_clippy
- action: cargo_build
- action: cargo_test
- action: tar_create
  archive: /dev/vde
  directory: /ci/cache
- action: tar_create
  archive: /dev/vdd
  directory: /ci/artifacts
executor_drive: /dev/vdb
source_drive: /dev/vdc
artifact_drive: /dev/vdd
cache_drive: /dev/vde
deps_drive: /dev/vdf
workspace_dir: /ci
source_dir: /ci/src
deps_dir: /ci/deps
cache_dir: /ci/cache
artifacts_dir: /ci/artifacts

plan: Successful action mkdir: /ci

After 0.02 seconds at 2026-03-15 09:03:29Z

plan: Start action mkdir: /ci
After 0.00 seconds at 2026-03-15 09:03:29Z
```
Mkdir(
    Mkdir {
        pathname: "/ci",
    },
)
```
plan: Action succeeded mkdir: /ci
After 0.00 seconds at 2026-03-15 09:03:29Z
```
Mkdir(
    Mkdir {
        pathname: "/ci",
    },
)
```

plan: Successful action mkdir: /ci/artifacts

After 0.02 seconds at 2026-03-15 09:03:29Z

plan: Start action mkdir: /ci/artifacts
After 0.00 seconds at 2026-03-15 09:03:29Z
```
Mkdir(
    Mkdir {
        pathname: "/ci/artifacts",
    },
)
```
plan: Action succeeded mkdir: /ci/artifacts
After 0.00 seconds at 2026-03-15 09:03:29Z
```
Mkdir(
    Mkdir {
        pathname: "/ci/artifacts",
    },
)
```

plan: Successful action tar_extract

After 0.03 seconds at 2026-03-15 09:03:29Z

plan: Start action tar_extract

After 0.00 seconds at 2026-03-15 09:03:29Z

TarExtract(
    TarExtract {
        archive: "/dev/vdc",
        directory: "/ci/src",
    },
)

plan: Action succeeded tar_extract

After 0.00 seconds at 2026-03-15 09:03:29Z

TarExtract(
    TarExtract {
        archive: "/dev/vdc",
        directory: "/ci/src",
    },
)

plan: Successful action tar_extract

After 0.04 seconds at 2026-03-15 09:03:29Z

plan: Start action tar_extract

After 0.00 seconds at 2026-03-15 09:03:29Z

TarExtract(
    TarExtract {
        archive: "/dev/vdf",
        directory: "/ci/deps",
    },
)

plan: Action succeeded tar_extract

After 0.00 seconds at 2026-03-15 09:03:30Z

TarExtract(
    TarExtract {
        archive: "/dev/vdf",
        directory: "/ci/deps",
    },
)

plan: Successful action tar_extract

After 0.64 seconds at 2026-03-15 09:03:30Z

plan: Start action tar_extract

After 0.00 seconds at 2026-03-15 09:03:30Z

TarExtract(
    TarExtract {
        archive: "/dev/vde",
        directory: "/ci/cache",
    },
)

plan: Action succeeded tar_extract

After 0.00 seconds at 2026-03-15 09:03:30Z

TarExtract(
    TarExtract {
        archive: "/dev/vde",
        directory: "/ci/cache",
    },
)

plan: Successful action shell: ln -sf /ci /workspace

After 0.93 seconds at 2026-03-15 09:03:30Z

plan: Start action shell: ln -sf /ci /workspace
After 0.00 seconds at 2026-03-15 09:03:30Z
```
Shell(
    Shell {
        shell: "ln -sf /ci /workspace",
    },
)
```
plan: Start program bash
After 0.00 seconds at 2026-03-15 09:03:30Z
- bash
- -c
- set -xeuo pipefail ln -sf /ci /workspace
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
Stderr:
+ ln -sf /ci /workspace
plan: Action succeeded shell: ln -sf /ci /workspace
After 0.00 seconds at 2026-03-15 09:03:30Z
```
Shell(
    Shell {
        shell: "ln -sf /ci /workspace",
    },
)
```

plan: Successful action shell: git config --global user.name 'Ambient CI'

After 0.94 seconds at 2026-03-15 09:03:30Z

plan: Start action shell: git config --global user.name 'Ambient CI'
After 0.00 seconds at 2026-03-15 09:03:30Z
```
Shell(
    Shell {
        shell: "git config --global user.name 'Ambient CI'",
    },
)
```
plan: Start program bash
After 0.00 seconds at 2026-03-15 09:03:30Z
- bash
- -c
- set -xeuo pipefail git config --global user.name 'Ambient CI'
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
Stderr:
+ git config --global user.name 'Ambient CI'
plan: Action succeeded shell: git config --global user.name 'Ambient CI'
After 0.00 seconds at 2026-03-15 09:03:30Z
```
Shell(
    Shell {
        shell: "git config --global user.name 'Ambient CI'",
    },
)
```

plan: Successful action shell: git config --global user.email ambient@example.com

After 0.96 seconds at 2026-03-15 09:03:30Z

plan: Start action shell: git config --global user.email ambient@example.com
After 0.00 seconds at 2026-03-15 09:03:30Z
```
Shell(
    Shell {
        shell: "git config --global user.email ambient@example.com",
    },
)
```
plan: Start program bash
After 0.00 seconds at 2026-03-15 09:03:30Z
- bash
- -c
- set -xeuo pipefail git config --global user.email ambient@example.com
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
Stderr:
+ git config --global user.email ambient@example.com
plan: Action succeeded shell: git config --global user.email ambient@example.com
After 0.00 seconds at 2026-03-15 09:03:30Z
```
Shell(
    Shell {
        shell: "git config --global user.email ambient@example.com",
    },
)
```

plan: Successful action cargo_fmt

After 0.97 seconds at 2026-03-15 09:03:30Z

plan: Start action cargo_fmt
After 0.00 seconds at 2026-03-15 09:03:30Z
```
CargoFmt(
    CargoFmt,
)
```
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:30Z
- cargo
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
Stdout:
cargo 1.94.0 (85eff7c80 2026-01-15)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:30Z
- cargo
- clippy
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
Stdout:
clippy 0.1.94 (4a4ef493e3 2026-03-02)
plan: Start program rustc
After 0.00 seconds at 2026-03-15 09:03:30Z
- rustc
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
Stdout:
rustc 1.94.0 (4a4ef493e 2026-03-02)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:30Z
- cargo
- fmt
- --check
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:30Z
Exit code: 0
plan: Action succeeded cargo_fmt
After 0.00 seconds at 2026-03-15 09:03:30Z
```
CargoFmt(
    CargoFmt,
)
```

plan: Successful action cargo_clippy

After 1.37 seconds at 2026-03-15 09:03:30Z

plan: Start action cargo_clippy
After 0.00 seconds at 2026-03-15 09:03:30Z
```
CargoClippy(
    CargoClippy,
)
```
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:30Z
- cargo
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:31Z
Exit code: 0
Stdout:
cargo 1.94.0 (85eff7c80 2026-01-15)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:31Z
- cargo
- clippy
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:31Z
Exit code: 0
Stdout:
clippy 0.1.94 (4a4ef493e3 2026-03-02)
plan: Start program rustc
After 0.00 seconds at 2026-03-15 09:03:31Z
- rustc
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:31Z
Exit code: 0
Stdout:
rustc 1.94.0 (4a4ef493e 2026-03-02)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:31Z
- cargo
- clippy
- --offline
- --locked
- --workspace
- --all-targets
- --no-deps
- --
- --deny
- warnings

plan: Program succeeded

After 0.00 seconds at 2026-03-15 09:03:31Z

Exit code: 0

Stderr:

    Checking sop-run v0.1.0 (/ci/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.30s

plan: Action succeeded cargo_clippy
After 0.00 seconds at 2026-03-15 09:03:31Z
```
CargoClippy(
    CargoClippy,
)
```

plan: Successful action cargo_build

After 1.95 seconds at 2026-03-15 09:03:31Z

plan: Start action cargo_build
After 0.00 seconds at 2026-03-15 09:03:31Z
```
CargoBuild(
    CargoBuild,
)
```
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:31Z
- cargo
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:31Z
Exit code: 0
Stdout:
cargo 1.94.0 (85eff7c80 2026-01-15)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:31Z
- cargo
- clippy
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:31Z
Exit code: 0
Stdout:
clippy 0.1.94 (4a4ef493e3 2026-03-02)
plan: Start program rustc
After 0.00 seconds at 2026-03-15 09:03:31Z
- rustc
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:31Z
Exit code: 0
Stdout:
rustc 1.94.0 (4a4ef493e 2026-03-02)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:31Z
- cargo
- build
- --offline
- --locked
- --workspace
- --all-targets

plan: Program succeeded

After 0.00 seconds at 2026-03-15 09:03:32Z

Exit code: 0

Stderr:

   Compiling sop-run v0.1.0 (/ci/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.31s

plan: Action succeeded cargo_build
After 0.00 seconds at 2026-03-15 09:03:32Z
```
CargoBuild(
    CargoBuild,
)
```

plan: Successful action cargo_test

After 2.49 seconds at 2026-03-15 09:03:32Z

plan: Start action cargo_test
After 0.00 seconds at 2026-03-15 09:03:32Z
```
CargoTest(
    CargoTest,
)
```
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:32Z
- cargo
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:32Z
Exit code: 0
Stdout:
cargo 1.94.0 (85eff7c80 2026-01-15)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:32Z
- cargo
- clippy
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:32Z
Exit code: 0
Stdout:
clippy 0.1.94 (4a4ef493e3 2026-03-02)
plan: Start program rustc
After 0.00 seconds at 2026-03-15 09:03:32Z
- rustc
- --version
plan: Program succeeded
After 0.00 seconds at 2026-03-15 09:03:32Z
Exit code: 0
Stdout:
rustc 1.94.0 (4a4ef493e 2026-03-02)
plan: Start program cargo
After 0.00 seconds at 2026-03-15 09:03:32Z
- cargo
- test
- --offline
- --locked
- --workspace

plan: Program succeeded

After 0.00 seconds at 2026-03-15 09:03:32Z

Exit code: 0

Stdout:

running 14 tests
test cmd::armor::test::armor ... ok
test cmd::dearmor::test::armor ... ok
test cmd::extract_cert::test::extract_cert ... ok
test cmd::generate_key::test::generate_armored_key ... ok
test cmd::encrypt::test::round_trip ... ok
test cmd::generate_key::test::generate_binary_key ... ok
test cmd::version::test::backend ... ok
test cmd::version::test::extended ... ok
test cmd::version::test::sop_spec ... ok
test cmd::version::test::sopv ... ok
test cmd::sign::test::roundtrip ... ok
test engine::test::cat ... ok
test cmd::version::test::version ... ok
test engine::test::echo ... ok

test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s


running 0 tests

test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s

Stderr:

    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
     Running unittests src/lib.rs (/ci/cache/cargo-target/debug/deps/sop_run-3eefebab2770e865)
   Doc-tests sop_run

plan: Action succeeded cargo_test
After 0.00 seconds at 2026-03-15 09:03:32Z
```
CargoTest(
    CargoTest,
)
```

plan: Successful action tar_create

After 2.83 seconds at 2026-03-15 09:03:32Z

plan: Start action tar_create

After 0.00 seconds at 2026-03-15 09:03:32Z

TarCreate(
    TarCreate {
        archive: "/dev/vde",
        directory: "/ci/cache",
    },
)

plan: Action succeeded tar_create

After 0.00 seconds at 2026-03-15 09:03:32Z

TarCreate(
    TarCreate {
        archive: "/dev/vde",
        directory: "/ci/cache",
    },
)

plan: Successful action tar_create

After 3.18 seconds at 2026-03-15 09:03:32Z

plan: Start action tar_create

After 0.00 seconds at 2026-03-15 09:03:32Z

TarCreate(
    TarCreate {
        archive: "/dev/vdd",
        directory: "/ci/artifacts",
    },
)

plan: Action succeeded tar_create

After 0.00 seconds at 2026-03-15 09:03:32Z

TarCreate(
    TarCreate {
        archive: "/dev/vdd",
        directory: "/ci/artifacts",
    },
)

plan: Plan succeeded

After 3.20 seconds at 2026-03-15 09:03:32ZHopefully all is good.

Raw log messages for Ambient troubleshooting

Raw log messages

These raw log messages are meant to help Ambient developers figure out problems. You can ignore them.

{
  "type": "executor_starts",
  "name": "ambient-execute-plan",
  "version": "0.13.1@52633e5",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 627772316
  },
  "log_source": "Plan"
}

{
  "type": "runnable_plan",
  "steps": [
    {
      "action": "mkdir",
      "pathname": "/ci"
    },
    {
      "action": "mkdir",
      "pathname": "/ci/artifacts"
    },
    {
      "action": "tar_extract",
      "archive": "/dev/vdc",
      "directory": "/ci/src"
    },
    {
      "action": "tar_extract",
      "archive": "/dev/vdf",
      "directory": "/ci/deps"
    },
    {
      "action": "tar_extract",
      "archive": "/dev/vde",
      "directory": "/ci/cache"
    },
    {
      "action": "shell",
      "shell": "ln -sf /ci /workspace"
    },
    {
      "action": "shell",
      "shell": "git config --global user.name 'Ambient CI'"
    },
    {
      "action": "shell",
      "shell": "git config --global user.email ambient@example.com"
    },
    {
      "action": "cargo_fmt"
    },
    {
      "action": "cargo_clippy"
    },
    {
      "action": "cargo_build"
    },
    {
      "action": "cargo_test"
    },
    {
      "action": "tar_create",
      "archive": "/dev/vde",
      "directory": "/ci/cache"
    },
    {
      "action": "tar_create",
      "archive": "/dev/vdd",
      "directory": "/ci/artifacts"
    }
  ],
  "executor_drive": "/dev/vdb",
  "source_drive": "/dev/vdc",
  "artifact_drive": "/dev/vdd",
  "cache_drive": "/dev/vde",
  "deps_drive": "/dev/vdf",
  "workspace_dir": "/ci",
  "source_dir": "/ci/src",
  "deps_dir": "/ci/deps",
  "cache_dir": "/ci/cache",
  "artifacts_dir": "/ci/artifacts",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 630790898
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "mkdir",
  "pathname": "/ci",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 646745363
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "mkdir",
  "pathname": "/ci",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 648957188
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "mkdir",
  "pathname": "/ci/artifacts",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 651156366
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "mkdir",
  "pathname": "/ci/artifacts",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 653488418
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "tar_extract",
  "archive": "/dev/vdc",
  "directory": "/ci/src",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 655893555
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "tar_extract",
  "archive": "/dev/vdc",
  "directory": "/ci/src",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 665153319
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "tar_extract",
  "archive": "/dev/vdf",
  "directory": "/ci/deps",
  "timestamp": {
    "secs_since_epoch": 1773565409,
    "nanos_since_epoch": 665178208
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "tar_extract",
  "archive": "/dev/vdf",
  "directory": "/ci/deps",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 263110486
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "tar_extract",
  "archive": "/dev/vde",
  "directory": "/ci/cache",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 263145107
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "tar_extract",
  "archive": "/dev/vde",
  "directory": "/ci/cache",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 555634714
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "shell",
  "shell": "ln -sf /ci /workspace",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 555671143
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        98,
        97,
        115,
        104
      ]
    },
    {
      "Unix": [
        45,
        99
      ]
    },
    {
      "Unix": [
        115,
        101,
        116,
        32,
        45,
        120,
        101,
        117,
        111,
        32,
        112,
        105,
        112,
        101,
        102,
        97,
        105,
        108,
        10,
        108,
        110,
        32,
        45,
        115,
        102,
        32,
        47,
        99,
        105,
        32,
        47,
        119,
        111,
        114,
        107,
        115,
        112,
        97,
        99,
        101,
        10
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 555682704
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "",
  "stderr": "+ ln -sf /ci /workspace\n",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 567861681
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "shell",
  "shell": "ln -sf /ci /workspace",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 567885131
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "shell",
  "shell": "git config --global user.name 'Ambient CI'",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 567890261
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        98,
        97,
        115,
        104
      ]
    },
    {
      "Unix": [
        45,
        99
      ]
    },
    {
      "Unix": [
        115,
        101,
        116,
        32,
        45,
        120,
        101,
        117,
        111,
        32,
        112,
        105,
        112,
        101,
        102,
        97,
        105,
        108,
        10,
        103,
        105,
        116,
        32,
        99,
        111,
        110,
        102,
        105,
        103,
        32,
        45,
        45,
        103,
        108,
        111,
        98,
        97,
        108,
        32,
        117,
        115,
        101,
        114,
        46,
        110,
        97,
        109,
        101,
        32,
        39,
        65,
        109,
        98,
        105,
        101,
        110,
        116,
        32,
        67,
        73,
        39,
        10
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 568104519
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "",
  "stderr": "+ git config --global user.name 'Ambient CI'\n",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 585912224
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "shell",
  "shell": "git config --global user.name 'Ambient CI'",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 585937011
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "shell",
  "shell": "git config --global user.email ambient@example.com",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 585942903
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        98,
        97,
        115,
        104
      ]
    },
    {
      "Unix": [
        45,
        99
      ]
    },
    {
      "Unix": [
        115,
        101,
        116,
        32,
        45,
        120,
        101,
        117,
        111,
        32,
        112,
        105,
        112,
        101,
        102,
        97,
        105,
        108,
        10,
        103,
        105,
        116,
        32,
        99,
        111,
        110,
        102,
        105,
        103,
        32,
        45,
        45,
        103,
        108,
        111,
        98,
        97,
        108,
        32,
        117,
        115,
        101,
        114,
        46,
        101,
        109,
        97,
        105,
        108,
        32,
        97,
        109,
        98,
        105,
        101,
        110,
        116,
        64,
        101,
        120,
        97,
        109,
        112,
        108,
        101,
        46,
        99,
        111,
        109,
        10
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 586165272
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "",
  "stderr": "+ git config --global user.email ambient@example.com\n",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 601987514
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "shell",
  "shell": "git config --global user.email ambient@example.com",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 602009115
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "cargo_fmt",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 602014169
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 602445401
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "cargo 1.94.0 (85eff7c80 2026-01-15)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 681824631
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        99,
        108,
        105,
        112,
        112,
        121
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 681863610
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "clippy 0.1.94 (4a4ef493e3 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 788659654
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        114,
        117,
        115,
        116,
        99
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 791530409
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "rustc 1.94.0 (4a4ef493e 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 838271794
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        102,
        109,
        116
      ]
    },
    {
      "Unix": [
        45,
        45,
        99,
        104,
        101,
        99,
        107
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 841034773
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 998471698
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "cargo_fmt",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 998507022
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "cargo_clippy",
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 998512400
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565410,
    "nanos_since_epoch": 998520917
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "cargo 1.94.0 (85eff7c80 2026-01-15)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 43388106
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        99,
        108,
        105,
        112,
        112,
        121
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 46243034
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "clippy 0.1.94 (4a4ef493e3 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 129128047
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        114,
        117,
        115,
        116,
        99
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 131980035
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "rustc 1.94.0 (4a4ef493e 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 174201816
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        99,
        108,
        105,
        112,
        112,
        121
      ]
    },
    {
      "Unix": [
        45,
        45,
        111,
        102,
        102,
        108,
        105,
        110,
        101
      ]
    },
    {
      "Unix": [
        45,
        45,
        108,
        111,
        99,
        107,
        101,
        100
      ]
    },
    {
      "Unix": [
        45,
        45,
        119,
        111,
        114,
        107,
        115,
        112,
        97,
        99,
        101
      ]
    },
    {
      "Unix": [
        45,
        45,
        97,
        108,
        108,
        45,
        116,
        97,
        114,
        103,
        101,
        116,
        115
      ]
    },
    {
      "Unix": [
        45,
        45,
        110,
        111,
        45,
        100,
        101,
        112,
        115
      ]
    },
    {
      "Unix": [
        45,
        45
      ]
    },
    {
      "Unix": [
        45,
        45,
        100,
        101,
        110,
        121
      ]
    },
    {
      "Unix": [
        119,
        97,
        114,
        110,
        105,
        110,
        103,
        115
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 174245197
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "",
  "stderr": "    Checking sop-run v0.1.0 (/ci/src)\n    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.30s\n",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 579569963
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "cargo_clippy",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 579606122
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "cargo_build",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 579611813
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 579619688
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "cargo 1.94.0 (85eff7c80 2026-01-15)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 625939695
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        99,
        108,
        105,
        112,
        112,
        121
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 625980661
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "clippy 0.1.94 (4a4ef493e3 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 714109925
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        114,
        117,
        115,
        116,
        99
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 714150005
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "rustc 1.94.0 (4a4ef493e 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 758773248
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        98,
        117,
        105,
        108,
        100
      ]
    },
    {
      "Unix": [
        45,
        45,
        111,
        102,
        102,
        108,
        105,
        110,
        101
      ]
    },
    {
      "Unix": [
        45,
        45,
        108,
        111,
        99,
        107,
        101,
        100
      ]
    },
    {
      "Unix": [
        45,
        45,
        119,
        111,
        114,
        107,
        115,
        112,
        97,
        99,
        101
      ]
    },
    {
      "Unix": [
        45,
        45,
        97,
        108,
        108,
        45,
        116,
        97,
        114,
        103,
        101,
        116,
        115
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565411,
    "nanos_since_epoch": 758814028
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "",
  "stderr": "   Compiling sop-run v0.1.0 (/ci/src)\n    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.31s\n",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 116608515
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "cargo_build",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 120419076
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "cargo_test",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 122517863
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 124537052
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "cargo 1.94.0 (85eff7c80 2026-01-15)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 164769442
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        99,
        108,
        105,
        112,
        112,
        121
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 164809514
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "clippy 0.1.94 (4a4ef493e3 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 251696186
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        114,
        117,
        115,
        116,
        99
      ]
    },
    {
      "Unix": [
        45,
        45,
        118,
        101,
        114,
        115,
        105,
        111,
        110
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 254605872
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "rustc 1.94.0 (4a4ef493e 2026-03-02)\n",
  "stderr": "",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 296486372
  },
  "log_source": "Plan"
}

{
  "type": "start_program",
  "argv": [
    {
      "Unix": [
        99,
        97,
        114,
        103,
        111
      ]
    },
    {
      "Unix": [
        116,
        101,
        115,
        116
      ]
    },
    {
      "Unix": [
        45,
        45,
        111,
        102,
        102,
        108,
        105,
        110,
        101
      ]
    },
    {
      "Unix": [
        45,
        45,
        108,
        111,
        99,
        107,
        101,
        100
      ]
    },
    {
      "Unix": [
        45,
        45,
        119,
        111,
        114,
        107,
        115,
        112,
        97,
        99,
        101
      ]
    }
  ],
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 296525809
  },
  "log_source": "Plan"
}

{
  "type": "program_succeeded",
  "exit_code": 0,
  "stdout": "\nrunning 14 tests\ntest cmd::armor::test::armor ... ok\ntest cmd::dearmor::test::armor ... ok\ntest cmd::extract_cert::test::extract_cert ... ok\ntest cmd::generate_key::test::generate_armored_key ... ok\ntest cmd::encrypt::test::round_trip ... ok\ntest cmd::generate_key::test::generate_binary_key ... ok\ntest cmd::version::test::backend ... ok\ntest cmd::version::test::extended ... ok\ntest cmd::version::test::sop_spec ... ok\ntest cmd::version::test::sopv ... ok\ntest cmd::sign::test::roundtrip ... ok\ntest engine::test::cat ... ok\ntest cmd::version::test::version ... ok\ntest engine::test::echo ... ok\n\ntest result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s\n\n\nrunning 0 tests\n\ntest result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s\n\n",
  "stderr": "    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s\n     Running unittests src/lib.rs (/ci/cache/cargo-target/debug/deps/sop_run-3eefebab2770e865)\n   Doc-tests sop_run\n",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 452942132
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "cargo_test",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 453001845
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "tar_create",
  "archive": "/dev/vde",
  "directory": "/ci/cache",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 453220792
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "tar_create",
  "archive": "/dev/vde",
  "directory": "/ci/cache",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 808263905
  },
  "log_source": "Plan"
}

{
  "type": "execute_action",
  "action": "tar_create",
  "archive": "/dev/vdd",
  "directory": "/ci/artifacts",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 810990306
  },
  "log_source": "Plan"
}

{
  "type": "action_succeeded",
  "action": "tar_create",
  "archive": "/dev/vdd",
  "directory": "/ci/artifacts",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 817796806
  },
  "log_source": "Plan"
}

{
  "type": "plan_succeeded",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 823331511
  },
  "log_source": "Plan"
}

{
  "type": "executor_ends_successfully",
  "timestamp": {
    "secs_since_epoch": 1773565412,
    "nanos_since_epoch": 825120331
  },
  "log_source": "Plan"
}

CI: SUCCESS sop-run

Table of Contents

Run log

Plan, inside VM without network

Raw log messages for Ambient troubleshooting

Trigger message

Ambient stdout

Ambient stderr