01M3XF9ZB3AH7QNX9ST61ZKGNX: test-memory

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: 01M3XFAZYV4B2ZDAPHXSM03EDY

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-10-02T05:07:04.340Zjob dependencies complete; ready to run (waiting for 13 m 56 s)
22026-10-02T05:07:04.353Zjob assigned to worker 01M3XG36J8E6VGKG6W09FG8ZEK [factory aws, i-018c6f7dedcd5a8c7] (queued for 0 s)
32026-10-02T05:07:10.934Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-10-02T05:07:10.940Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-10-02T05:07:10.940Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-10-02T05:07:12.177Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-10-02T05:07:12.189Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-10-02T05:07:12.189Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-10-02T05:07:12.189Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-10-02T05:07:12.424Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-10-02T05:07:12.424Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-10-02T05:07:12.434Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-10-02T05:07:12.434Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-10-02T05:07:12.593Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-10-02T05:07:12.597Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-10-02T05:07:12.605Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-10-02T05:07:12.605Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-10-02T05:07:12.891Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-10-02T05:07:12.900Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-10-02T05:07:13.049Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-10-02T05:07:13.060Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-10-02T05:07:13.219Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-10-02T05:07:13.219Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-10-02T05:07:13.352Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-10-02T05:07:13.352Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-10-02T05:07:13.515Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-10-02T05:07:13.515Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-10-02T05:07:13.587Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-10-02T05:07:13.592Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-10-02T05:07:13.593Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-10-02T05:07:13.593Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-10-02T05:07:13.598Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-10-02T05:07:13.598Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-10-02T05:07:13.602Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-10-02T05:07:13.602Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-10-02T05:07:13.609Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-10-02T05:07:13.609Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-10-02T05:07:14.057Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-10-02T05:07:14.057Zstarting task 0: "setup"
402026-10-02T05:07:14.062Z++ uname -s
412026-10-02T05:07:14.063Z+ kern=SunOS
422026-10-02T05:07:14.063Z+ build_user=build
432026-10-02T05:07:14.063Z+ build_uid=12345
442026-10-02T05:07:14.063Z+ work_dir=/work
452026-10-02T05:07:14.063Z+ input_dir=/input
462026-10-02T05:07:14.063Z+ [[ 0 == 12345 ]]
472026-10-02T05:07:14.063Z+ case "$kern" in
482026-10-02T05:07:14.073Z+ groupadd -g 12345 build
492026-10-02T05:07:14.080Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-10-02T05:07:16.082Z+ zfs create -o mountpoint=/work rpool/work
512026-10-02T05:07:16.142Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-10-02T05:07:16.157Z+ home_fs=zfs
532026-10-02T05:07:16.157Z+ [[ zfs == autofs ]]
542026-10-02T05:07:16.157Z+ mkdir -p /home/build
552026-10-02T05:07:16.157Z+ chown build:build /home/build /work
562026-10-02T05:07:18.140Z+ chmod 0700 /home/build /work
572026-10-02T05:07:18.144Zprocess exited: duration 4088 ms, exit code 0
 
582026-10-02T05:07:18.170Zstarting task 1: "authentication"
592026-10-02T05:07:18.174ZWARNING: job store has no value for "GITHUB_TOKEN"; waiting for a value...
602026-10-02T05:07:22.193Zprocess exited: duration 4030 ms, exit code 0
 
612026-10-02T05:07:22.209Zstarting task 2: "build"
622026-10-02T05:07:22.218Z+ banner cores
632026-10-02T05:07:22.226Z
642026-10-02T05:07:22.226Z #### #### ##### ###### ####
652026-10-02T05:07:22.226Z # # # # # # # #
662026-10-02T05:07:22.226Z # # # # # ##### ####
672026-10-02T05:07:22.227Z # # # ##### # #
682026-10-02T05:07:22.227Z # # # # # # # # #
692026-10-02T05:07:22.227Z #### #### # # ###### ####
702026-10-02T05:07:22.227Z
712026-10-02T05:07:22.227Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
722026-10-02T05:07:22.227Z+ banner unpack
732026-10-02T05:07:22.227Z
742026-10-02T05:07:22.227Z # # # # ##### ## #### # #
752026-10-02T05:07:22.227Z # # ## # # # # # # # # #
762026-10-02T05:07:22.227Z # # # # # # # # # # ####
772026-10-02T05:07:22.228Z # # # # # ##### ###### # # #
782026-10-02T05:07:22.228Z # # # ## # # # # # # #
792026-10-02T05:07:22.228Z #### # # # # # #### # #
802026-10-02T05:07:22.228Z
812026-10-02T05:07:22.228Z+ mkdir -p /var/tmp/bins
822026-10-02T05:07:22.228Z+ for t in "$input/rbins/"*.gz
832026-10-02T05:07:22.228Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
842026-10-02T05:07:22.228Z+ b=crucible-downstairs.gz
852026-10-02T05:07:22.229Z+ b=crucible-downstairs
862026-10-02T05:07:22.229Z+ gunzip
872026-10-02T05:07:22.517Z+ chmod +x /var/tmp/bins/crucible-downstairs
882026-10-02T05:07:22.523Z+ for t in "$input/rbins/"*.gz
892026-10-02T05:07:22.523Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
902026-10-02T05:07:22.523Z+ b=crucible-hammer.gz
912026-10-02T05:07:22.523Z+ b=crucible-hammer
922026-10-02T05:07:22.524Z+ gunzip
932026-10-02T05:07:22.776Z+ chmod +x /var/tmp/bins/crucible-hammer
942026-10-02T05:07:22.779Z+ for t in "$input/rbins/"*.gz
952026-10-02T05:07:22.779Z++ basename /input/rbuild/work/rbins/crudd.gz
962026-10-02T05:07:22.779Z+ b=crudd.gz
972026-10-02T05:07:22.779Z+ b=crudd
982026-10-02T05:07:22.779Z+ gunzip
992026-10-02T05:07:23.021Z+ chmod +x /var/tmp/bins/crudd
1002026-10-02T05:07:23.028Z+ for t in "$input/rbins/"*.gz
1012026-10-02T05:07:23.028Z++ basename /input/rbuild/work/rbins/crutest.gz
1022026-10-02T05:07:23.028Z+ b=crutest.gz
1032026-10-02T05:07:23.029Z+ b=crutest
1042026-10-02T05:07:23.029Z+ gunzip
1052026-10-02T05:07:23.300Z+ chmod +x /var/tmp/bins/crutest
1062026-10-02T05:07:23.311Z+ for t in "$input/rbins/"*.gz
1072026-10-02T05:07:23.312Z++ basename /input/rbuild/work/rbins/dsc.gz
1082026-10-02T05:07:23.312Z+ b=dsc.gz
1092026-10-02T05:07:23.312Z+ b=dsc
1102026-10-02T05:07:23.312Z+ gunzip
1112026-10-02T05:07:23.431Z+ chmod +x /var/tmp/bins/dsc
1122026-10-02T05:07:23.436Z+ export BINDIR=/var/tmp/bins
1132026-10-02T05:07:23.436Z+ BINDIR=/var/tmp/bins
1142026-10-02T05:07:23.436Z+ export RUST_BACKTRACE=1
1152026-10-02T05:07:23.436Z+ RUST_BACKTRACE=1
1162026-10-02T05:07:23.436Z+ banner setup
1172026-10-02T05:07:23.436Z
1182026-10-02T05:07:23.436Z #### ###### ##### # # #####
1192026-10-02T05:07:23.436Z # # # # # # #
1202026-10-02T05:07:23.436Z #### ##### # # # # #
1212026-10-02T05:07:23.436Z # # # # # #####
1222026-10-02T05:07:23.436Z # # # # # # #
1232026-10-02T05:07:23.436Z #### ###### # #### #
1242026-10-02T05:07:23.436Z
1252026-10-02T05:07:23.436Z+ pfexec plimit -n 9123456 1088
1262026-10-02T05:07:23.451Z+ echo 'Setup self timeout'
1272026-10-02T05:07:23.451ZSetup self timeout
1282026-10-02T05:07:23.455Z+ jobpid=1088
1292026-10-02T05:07:23.455Z+ echo 'Setup debug logging'
1302026-10-02T05:07:23.455Z+ mkdir /tmp/debug
1312026-10-02T05:07:23.455ZSetup debug logging
1322026-10-02T05:07:23.455Z+ sleep 3600
1332026-10-02T05:07:23.455Z+ psrinfo -v
1342026-10-02T05:07:23.455Z+ df -h
1352026-10-02T05:07:23.460Z+ prstat -d d -mLc 1
1362026-10-02T05:07:23.460Z+ iostat -T d -xn 1
1372026-10-02T05:07:23.463Z+ mpstat -T d 1
1382026-10-02T05:07:23.463Z+ vmstat -T d -p 1
1392026-10-02T05:07:23.463Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1402026-10-02T05:07:23.463Z+ banner 512-memtest
1412026-10-02T05:07:23.463Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1422026-10-02T05:07:23.482Z####### # #####
1432026-10-02T05:07:23.482Z# ## # # # # ###### # # ##### ###### ####
1442026-10-02T05:07:23.482Z# # # # ## ## # ## ## # # #
1452026-10-02T05:07:23.482Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1462026-10-02T05:07:23.483Z # # # # # # # # # # #
1472026-10-02T05:07:23.483Z# # # # # # # # # # # # #
1482026-10-02T05:07:23.483Z ##### ##### ####### # # ###### # # # ###### ####
1492026-10-02T05:07:23.483Z
1502026-10-02T05:07:23.488Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1512026-10-02T05:07:23.488ZUsing block size 512
1522026-10-02T05:07:23.488ZUsing extent size 131072
1532026-10-02T05:07:23.488ZUsing extent count 160
1542026-10-02T05:07:23.488Z/input/rbuild/work
1552026-10-02T05:07:23.502ZMemory usage test begins at October 2, 2026 at 05:07:22 AM UTC
1562026-10-02T05:07:23.503ZMemory usage values in kilobytes unless specified otherwise
1572026-10-02T05:07:23.529ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1582026-10-02T05:07:47.223Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1592026-10-02T05:07:47.359Z 1157 113224 707 148868 930 97808 611 148868 930 160
1602026-10-02T05:07:47.454Z 1158 86868 542 122512 765 71532 447 122512 765 160
1612026-10-02T05:07:47.546Z 1159 97216 607 132852 830 81964 512 132852 830 160
1622026-10-02T05:07:47.549ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 394 MiB
1632026-10-02T05:07:47.565ZSize of volume user gets : 10737418240
1642026-10-02T05:07:47.565ZSize on disk of all region dirs: 74726433 or 35.6G
1652026-10-02T05:07:47.565ZSize on disk of a single region: 24908811 or 11.9G
1662026-10-02T05:07:47.568Z Total Overage with 512 block size: 0.70%
1672026-10-02T05:07:47.571ZRegion Overage with 512 block size: 0.23%
1682026-10-02T05:07:48.579Z
1692026-10-02T05:07:48.590ZMemory usage test finished on October 2, 2026 at 05:07:47 AM UTC
1702026-10-02T05:07:48.590Z
1712026-10-02T05:07:48.590Zreal 25.104616871
1722026-10-02T05:07:48.591Zuser 35.503538554
1732026-10-02T05:07:48.591Zsys 26.646301070
1742026-10-02T05:07:48.591Ztrap 0.350908706
1752026-10-02T05:07:48.591Ztflt 0.001680548
1762026-10-02T05:07:48.591Zdflt 0.026386158
1772026-10-02T05:07:48.591Zkflt 0.007115073
1782026-10-02T05:07:48.591Zlock 45:35.451559415
1792026-10-02T05:07:48.591Zslp 1:45.767248497
1802026-10-02T05:07:48.591Zlat 53.541915850
1812026-10-02T05:07:48.591Zstop 0.005152848
1822026-10-02T05:07:48.591Z+ banner 4k-memtest
1832026-10-02T05:07:48.591Z#
1842026-10-02T05:07:48.591Z# # # # # # ###### # # ##### ###### #### #####
1852026-10-02T05:07:48.591Z# # # # ## ## # ## ## # # # #
1862026-10-02T05:07:48.591Z# # #### ##### # ## # ##### # ## # # ##### #### #
1872026-10-02T05:07:48.591Z####### # # # # # # # # # # #
1882026-10-02T05:07:48.591Z # # # # # # # # # # # # #
1892026-10-02T05:07:48.591Z # # # # # ###### # # # ###### #### #
1902026-10-02T05:07:48.592Z
1912026-10-02T05:07:48.592Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1922026-10-02T05:07:48.595ZUsing block size 4096
1932026-10-02T05:07:48.595ZUsing extent size 16384
1942026-10-02T05:07:48.595ZUsing extent count 160
1952026-10-02T05:07:48.595Z/input/rbuild/work
1962026-10-02T05:07:48.602ZMemory usage test begins at October 2, 2026 at 05:07:47 AM UTC
1972026-10-02T05:07:48.602ZMemory usage values in kilobytes unless specified otherwise
1982026-10-02T05:07:48.615ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1992026-10-02T05:08:10.745Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2002026-10-02T05:08:10.799Z 1323 32996 206 67296 420 16512 103 67296 420 160
2012026-10-02T05:08:10.860Z 1325 33056 206 67368 421 16576 103 67368 421 160
2022026-10-02T05:08:10.921Z 1324 36088 225 70400 440 19660 122 70400 440 160
2032026-10-02T05:08:10.925ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 200 MiB
2042026-10-02T05:08:10.939ZSize of volume user gets : 10737418240
2052026-10-02T05:08:10.939ZSize on disk of all region dirs: 64391073 or 30.7G
2062026-10-02T05:08:10.939ZSize on disk of a single region: 21463691 or 10.2G
2072026-10-02T05:08:10.957Z Total Overage with 4096 block size: 0.60%
2082026-10-02T05:08:10.957ZRegion Overage with 4096 block size: 0.20%
2092026-10-02T05:08:11.954Z
2102026-10-02T05:08:11.966ZMemory usage test finished on October 2, 2026 at 05:08:10 AM UTC
2112026-10-02T05:08:11.968Z
2122026-10-02T05:08:11.969Zreal 23.365826160
2132026-10-02T05:08:11.969Zuser 16.307016471
2142026-10-02T05:08:11.969Zsys 25.001797356
2152026-10-02T05:08:11.970Ztrap 0.104368790
2162026-10-02T05:08:11.970Ztflt 0.000445647
2172026-10-02T05:08:11.970Zdflt 0.001854575
2182026-10-02T05:08:11.970Zkflt 0.000020954
2192026-10-02T05:08:11.970Zlock 39:51.223426047
2202026-10-02T05:08:11.970Zslp 1:51.815899854
2212026-10-02T05:08:11.970Zlat 16.373640910
2222026-10-02T05:08:11.970Zstop 0.004512822
2232026-10-02T05:08:16.960Zprocess exited: duration 49741 ms, exit code 0
2242026-10-02T05:08:16.960Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2252026-10-02T05:09:16.992Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2262026-10-02T05:09:16.992Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2272026-10-02T05:09:16.999Zfound 12 output files
2282026-10-02T05:09:16.999Zuploading: /tmp/test_mem_log.txt (2641507 bytes)
2292026-10-02T05:09:18.065Zuploaded: /tmp/test_mem_log.txt
2302026-10-02T05:09:18.065Zuploading: /tmp/debug/df.txt (1270 bytes)
2312026-10-02T05:09:19.074Zuploaded: /tmp/debug/df.txt
2322026-10-02T05:09:19.074Zuploading: /tmp/debug/iostat.txt (34294 bytes)
2332026-10-02T05:09:19.081Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 34294 -> 34894
2342026-10-02T05:09:20.095Zuploaded: /tmp/debug/iostat.txt
2352026-10-02T05:09:20.095Zuploading: /tmp/debug/mpstat.txt (83522 bytes)
2362026-10-02T05:09:20.108Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 83522 -> 85709
2372026-10-02T05:09:21.123Zuploaded: /tmp/debug/mpstat.txt
2382026-10-02T05:09:21.123Zuploading: /tmp/debug/paging.txt (15006 bytes)
2392026-10-02T05:09:21.130Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15006 -> 15466
2402026-10-02T05:09:22.143Zuploaded: /tmp/debug/paging.txt
2412026-10-02T05:09:22.143Zuploading: /tmp/debug/perf.txt (52176 bytes)
2422026-10-02T05:09:23.159Zuploaded: /tmp/debug/perf.txt
2432026-10-02T05:09:23.164Zuploading: /tmp/debug/prstat.txt (155889 bytes)
2442026-10-02T05:09:23.175Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 155889 -> 163654
2452026-10-02T05:09:24.183Zuploaded: /tmp/debug/prstat.txt
2462026-10-02T05:09:24.183Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2472026-10-02T05:09:25.192Zuploaded: /tmp/debug/psrinfo.txt
2482026-10-02T05:09:25.195Zuploading: /tmp/debug/upinfo.txt (4284 bytes)
2492026-10-02T05:09:26.202Zuploaded: /tmp/debug/upinfo.txt
2502026-10-02T05:09:26.203Zuploading: /tmp/dsc/downstairs-8810.txt (8912 bytes)
2512026-10-02T05:09:27.213Zuploaded: /tmp/dsc/downstairs-8810.txt
2522026-10-02T05:09:27.218Zuploading: /tmp/dsc/downstairs-8820.txt (7738 bytes)
2532026-10-02T05:09:28.223Zuploaded: /tmp/dsc/downstairs-8820.txt
2542026-10-02T05:09:28.223Zuploading: /tmp/dsc/downstairs-8830.txt (7736 bytes)
2552026-10-02T05:09:29.235Zuploaded: /tmp/dsc/downstairs-8830.txt