01J9YKCG5E0JM39VVEX040TZAF: 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: 01J9YKDE3GQMXXVFE7ZB09M7GK

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12024-10-11T20:28:08.540Zjob dependencies complete; ready to run (waiting for 14 m 57 s)
22024-10-11T20:28:58.922Zjob assigned to worker 01J9YM8TZ2445B7RJSBPZ6PB4Z (queued for 50 s)
32024-10-11T20:29:06.970Zdownloading input: /input/build/work/bins/crucible-downstairs.gz
42024-10-11T20:29:08.650Zdownloaded input: /input/build/work/bins/crucible-downstairs.gz
52024-10-11T20:29:08.651Zdownloading input: /input/build/work/bins/crucible-hammer.gz
62024-10-11T20:29:11.045Zdownloaded input: /input/build/work/bins/crucible-hammer.gz
72024-10-11T20:29:11.045Zdownloading input: /input/build/work/bins/crutest.gz
82024-10-11T20:29:13.517Zdownloaded input: /input/build/work/bins/crutest.gz
92024-10-11T20:29:13.517Zdownloading input: /input/build/work/bins/dsc.gz
102024-10-11T20:29:14.918Zdownloaded input: /input/build/work/bins/dsc.gz
112024-10-11T20:29:14.933Zdownloading input: /input/build/work/scripts/perf-downstairs-tick.d
122024-10-11T20:29:14.947Zdownloaded input: /input/build/work/scripts/perf-downstairs-tick.d
132024-10-11T20:29:14.947Zdownloading input: /input/build/work/scripts/test_ds.sh
142024-10-11T20:29:14.973Zdownloaded input: /input/build/work/scripts/test_ds.sh
152024-10-11T20:29:14.973Zdownloading input: /input/build/work/scripts/test_live_repair.sh
162024-10-11T20:29:14.997Zdownloaded input: /input/build/work/scripts/test_live_repair.sh
172024-10-11T20:29:14.997Zdownloading input: /input/build/work/scripts/test_repair.sh
182024-10-11T20:29:15.045Zdownloaded input: /input/build/work/scripts/test_repair.sh
192024-10-11T20:29:15.045Zdownloading input: /input/build/work/scripts/test_replay.sh
202024-10-11T20:29:15.079Zdownloaded input: /input/build/work/scripts/test_replay.sh
212024-10-11T20:29:15.079Zdownloading input: /input/build/work/scripts/test_up.sh
222024-10-11T20:29:15.104Zdownloaded input: /input/build/work/scripts/test_up.sh
232024-10-11T20:29:15.105Zdownloading input: /input/build/work/scripts/upstairs_info.d
242024-10-11T20:29:15.142Zdownloaded input: /input/build/work/scripts/upstairs_info.d
252024-10-11T20:29:15.164Zdownloading input: /input/build/tmp/cargo-test-out.log
262024-10-11T20:29:16.113Zdownloaded input: /input/build/tmp/cargo-test-out.log
 
272024-10-11T20:29:16.113Zstarting task 0: "setup"
282024-10-11T20:29:16.128Z++ uname -s
292024-10-11T20:29:16.128Z+ kern=SunOS
302024-10-11T20:29:16.128Z+ case "$kern" in
312024-10-11T20:29:16.129Z+ groupadd -g 12345 build
322024-10-11T20:29:16.129Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
332024-10-11T20:29:18.128Z+ zfs create -o mountpoint=/work rpool/work
342024-10-11T20:29:18.737Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
352024-10-11T20:29:18.780Z+ home_fs=zfs
362024-10-11T20:29:18.780Z+ [[ zfs == autofs ]]
372024-10-11T20:29:18.780Z+ mkdir -p /home/build
382024-10-11T20:29:18.780Z+ chown build:build /home/build /work
392024-10-11T20:29:19.740Z+ chmod 0700 /home/build /work
402024-10-11T20:29:19.791Zprocess exited: duration 3637 ms, exit code 0
 
412024-10-11T20:29:19.874Zstarting task 1: "authentication"
422024-10-11T20:29:19.930Zprocess exited: duration 71 ms, exit code 0
 
432024-10-11T20:29:20.014Zstarting task 2: "build"
442024-10-11T20:29:20.088Z+ banner cores
452024-10-11T20:29:20.088Z
462024-10-11T20:29:20.089Z #### #### ##### ###### ####
472024-10-11T20:29:20.089Z # # # # # # # #
482024-10-11T20:29:20.089Z # # # # # ##### ####
492024-10-11T20:29:20.089Z # # # ##### # #
502024-10-11T20:29:20.089Z # # # # # # # # #
512024-10-11T20:29:20.089Z #### #### # # ###### ####
522024-10-11T20:29:20.089Z
532024-10-11T20:29:20.089Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
542024-10-11T20:29:20.089Z+ 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
552024-10-11T20:29:20.089Z+ echo ' chmod input script dir contains:'
562024-10-11T20:29:20.089Z chmod input script dir contains:
572024-10-11T20:29:20.089Z+ ls -ltr /input/build/work/scripts
582024-10-11T20:29:20.089Ztotal 42
592024-10-11T20:29:20.089Z-rwxr-xr-x 1 root root 3056 Oct 11 20:29 perf-downstairs-tick.d
602024-10-11T20:29:20.089Z-rwxr-xr-x 1 root root 2127 Oct 11 20:29 test_ds.sh
612024-10-11T20:29:20.089Z-rwxr-xr-x 1 root root 4272 Oct 11 20:29 test_live_repair.sh
622024-10-11T20:29:20.089Z-rwxr-xr-x 1 root root 6724 Oct 11 20:29 test_repair.sh
632024-10-11T20:29:20.089Z-rwxr-xr-x 1 root root 3494 Oct 11 20:29 test_replay.sh
642024-10-11T20:29:20.089Z-rwxr-xr-x 1 root root 13887 Oct 11 20:29 test_up.sh
652024-10-11T20:29:20.090Z-rwxr-xr-x 1 root root 2407 Oct 11 20:29 upstairs_info.d
662024-10-11T20:29:20.090Z+ banner unpack
672024-10-11T20:29:20.090Z
682024-10-11T20:29:20.090Z # # # # ##### ## #### # #
692024-10-11T20:29:20.090Z # # ## # # # # # # # # #
702024-10-11T20:29:20.090Z # # # # # # # # # # ####
712024-10-11T20:29:20.090Z # # # # # ##### ###### # # #
722024-10-11T20:29:20.090Z # # # ## # # # # # # #
732024-10-11T20:29:20.090Z #### # # # # # #### # #
742024-10-11T20:29:20.091Z
752024-10-11T20:29:20.091Z+ mkdir -p /var/tmp/bins
762024-10-11T20:29:20.091Z+ for t in "$input/bins/"*.gz
772024-10-11T20:29:20.091Z++ basename /input/build/work/bins/crucible-downstairs.gz
782024-10-11T20:29:20.091Z+ b=crucible-downstairs.gz
792024-10-11T20:29:20.091Z+ b=crucible-downstairs
802024-10-11T20:29:20.091Z+ gunzip
812024-10-11T20:29:22.338Z+ chmod +x /var/tmp/bins/crucible-downstairs
822024-10-11T20:29:22.341Z+ for t in "$input/bins/"*.gz
832024-10-11T20:29:22.341Z++ basename /input/build/work/bins/crucible-hammer.gz
842024-10-11T20:29:22.345Z+ b=crucible-hammer.gz
852024-10-11T20:29:22.345Z+ b=crucible-hammer
862024-10-11T20:29:22.345Z+ gunzip
872024-10-11T20:29:24.582Z+ chmod +x /var/tmp/bins/crucible-hammer
882024-10-11T20:29:24.586Z+ for t in "$input/bins/"*.gz
892024-10-11T20:29:24.586Z++ basename /input/build/work/bins/crutest.gz
902024-10-11T20:29:24.590Z+ b=crutest.gz
912024-10-11T20:29:24.590Z+ b=crutest
922024-10-11T20:29:24.590Z+ gunzip
932024-10-11T20:29:26.875Z+ chmod +x /var/tmp/bins/crutest
942024-10-11T20:29:26.878Z+ for t in "$input/bins/"*.gz
952024-10-11T20:29:26.878Z++ basename /input/build/work/bins/dsc.gz
962024-10-11T20:29:26.882Z+ b=dsc.gz
972024-10-11T20:29:26.882Z+ b=dsc
982024-10-11T20:29:26.882Z+ gunzip
992024-10-11T20:29:28.315Z+ chmod +x /var/tmp/bins/dsc
1002024-10-11T20:29:28.321Z+ export BINDIR=/var/tmp/bins
1012024-10-11T20:29:28.321Z+ BINDIR=/var/tmp/bins
1022024-10-11T20:29:28.321Z+ banner setup
1032024-10-11T20:29:28.342Z
1042024-10-11T20:29:28.343Z #### ###### ##### # # #####
1052024-10-11T20:29:28.343Z # # # # # # #
1062024-10-11T20:29:28.343Z #### ##### # # # # #
1072024-10-11T20:29:28.343Z # # # # # #####
1082024-10-11T20:29:28.343Z # # # # # # #
1092024-10-11T20:29:28.343Z #### ###### # #### #
1102024-10-11T20:29:28.343Z
1112024-10-11T20:29:28.343Z+ echo 'Setup self timeout'
1122024-10-11T20:29:28.343ZSetup self timeout
1132024-10-11T20:29:28.343Z+ jobpid=1137
1142024-10-11T20:29:28.343Z+ echo 'Setup debug logging'
1152024-10-11T20:29:28.343ZSetup debug logging
1162024-10-11T20:29:28.343Z+ mkdir /tmp/debug
1172024-10-11T20:29:28.343Z+ sleep 10800
1182024-10-11T20:29:28.343Z+ psrinfo -v
1192024-10-11T20:29:28.343Z+ df -h
1202024-10-11T20:29:28.352Z+ prstat -d d -mLc 1
1212024-10-11T20:29:28.357Z+ iostat -T d -xn 1
1222024-10-11T20:29:28.357Z+ mpstat -T d 1
1232024-10-11T20:29:28.357Z+ vmstat -T d -p 1
1242024-10-11T20:29:28.357Z+ pfexec dtrace -Z -s /input/build/work/scripts/perf-downstairs-tick.d
1252024-10-11T20:29:28.357Z+ banner replay
1262024-10-11T20:29:28.357Z+ pfexec dtrace -Z -s /input/build/work/scripts/upstairs_info.d
1272024-10-11T20:29:28.357Z
1282024-10-11T20:29:28.357Z ##### ###### ##### # ## # #
1292024-10-11T20:29:28.357Z # # # # # # # # # #
1302024-10-11T20:29:28.357Z # # ##### # # # # # #
1312024-10-11T20:29:28.357Z ##### # ##### # ###### #
1322024-10-11T20:29:28.357Z # # # # # # # #
1332024-10-11T20:29:28.357Z # # ###### # ###### # # #
1342024-10-11T20:29:28.357Z
1352024-10-11T20:29:28.357Z+ ptime -m bash /input/build/work/scripts/test_replay.sh
1362024-10-11T20:29:28.381Zstarting October 11, 2024 at 08:29:28 PM UTC
1372024-10-11T20:29:28.386ZTail /tmp/test_replay.log for test output
1382024-10-11T20:29:28.386ZCreating 3 downstairs regions
1392024-10-11T20:29:28.953ZStarting 3 downstairs
1402024-10-11T20:29:33.931ZRunning initial fill
1412024-10-11T20:29:38.662ZReplay loop starts now October 11, 2024 at 08:29:38 PM UTC
1422024-10-11T20:36:48.486ZReplays:30 time: 7:10
1432024-10-11T20:36:48.491ZDo final verify
1442024-10-11T20:36:58.075ZOctober 11, 2024 at 08:36:57 PM UTC Test ends with 0
1452024-10-11T20:36:58.077Z
1462024-10-11T20:36:58.077Zreal 7:29.667325559
1472024-10-11T20:36:58.077Zuser 25.297424412
1482024-10-11T20:36:58.077Zsys 6.625444412
1492024-10-11T20:36:58.078Ztrap 0.054987806
1502024-10-11T20:36:58.078Ztflt 0.007482692
1512024-10-11T20:36:58.078Zdflt 0.046006831
1522024-10-11T20:36:58.078Zkflt 0.001325691
1532024-10-11T20:36:58.078Zlock 11:38:33.813124733
1542024-10-11T20:36:58.078Zslp 40:14.639700872
1552024-10-11T20:36:58.078Zlat 3.582577920
1562024-10-11T20:36:58.078Zstop 0.547626790
1572024-10-11T20:37:03.082Zprocess exited: duration 458038 ms, exit code 0
1582024-10-11T20:37:03.083Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
1592024-10-11T20:38:03.137Zexec warning: : stdout descriptor may be held open by a background process; giving up!
1602024-10-11T20:38:03.137Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
1612024-10-11T20:38:03.153Zfound 13 output files
1622024-10-11T20:38:03.153Zuploading: /tmp/test_replay.log (2868108 bytes)
1632024-10-11T20:38:04.230Zuploaded: /tmp/test_replay.log
1642024-10-11T20:38:04.230Zuploading: /tmp/test_replay_verify.log (70178 bytes)
1652024-10-11T20:38:05.244Zuploaded: /tmp/test_replay_verify.log
1662024-10-11T20:38:05.245Zuploading: /tmp/debug/df.txt (1270 bytes)
1672024-10-11T20:38:06.253Zuploaded: /tmp/debug/df.txt
1682024-10-11T20:38:06.253Zuploading: /tmp/debug/iostat.txt (154511 bytes)
1692024-10-11T20:38:06.262Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 154511 -> 155411
1702024-10-11T20:38:07.268Zuploaded: /tmp/debug/iostat.txt
1712024-10-11T20:38:07.268Zuploading: /tmp/debug/mpstat.txt (375436 bytes)
1722024-10-11T20:38:07.280Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 375436 -> 378352
1732024-10-11T20:38:08.287Zuploaded: /tmp/debug/mpstat.txt
1742024-10-11T20:38:08.287Zuploading: /tmp/debug/paging.txt (67441 bytes)
1752024-10-11T20:38:08.294Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 67441 -> 68016
1762024-10-11T20:38:09.301Zuploaded: /tmp/debug/paging.txt
1772024-10-11T20:38:09.301Zuploading: /tmp/debug/perf.txt (1057004 bytes)
1782024-10-11T20:38:10.334Zuploaded: /tmp/debug/perf.txt
1792024-10-11T20:38:10.334Zuploading: /tmp/debug/prstat.txt (708615 bytes)
1802024-10-11T20:38:10.351Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 708615 -> 717659
1812024-10-11T20:38:11.358Zuploaded: /tmp/debug/prstat.txt
1822024-10-11T20:38:11.360Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
1832024-10-11T20:38:12.367Zuploaded: /tmp/debug/psrinfo.txt
1842024-10-11T20:38:12.369Zuploading: /tmp/debug/upinfo.txt (72206 bytes)
1852024-10-11T20:38:13.379Zuploaded: /tmp/debug/upinfo.txt
1862024-10-11T20:38:13.379Zuploading: /tmp/dsc/downstairs-8810.txt (7824 bytes)
1872024-10-11T20:38:14.388Zuploaded: /tmp/dsc/downstairs-8810.txt
1882024-10-11T20:38:14.390Zuploading: /tmp/dsc/downstairs-8820.txt (62943 bytes)
1892024-10-11T20:38:15.412Zuploaded: /tmp/dsc/downstairs-8820.txt
1902024-10-11T20:38:15.412Zuploading: /tmp/dsc/downstairs-8830.txt (62762 bytes)
1912024-10-11T20:38:16.453Zuploaded: /tmp/dsc/downstairs-8830.txt