01K5034S8SEDJ4E75303CFB9SX: phd-run

BasicConfig {
    output_rules: [
        "/tmp/phd-runner.log",
        "/tmp/phd-tmp-files.tar.gz",
    ],
    rust_toolchain: None,
    target: Some(
        "lab-2.0-opte",
    ),
    access_repos: [],
    publish: [],
    skip_clone: true,
}

Buildomat Job: 01K50353DKZGXV2FP3A5WRDNPR

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12025-09-12T23:20:17.763Zjob dependencies complete; ready to run (waiting for 8 m 58 s)
22025-09-12T23:35:42.958Zjob assigned to worker 01K504A7QZ6KT7AXNRKHRBNGZQ [factory lab, buskin/16724] (queued for 15 m 25 s)
32025-09-12T23:35:43.263Zhttp://172.20.0.5:9969/ipxe/buskin... ok
42025-09-12T23:35:43.306Zhttp://172.20.0.5:9969/os/buskin/platform/i86pc/kernel/amd64/unix... ok
52025-09-12T23:35:43.340Zhttp://172.20.0.5:9969/os/buskin/platform/i86pc/amd64/boot_archive... ok
62025-09-12T23:35:43.373Zhttp://172.20.0.5:9969/os/buskin/platform/i86pc/amd64/boot_archive.hash... ok
72025-09-12T23:35:43.406Zhttp://172.20.0.5:9969/postboot/buskin... ok
82025-09-12T23:35:43.441ZwcnCroia6U wcnCroia6U wcnCroia6U wcnCroia6U wcnCroia6U wcnCroia6U wcnCroia6U wcnCroia6U
92025-09-12T23:35:43.475Zcpu0: microcode has been updated from version 0xa001133 to 0xa0011d5
102025-09-12T23:35:43.508ZOxide Helios Version helios-2.0.23457 64-bit
112025-09-12T23:35:43.541ZConfiguring devices.
122025-09-12T23:35:43.575Z##################################################
132025-09-12T23:35:43.608Z#### ############# ##
142025-09-12T23:35:43.642Z### ### ############ ##
152025-09-12T23:35:43.675Z## ### # ## ### ## Oxide Computer Company ##
162025-09-12T23:35:43.709Z## ## ## ### # ### ##
172025-09-12T23:35:43.742Z## # ### #### #### This Station Under ##
182025-09-12T23:35:43.776Z### ### #### # ### Computer Control ##
192025-09-12T23:35:43.809Z#### #### ### ## ##
202025-09-12T23:35:43.865Z##################################################
212025-09-12T23:35:43.898ZHostname: buskin
222025-09-12T23:35:43.932ZLoading smf(7) service descriptions:
232025-09-12T23:35:44.489Zdownloading input: /input/phd-build/out/phd-run-with-args.sh
242025-09-12T23:35:44.522Zdownloaded input: /input/phd-build/out/phd-run-with-args.sh
252025-09-12T23:35:44.522Zdownloading input: /input/phd-build/out/phd-runner.sha256.txt
262025-09-12T23:35:44.560Zdownloaded input: /input/phd-build/out/phd-runner.sha256.txt
272025-09-12T23:35:44.583Zdownloading input: /input/phd-build/out/phd-runner.tar.gz
282025-09-12T23:36:03.741Zdownloaded input: /input/phd-build/out/phd-runner.tar.gz
292025-09-12T23:36:03.765Zdownloading input: /input/phd-build/out/propolis-server-debug.sha256.txt
302025-09-12T23:36:03.788Zdownloaded input: /input/phd-build/out/propolis-server-debug.sha256.txt
312025-09-12T23:36:03.788Zdownloading input: /input/phd-build/out/propolis-server-debug.tar.gz
322025-09-12T23:36:31.239Zdownloaded input: /input/phd-build/out/propolis-server-debug.tar.gz
 
332025-09-12T23:36:31.264Zstarting task 0: "setup"
342025-09-12T23:36:31.264Z++ uname -s
352025-09-12T23:36:31.264Z+ kern=SunOS
362025-09-12T23:36:31.264Z+ build_user=build
372025-09-12T23:36:31.264Z+ build_uid=12345
382025-09-12T23:36:31.264Z+ work_dir=/work
392025-09-12T23:36:31.264Z+ input_dir=/input
402025-09-12T23:36:31.264Z+ [[ 0 == 12345 ]]
412025-09-12T23:36:31.264Z+ case "$kern" in
422025-09-12T23:36:31.264Z+ groupadd -g 12345 build
432025-09-12T23:36:31.264Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
442025-09-12T23:36:33.257Z+ zfs create -o mountpoint=/work rpool/work
452025-09-12T23:36:33.282Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
462025-09-12T23:36:33.282Z+ home_fs=zfs
472025-09-12T23:36:33.282Z+ [[ zfs == autofs ]]
482025-09-12T23:36:33.282Z+ mkdir -p /home/build
492025-09-12T23:36:33.282Z+ chown build:build /home/build /work
502025-09-12T23:36:34.281Z+ chmod 0700 /home/build /work
512025-09-12T23:36:34.306Zprocess exited: duration 3045 ms, exit code 0
 
522025-09-12T23:36:34.355Zstarting task 1: "authentication"
532025-09-12T23:36:34.399Zprocess exited: duration 43 ms, exit code 0
 
542025-09-12T23:36:34.446Zstarting task 2: "build"
552025-09-12T23:36:34.475Z+ indir=/input
562025-09-12T23:36:34.475Z+ indir_suffix='phd-build/out/*.tar.gz'
572025-09-12T23:36:34.475Z+ phddir=/work/phd-test
582025-09-12T23:36:34.475Z+ pfexec zpool create -f phd-artifacts c1t1d0 c2t1d0
592025-09-12T23:36:34.640Z+ artifactdir=/phd-artifacts
602025-09-12T23:36:34.640Z+ banner Inputs
612025-09-12T23:36:34.665Z ###
622025-09-12T23:36:34.665Z # # # ##### # # ##### ####
632025-09-12T23:36:34.665Z # ## # # # # # # #
642025-09-12T23:36:34.665Z # # # # # # # # # ####
652025-09-12T23:36:34.665Z # # # # ##### # # # #
662025-09-12T23:36:34.665Z # # ## # # # # # #
672025-09-12T23:36:34.665Z ### # # # #### # ####
682025-09-12T23:36:34.665Z
692025-09-12T23:36:34.665Z+ find /input -ls
702025-09-12T23:36:34.665Z 34 1 drwxr-xr-x 3 root root 3 Sep 12 23:35 /input
712025-09-12T23:36:34.665Z 2 1 drwxr-xr-x 3 root root 3 Sep 12 23:35 /input/phd-build
722025-09-12T23:36:34.665Z 3 2 drwxr-xr-x 2 root root 7 Sep 12 23:36 /input/phd-build/out
732025-09-12T23:36:34.665Z 128 3 -rw-r--r-- 1 root root 2402 Sep 12 23:35 /input/phd-build/out/phd-run-with-args.sh
742025-09-12T23:36:34.665Z 257 147684 -rw-r--r-- 1 root root 151128722 Sep 12 23:36 /input/phd-build/out/propolis-server-debug.tar.gz
752025-09-12T23:36:34.665Z 130 105839 -rw-r--r-- 1 root root 108309248 Sep 12 23:36 /input/phd-build/out/phd-runner.tar.gz
762025-09-12T23:36:34.665Z 129 1 -rw-r--r-- 1 root root 65 Sep 12 23:35 /input/phd-build/out/phd-runner.sha256.txt
772025-09-12T23:36:34.665Z 256 1 -rw-r--r-- 1 root root 65 Sep 12 23:36 /input/phd-build/out/propolis-server-debug.sha256.txt
782025-09-12T23:36:34.665Z+ rm -rf /work/phd-test
792025-09-12T23:36:34.666Z+ mkdir /work/phd-test
802025-09-12T23:36:34.666Z+ for p in $indir/$indir_suffix
812025-09-12T23:36:34.666Z+ tar xzvf /input/phd-build/out/phd-runner.tar.gz -C /work/phd-test
822025-09-12T23:36:37.304ZDecompressing '/input/phd-build/out/phd-runner.tar.gz' with '/usr/bin/gzcat'...
832025-09-12T23:36:37.305Zx phd-runner, 390376568 bytes, 762455 tape blocks
842025-09-12T23:36:37.305Zx artifacts.toml, 548 bytes, 2 tape blocks
852025-09-12T23:36:37.329Z++ tar tf /input/phd-build/out/phd-runner.tar.gz
862025-09-12T23:36:39.521Z+ for f in $(tar tf "$p")
872025-09-12T23:36:39.521Z+ chmod +x /work/phd-test/phd-runner
882025-09-12T23:36:39.546Z+ for f in $(tar tf "$p")
892025-09-12T23:36:39.546Z+ chmod +x /work/phd-test/artifacts.toml
902025-09-12T23:36:39.546Z+ for p in $indir/$indir_suffix
912025-09-12T23:36:39.546Z+ tar xzvf /input/phd-build/out/propolis-server-debug.tar.gz -C /work/phd-test
922025-09-12T23:36:43.118ZDecompressing '/input/phd-build/out/propolis-server-debug.tar.gz' with '/usr/bin/gzcat'...
932025-09-12T23:36:43.118Zx propolis-server, 577129960 bytes, 1127207 tape blocks
942025-09-12T23:36:43.143Z++ tar tf /input/phd-build/out/propolis-server-debug.tar.gz
952025-09-12T23:36:46.309Z+ for f in $(tar tf "$p")
962025-09-12T23:36:46.309Z+ chmod +x /work/phd-test/propolis-server
972025-09-12T23:36:46.340Z+ ls /work/phd-test
982025-09-12T23:36:46.341Zartifacts.toml
992025-09-12T23:36:46.341Zphd-runner
1002025-09-12T23:36:46.341Zpropolis-server
1012025-09-12T23:36:46.341Z+ banner Setup
1022025-09-12T23:36:46.341Z #####
1032025-09-12T23:36:46.341Z# # ###### ##### # # #####
1042025-09-12T23:36:46.341Z# # # # # # #
1052025-09-12T23:36:46.341Z ##### ##### # # # # #
1062025-09-12T23:36:46.341Z # # # # # #####
1072025-09-12T23:36:46.341Z# # # # # # #
1082025-09-12T23:36:46.341Z ##### ###### # #### #
1092025-09-12T23:36:46.341Z
1102025-09-12T23:36:46.341Z+ tmpdir=/tmp/propolis-phd
1112025-09-12T23:36:46.341Z+ '[' '!' -d /tmp/propolis-phd ']'
1122025-09-12T23:36:46.341Z+ mkdir /tmp/propolis-phd
1132025-09-12T23:36:46.341Z+ pfexec /usr/lib/rsrvrctl -s 4096
1142025-09-12T23:36:46.922Z+ banner Tests
1152025-09-12T23:36:46.949Z#######
1162025-09-12T23:36:46.949Z # ###### #### ##### ####
1172025-09-12T23:36:46.949Z # # # # #
1182025-09-12T23:36:46.949Z # ##### #### # ####
1192025-09-12T23:36:46.949Z # # # # #
1202025-09-12T23:36:46.949Z # # # # # # #
1212025-09-12T23:36:46.949Z # ###### #### # ####
1222025-09-12T23:36:46.949Z
1232025-09-12T23:36:46.949Z+ runner=/work/phd-test/phd-runner
1242025-09-12T23:36:46.949Z+ artifacts=/work/phd-test/artifacts.toml
1252025-09-12T23:36:46.949Z+ propolis=/work/phd-test/propolis-server
1262025-09-12T23:36:46.949Z+ ls /work/phd-test/phd-runner
1272025-09-12T23:36:46.949Z/work/phd-test/phd-runner
1282025-09-12T23:36:46.949Z+ ls /work/phd-test/artifacts.toml
1292025-09-12T23:36:46.949Z/work/phd-test/artifacts.toml
1302025-09-12T23:36:46.949Z+ ls /work/phd-test/propolis-server
1312025-09-12T23:36:46.949Z/work/phd-test/propolis-server
1322025-09-12T23:36:46.949Z+ args=($runner '--emit-bunyan' 'run' '--propolis-server-cmd' $propolis '--crucible-downstairs-commit' 'auto' '--artifact-toml-path' $artifacts '--tmp-directory' $tmpdir '--artifact-directory' $artifactdir $@)
1332025-09-12T23:36:46.949Z+ set +e
1342025-09-12T23:36:46.950Z+ RUST_BACKTRACE=1
1352025-09-12T23:36:46.950Z+ RUST_LOG=info,phd=debug
1362025-09-12T23:36:46.950Z+ ptime -m pfexec /work/phd-test/phd-runner --emit-bunyan run --propolis-server-cmd /work/phd-test/propolis-server --crucible-downstairs-commit auto --artifact-toml-path /work/phd-test/artifacts.toml --tmp-directory /tmp/propolis-phd --artifact-directory /phd-artifacts --exclude-filter phd_tests::migrate::from_base
1372025-09-12T23:36:46.950Z+ tee /tmp/phd-runner.log
1382025-09-12T23:36:46.975Z{"v":0,"name":"phd-runner","msg":"phd_runner","level":30,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.713868634Z","target":"phd_runner","line":36,"file":"phd-tests/runner/src/main.rs","runner_args":"ProcessArgs { command: Run(RunOptions { propolis_server_cmd: \"/work/phd-test/propolis-server\", base_propolis_branch: None, base_propolis_commit: None, base_propolis_cmd: None, crucible_downstairs_cmd: None, crucible_downstairs_commit: Some(Auto), tmp_directory: \"/tmp/propolis-phd\", artifact_directory: Some(\"/phd-artifacts\"), output_mode: TmpFile, default_guest_cpus: 2, default_guest_memory_mib: 512, artifact_toml_path: \"/work/phd-test/artifacts.toml\", default_guest_artifact: \"alpine\", default_bootrom_artifact: \"ovmf\", include_filter: [], exclude_filter: [\"phd_tests::migrate::from_base\"], max_buildomat_wait_secs: 1200 }), disable_ansi: false, emit_bunyan: true }"}
1392025-09-12T23:36:47.001Z{"v":0,"name":"phd-runner","msg":"Created new artifact store from manifest","level":20,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.716944966Z","target":"phd_framework::artifacts::store","line":221,"file":"phd-tests/framework/src/artifacts/store.rs","store":"Store { local_dir: \"/phd-artifacts\", artifacts: {\"alpine\": Mutex { data: StoredArtifact { description: Artifact { filename: \"alpine.iso\", kind: GuestOs(Alpine), source: RemoteServer { sha256: \"ba8007f74f9b54fbae3b2520da577831b4834778a498d732f091260c61aa7ca1\" }, untar: None }, cached_path: None } }, \"ovmf\": Mutex { data: StoredArtifact { description: Artifact { filename: \"OVMF_CODE.fd\", kind: Bootrom, source: Buildomat(BuildomatArtifact { repo: Repo(\"oxidecomputer/edk2\"), series: Series(\"image_debug\"), commit: Commit(\"907a5fd1763ce5ddd74001261e5b52cd200a25f9\"), sha256: \"ff12d5cb021e34447b44301f70434e861b07d2779c16abe2f2efef49ff02fffb\" }), untar: None }, cached_path: None } }}, downloader: DownloadConfig { timeout: 600s, buildomat_backoff: ExponentialBackoff { current_interval: 1s, initial_interval: 1s, randomization_factor: 0.5, multiplier: 1.5, max_interval: 60s, start_time: Instant { tv_sec: 411, tv_nsec: 732644412 }, max_elapsed_time: Some(1200s), clock: SystemClock }, remote_server_uris: [\"https://oxide-omicron-build.s3.amazonaws.com\"] } }"}
1402025-09-12T23:36:47.001Z{"v":0,"name":"phd-runner","msg":"Adding Propolis server from local command","level":30,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.716996037Z","target":"phd_framework::artifacts::store","line":229,"file":"phd-tests/framework/src/artifacts/store.rs","propolis_server_cmd":"/work/phd-test/propolis-server"}
1412025-09-12T23:36:47.001Z{"v":0,"name":"phd-runner","msg":"Adding crucible-downstairs from Buildomat Git revision","level":30,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.717054818Z","target":"phd_framework::artifacts::store","line":313,"file":"phd-tests/framework/src/artifacts/store.rs","commit":"e164393a88e7b62598897dc4f53315f083e25333"}
1422025-09-12T23:36:47.001Z{"v":0,"name":"phd-runner","msg":"Downloading file from Buildomat...","level":20,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.717307772Z","target":"phd_framework::artifacts::buildomat","line":215,"file":"phd-tests/framework/src/artifacts/buildomat.rs","uri":"https://buildomat.eng.oxide.computer/public/file/oxidecomputer/crucible/nightly-image/e164393a88e7b62598897dc4f53315f083e25333/crucible-nightly.sha256.txt","timeout":"600s"}
1432025-09-12T23:36:47.229Z{"v":0,"name":"phd-runner","msg":"No 'migration base' Propolis server provided. Migration-from-base tests will be skipped.","level":40,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.968812421Z","target":"phd_framework","line":179,"file":"phd-tests/framework/src/lib.rs"}
1442025-09-12T23:36:47.229Z{"v":0,"name":"phd-runner","msg":"No tests selected for execution","level":30,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.968915323Z","target":"phd_runner::execute","line":76,"file":"phd-tests/runner/src/execute.rs"}
1452025-09-12T23:36:47.229Ztest result: ok. 0 passed; 0 failed; 0 skipped; 0 not run; finished in 0.00s
1462025-09-12T23:36:47.229Z
1472025-09-12T23:36:47.254Z{"v":0,"name":"phd-runner","msg":"phd_runner","level":20,"hostname":"buskin","pid":863,"time":"2025-09-12T23:36:46.968960803Z","target":"phd_runner","line":41,"file":"phd-tests/runner/src/main.rs","exit_code":0}
1482025-09-12T23:36:47.254Z
1492025-09-12T23:36:47.254Zreal 0.293024791
1502025-09-12T23:36:47.254Zuser 0.055684864
1512025-09-12T23:36:47.254Zsys 0.050131682
1522025-09-12T23:36:47.254Ztrap 0.000181421
1532025-09-12T23:36:47.254Ztflt 0.000093801
1542025-09-12T23:36:47.254Zdflt 0.000100696
1552025-09-12T23:36:47.254Zkflt 0.000003449
1562025-09-12T23:36:47.254Zlock 13.311849565
1572025-09-12T23:36:47.254Zslp 0.340811340
1582025-09-12T23:36:47.254Zlat 0.009696277
1592025-09-12T23:36:47.254Zstop 0.000205357
1602025-09-12T23:36:47.255Z+ failcount=0
1612025-09-12T23:36:47.255Z+ set -e
1622025-09-12T23:36:47.255Z+ tar -czvf /tmp/phd-tmp-files.tar.gz -C /tmp/propolis-phd '/tmp/propolis-phd/*.log'
1632025-09-12T23:36:47.255Ztar: /tmp/propolis-phd/*.log: No such file or directory
1642025-09-12T23:36:47.255ZCompressing '/tmp/phd-tmp-files.tar.gz' with '/usr/bin/gzip'...
1652025-09-12T23:36:47.255Zprocess exited: duration 12795 ms, exit code 1
 
1662025-09-12T23:36:47.305Zfound 2 output files
1672025-09-12T23:36:47.306Zuploading: /tmp/phd-runner.log (4152 bytes)
1682025-09-12T23:36:48.406Zuploaded: /tmp/phd-runner.log
1692025-09-12T23:36:48.406Zuploading: /tmp/phd-tmp-files.tar.gz (29 bytes)
1702025-09-12T23:36:49.508Zuploaded: /tmp/phd-tmp-files.tar.gz