01KD6T5TPF25K4YNRERVTVAG2V: test-replay

BasicConfig {
    output_rules: [
        "/tmp/*.txt",
        "/tmp/*.log",
        "%/tmp/debug/*.txt",
        "%/tmp/dsc/*.txt",
        "/tmp/core.*",
    ],
    rust_toolchain: None,
    target: Some(
        "helios-2.0",
    ),
    access_repos: [],
    publish: [],
    skip_clone: true,
}

Buildomat Job: 01KD6T6EF1EKQSAS86QX3PWQ2K

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12025-12-24T00:13:45.745Zjob dependencies complete; ready to run (waiting for 18 m 44 s)
22025-12-24T00:14:44.007Zjob assigned to worker 01KD6V8XYCKWEAYBMKWVZV7DS8 [factory aws, i-04d88598e2e7caad6] (queued for 58 s)
32025-12-24T00:14:52.085Zdownloading input: /input/build/work/bins/crucible-downstairs.gz
42025-12-24T00:14:53.546Zdownloaded input: /input/build/work/bins/crucible-downstairs.gz
52025-12-24T00:14:53.546Zdownloading input: /input/build/work/bins/crucible-hammer.gz
62025-12-24T00:14:54.921Zdownloaded input: /input/build/work/bins/crucible-hammer.gz
72025-12-24T00:14:54.921Zdownloading input: /input/build/work/bins/crutest.gz
82025-12-24T00:14:56.612Zdownloaded input: /input/build/work/bins/crutest.gz
92025-12-24T00:14:56.613Zdownloading input: /input/build/work/bins/dsc.gz
102025-12-24T00:14:57.365Zdownloaded input: /input/build/work/bins/dsc.gz
112025-12-24T00:14:57.368Zdownloading input: /input/build/work/scripts/perf-downstairs-tick.d
122025-12-24T00:14:57.393Zdownloaded input: /input/build/work/scripts/perf-downstairs-tick.d
132025-12-24T00:14:57.394Zdownloading input: /input/build/work/scripts/test_ds.sh
142025-12-24T00:14:57.421Zdownloaded input: /input/build/work/scripts/test_ds.sh
152025-12-24T00:14:57.421Zdownloading input: /input/build/work/scripts/test_live_repair.sh
162025-12-24T00:14:57.438Zdownloaded input: /input/build/work/scripts/test_live_repair.sh
172025-12-24T00:14:57.438Zdownloading input: /input/build/work/scripts/test_repair.sh
182025-12-24T00:14:57.465Zdownloaded input: /input/build/work/scripts/test_repair.sh
192025-12-24T00:14:57.465Zdownloading input: /input/build/work/scripts/test_replay.sh
202025-12-24T00:14:57.494Zdownloaded input: /input/build/work/scripts/test_replay.sh
212025-12-24T00:14:57.494Zdownloading input: /input/build/work/scripts/test_up.sh
222025-12-24T00:14:57.526Zdownloaded input: /input/build/work/scripts/test_up.sh
232025-12-24T00:14:57.527Zdownloading input: /input/build/work/scripts/upstairs_info.d
242025-12-24T00:14:57.549Zdownloaded input: /input/build/work/scripts/upstairs_info.d
252025-12-24T00:14:57.553Zdownloading input: /input/build/tmp/cargo-test-out.log
262025-12-24T00:14:58.086Zdownloaded input: /input/build/tmp/cargo-test-out.log
 
272025-12-24T00:14:58.086Zstarting task 0: "setup"
282025-12-24T00:14:58.094Z++ uname -s
292025-12-24T00:14:58.097Z+ kern=SunOS
302025-12-24T00:14:58.097Z+ build_user=build
312025-12-24T00:14:58.097Z+ build_uid=12345
322025-12-24T00:14:58.098Z+ work_dir=/work
332025-12-24T00:14:58.098Z+ input_dir=/input
342025-12-24T00:14:58.098Z+ [[ 0 == 12345 ]]
352025-12-24T00:14:58.098Z+ case "$kern" in
362025-12-24T00:14:58.098Z+ groupadd -g 12345 build
372025-12-24T00:14:58.107Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
382025-12-24T00:15:00.109Z+ zfs create -o mountpoint=/work rpool/work
392025-12-24T00:15:00.412Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
402025-12-24T00:15:00.422Z+ home_fs=zfs
412025-12-24T00:15:00.423Z+ [[ zfs == autofs ]]
422025-12-24T00:15:00.423Z+ mkdir -p /home/build
432025-12-24T00:15:00.423Z+ chown build:build /home/build /work
442025-12-24T00:15:02.416Z+ chmod 0700 /home/build /work
452025-12-24T00:15:02.420Zprocess exited: duration 4331 ms, exit code 0
 
462025-12-24T00:15:02.430Zstarting task 1: "authentication"
472025-12-24T00:15:02.457Zprocess exited: duration 24 ms, exit code 0
 
482025-12-24T00:15:02.468Zstarting task 2: "build"
492025-12-24T00:15:02.474Z+ banner cores
502025-12-24T00:15:02.479Z
512025-12-24T00:15:02.479Z #### #### ##### ###### ####
522025-12-24T00:15:02.479Z # # # # # # # #
532025-12-24T00:15:02.479Z # # # # # ##### ####
542025-12-24T00:15:02.480Z # # # ##### # #
552025-12-24T00:15:02.480Z # # # # # # # # #
562025-12-24T00:15:02.480Z #### #### # # ###### ####
572025-12-24T00:15:02.480Z
582025-12-24T00:15:02.480Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
592025-12-24T00:15:02.485Z+ pfexec chmod +x /input/build/work/scripts/perf-downstairs-tick.d /input/build/work/scripts/test_ds.sh /input/build/work/scripts/test_live_repair.sh /input/build/work/scripts/test_repair.sh /input/build/work/scripts/test_replay.sh /input/build/work/scripts/test_up.sh /input/build/work/scripts/upstairs_info.d
602025-12-24T00:15:02.488Z+ echo ' chmod input script dir contains:'
612025-12-24T00:15:02.488Z chmod input script dir contains:
622025-12-24T00:15:02.488Z+ ls -ltr /input/build/work/scripts
632025-12-24T00:15:02.494Ztotal 46
642025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 3056 Dec 24 00:14 perf-downstairs-tick.d
652025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 2127 Dec 24 00:14 test_ds.sh
662025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 4923 Dec 24 00:14 test_live_repair.sh
672025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 7419 Dec 24 00:14 test_repair.sh
682025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 4282 Dec 24 00:14 test_replay.sh
692025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 13887 Dec 24 00:14 test_up.sh
702025-12-24T00:15:02.494Z-rwxr-xr-x 1 root root 3241 Dec 24 00:14 upstairs_info.d
712025-12-24T00:15:02.494Z+ banner unpack
722025-12-24T00:15:02.494Z
732025-12-24T00:15:02.495Z # # # # ##### ## #### # #
742025-12-24T00:15:02.495Z # # ## # # # # # # # # #
752025-12-24T00:15:02.495Z # # # # # # # # # # ####
762025-12-24T00:15:02.495Z # # # # # ##### ###### # # #
772025-12-24T00:15:02.495Z # # # ## # # # # # # #
782025-12-24T00:15:02.495Z #### # # # # # #### # #
792025-12-24T00:15:02.495Z
802025-12-24T00:15:02.495Z+ mkdir -p /var/tmp/bins
812025-12-24T00:15:02.499Z+ for t in "$input/bins/"*.gz
822025-12-24T00:15:02.500Z++ basename /input/build/work/bins/crucible-downstairs.gz
832025-12-24T00:15:02.500Z+ b=crucible-downstairs.gz
842025-12-24T00:15:02.500Z+ b=crucible-downstairs
852025-12-24T00:15:02.500Z+ gunzip
862025-12-24T00:15:05.437Z+ chmod +x /var/tmp/bins/crucible-downstairs
872025-12-24T00:15:05.442Z+ for t in "$input/bins/"*.gz
882025-12-24T00:15:05.442Z++ basename /input/build/work/bins/crucible-hammer.gz
892025-12-24T00:15:05.442Z+ b=crucible-hammer.gz
902025-12-24T00:15:05.442Z+ b=crucible-hammer
912025-12-24T00:15:05.442Z+ gunzip
922025-12-24T00:15:08.214Z+ chmod +x /var/tmp/bins/crucible-hammer
932025-12-24T00:15:08.217Z+ for t in "$input/bins/"*.gz
942025-12-24T00:15:08.220Z++ basename /input/build/work/bins/crutest.gz
952025-12-24T00:15:08.220Z+ b=crutest.gz
962025-12-24T00:15:08.220Z+ b=crutest
972025-12-24T00:15:08.220Z+ gunzip
982025-12-24T00:15:11.156Z+ chmod +x /var/tmp/bins/crutest
992025-12-24T00:15:11.159Z+ for t in "$input/bins/"*.gz
1002025-12-24T00:15:11.159Z++ basename /input/build/work/bins/dsc.gz
1012025-12-24T00:15:11.162Z+ b=dsc.gz
1022025-12-24T00:15:11.162Z+ b=dsc
1032025-12-24T00:15:11.162Z+ gunzip
1042025-12-24T00:15:12.633Z+ chmod +x /var/tmp/bins/dsc
1052025-12-24T00:15:12.636Z+ export BINDIR=/var/tmp/bins
1062025-12-24T00:15:12.636Z+ BINDIR=/var/tmp/bins
1072025-12-24T00:15:12.636Z+ export RUST_BACKTRACE=1
1082025-12-24T00:15:12.636Z+ RUST_BACKTRACE=1
1092025-12-24T00:15:12.636Z+ banner setup
1102025-12-24T00:15:12.639Z
1112025-12-24T00:15:12.639Z #### ###### ##### # # #####
1122025-12-24T00:15:12.639Z # # # # # # #
1132025-12-24T00:15:12.639Z #### ##### # # # # #
1142025-12-24T00:15:12.639Z # # # # # #####
1152025-12-24T00:15:12.639Z # # # # # # #
1162025-12-24T00:15:12.639Z #### ###### # #### #
1172025-12-24T00:15:12.639Z
1182025-12-24T00:15:12.639Z+ echo 'Setup self timeout'
1192025-12-24T00:15:12.639ZSetup self timeout
1202025-12-24T00:15:12.639Z+ jobpid=1158
1212025-12-24T00:15:12.639Z+ echo 'Setup debug logging'
1222025-12-24T00:15:12.639Z+ mkdir /tmp/debug
1232025-12-24T00:15:12.640ZSetup debug logging
1242025-12-24T00:15:12.640Z+ sleep 10800
1252025-12-24T00:15:12.643Z+ psrinfo -v
1262025-12-24T00:15:12.645Z+ df -h
1272025-12-24T00:15:12.651Z+ prstat -d d -mLc 1
1282025-12-24T00:15:12.654Z+ iostat -T d -xn 1
1292025-12-24T00:15:12.654Z+ mpstat -T d 1
1302025-12-24T00:15:12.654Z+ vmstat -T d -p 1
1312025-12-24T00:15:12.657Z+ pfexec dtrace -Z -s /input/build/work/scripts/perf-downstairs-tick.d
1322025-12-24T00:15:12.657Z+ banner replay
1332025-12-24T00:15:12.657Z+ pfexec dtrace -Z -s /input/build/work/scripts/upstairs_info.d
1342025-12-24T00:15:12.657Z
1352025-12-24T00:15:12.657Z ##### ###### ##### # ## # #
1362025-12-24T00:15:12.657Z # # # # # # # # # #
1372025-12-24T00:15:12.657Z # # ##### # # # # # #
1382025-12-24T00:15:12.657Z ##### # ##### # ###### #
1392025-12-24T00:15:12.657Z # # # # # # # #
1402025-12-24T00:15:12.657Z # # ###### # ###### # # #
1412025-12-24T00:15:12.657Z
1422025-12-24T00:15:12.660Z+ ptime -m bash /input/build/work/scripts/test_replay.sh
1432025-12-24T00:15:12.678Zstarting December 24, 2025 at 12:15:11 AM UTC
1442025-12-24T00:15:12.681ZTail /tmp/test_replay/test_replay.log for test output
1452025-12-24T00:15:12.684ZCreating 3 downstairs regions
1462025-12-24T00:15:13.011ZStarting 3 downstairs
1472025-12-24T00:15:18.023ZRunning initial fill
1482025-12-24T00:15:22.673ZReplay loop starts now December 24, 2025 at 12:15:21 AM UTC
1492025-12-24T00:23:22.446ZReplays:30 time: 8:00
1502025-12-24T00:23:22.449ZDo final verify
1512025-12-24T00:23:31.954ZDecember 24, 2025 at 12:23:30 AM UTC Test ends with 0
1522025-12-24T00:23:31.962Z
1532025-12-24T00:23:31.966Zreal 8:19.238902012
1542025-12-24T00:23:31.966Zuser 22.659027625
1552025-12-24T00:23:31.966Zsys 5.974288881
1562025-12-24T00:23:31.966Ztrap 0.049903021
1572025-12-24T00:23:31.966Ztflt 0.017626742
1582025-12-24T00:23:31.966Zdflt 0.013786165
1592025-12-24T00:23:31.966Zkflt 0.001167039
1602025-12-24T00:23:31.966Zlock 13:13:06.444673339
1612025-12-24T00:23:31.966Zslp 45:17.969290042
1622025-12-24T00:23:31.966Zlat 3.306809861
1632025-12-24T00:23:31.966Zstop 0.115111891
1642025-12-24T00:23:36.969Zprocess exited: duration 509434 ms, exit code 0
1652025-12-24T00:23:36.969Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
1662025-12-24T00:24:37.020Zexec warning: : stdout descriptor may be held open by a background process; giving up!
1672025-12-24T00:24:37.020Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
1682025-12-24T00:24:37.032Zfound 8 output files
1692025-12-24T00:24:37.032Zuploading: /tmp/debug/df.txt (1270 bytes)
1702025-12-24T00:24:38.042Zuploaded: /tmp/debug/df.txt
1712025-12-24T00:24:38.045Zuploading: /tmp/debug/iostat.txt (170079 bytes)
1722025-12-24T00:24:38.053Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 170079 -> 170380
1732025-12-24T00:24:39.061Zuploaded: /tmp/debug/iostat.txt
1742025-12-24T00:24:39.061Zuploading: /tmp/debug/mpstat.txt (412451 bytes)
1752025-12-24T00:24:39.077Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 412451 -> 413911
1762025-12-24T00:24:40.084Zuploaded: /tmp/debug/mpstat.txt
1772025-12-24T00:24:40.088Zuploading: /tmp/debug/paging.txt (74546 bytes)
1782025-12-24T00:24:40.094Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 74546 -> 74894
1792025-12-24T00:24:41.101Zuploaded: /tmp/debug/paging.txt
1802025-12-24T00:24:41.105Zuploading: /tmp/debug/perf.txt (973847 bytes)
1812025-12-24T00:24:42.129Zuploaded: /tmp/debug/perf.txt
1822025-12-24T00:24:42.132Zuploading: /tmp/debug/prstat.txt (771590 bytes)
1832025-12-24T00:24:42.153Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 771590 -> 778045
1842025-12-24T00:24:43.161Zuploaded: /tmp/debug/prstat.txt
1852025-12-24T00:24:43.161Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
1862025-12-24T00:24:44.172Zuploaded: /tmp/debug/psrinfo.txt
1872025-12-24T00:24:44.175Zuploading: /tmp/debug/upinfo.txt (60690 bytes)
1882025-12-24T00:24:45.186Zuploaded: /tmp/debug/upinfo.txt