01KHF8389MVB0BBT4MDJ7TXDV6: 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: 01KHF83MGM65YDXQVJRTJGGEEK

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-02-14T23:59:44.171Zjob dependencies complete; ready to run (waiting for 24 m 51 s)
22026-02-15T00:00:10.268Zjob assigned to worker 01KHF9H6HVZXKFXJVT3Y923MCB [factory gimlet-EVT22200007-propolis, EVT22200007/58210] (queued for 26 s)
32026-02-15T00:00:19.486Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-02-15T00:00:19.510Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-02-15T00:00:19.510Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-02-15T00:00:25.177Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-02-15T00:00:25.178Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-02-15T00:00:25.226Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-02-15T00:00:25.226Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-02-15T00:00:26.460Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-02-15T00:00:26.460Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-02-15T00:00:26.506Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-02-15T00:00:26.506Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-02-15T00:00:27.428Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-02-15T00:00:27.428Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-02-15T00:00:27.473Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-02-15T00:00:27.473Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-02-15T00:00:29.462Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-02-15T00:00:29.462Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-02-15T00:00:30.515Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-02-15T00:00:30.516Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-02-15T00:00:31.549Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-02-15T00:00:31.549Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-02-15T00:00:32.530Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-02-15T00:00:32.530Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-02-15T00:00:33.584Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-02-15T00:00:33.584Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-02-15T00:00:34.126Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-02-15T00:00:34.151Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-02-15T00:00:34.184Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-02-15T00:00:34.184Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-02-15T00:00:34.248Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-02-15T00:00:34.249Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-02-15T00:00:34.310Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-02-15T00:00:34.311Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-02-15T00:00:34.395Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-02-15T00:00:34.419Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-02-15T00:00:36.835Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-02-15T00:00:36.835Zstarting task 0: "setup"
402026-02-15T00:00:36.860Z++ uname -s
412026-02-15T00:00:36.860Z+ kern=SunOS
422026-02-15T00:00:36.860Z+ build_user=build
432026-02-15T00:00:36.860Z+ build_uid=12345
442026-02-15T00:00:36.860Z+ work_dir=/work
452026-02-15T00:00:36.860Z+ input_dir=/input
462026-02-15T00:00:36.860Z+ [[ 0 == 12345 ]]
472026-02-15T00:00:36.860Z+ case "$kern" in
482026-02-15T00:00:36.860Z+ groupadd -g 12345 build
492026-02-15T00:00:36.860Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-02-15T00:00:38.861Z+ zfs create -o mountpoint=/work rpool/work
512026-02-15T00:00:38.901Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-02-15T00:00:38.928Z+ home_fs=zfs
532026-02-15T00:00:38.928Z+ [[ zfs == autofs ]]
542026-02-15T00:00:38.928Z+ mkdir -p /home/build
552026-02-15T00:00:38.928Z+ chown build:build /home/build /work
562026-02-15T00:00:39.911Z+ chmod 0700 /home/build /work
572026-02-15T00:00:39.935Zprocess exited: duration 3079 ms, exit code 0
 
582026-02-15T00:00:39.987Zstarting task 1: "authentication"
592026-02-15T00:00:40.035Zprocess exited: duration 49 ms, exit code 0
 
602026-02-15T00:00:40.083Zstarting task 2: "build"
612026-02-15T00:00:40.107Z+ banner cores
622026-02-15T00:00:40.107Z
632026-02-15T00:00:40.107Z #### #### ##### ###### ####
642026-02-15T00:00:40.107Z # # # # # # # #
652026-02-15T00:00:40.107Z # # # # # ##### ####
662026-02-15T00:00:40.107Z # # # ##### # #
672026-02-15T00:00:40.107Z # # # # # # # # #
682026-02-15T00:00:40.107Z #### #### # # ###### ####
692026-02-15T00:00:40.107Z
702026-02-15T00:00:40.107Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
712026-02-15T00:00:40.107Z+ banner unpack
722026-02-15T00:00:40.107Z
732026-02-15T00:00:40.107Z # # # # ##### ## #### # #
742026-02-15T00:00:40.107Z # # ## # # # # # # # # #
752026-02-15T00:00:40.107Z # # # # # # # # # # ####
762026-02-15T00:00:40.107Z # # # # # ##### ###### # # #
772026-02-15T00:00:40.107Z # # # ## # # # # # # #
782026-02-15T00:00:40.107Z #### # # # # # #### # #
792026-02-15T00:00:40.107Z
802026-02-15T00:00:40.107Z+ mkdir -p /var/tmp/bins
812026-02-15T00:00:40.131Z+ for t in "$input/rbins/"*.gz
822026-02-15T00:00:40.131Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
832026-02-15T00:00:40.131Z+ b=crucible-downstairs.gz
842026-02-15T00:00:40.131Z+ b=crucible-downstairs
852026-02-15T00:00:40.131Z+ gunzip
862026-02-15T00:00:40.636Z+ chmod +x /var/tmp/bins/crucible-downstairs
872026-02-15T00:00:40.660Z+ for t in "$input/rbins/"*.gz
882026-02-15T00:00:40.660Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
892026-02-15T00:00:40.660Z+ b=crucible-hammer.gz
902026-02-15T00:00:40.660Z+ b=crucible-hammer
912026-02-15T00:00:40.660Z+ gunzip
922026-02-15T00:00:41.153Z+ chmod +x /var/tmp/bins/crucible-hammer
932026-02-15T00:00:41.177Z+ for t in "$input/rbins/"*.gz
942026-02-15T00:00:41.177Z++ basename /input/rbuild/work/rbins/crudd.gz
952026-02-15T00:00:41.177Z+ b=crudd.gz
962026-02-15T00:00:41.177Z+ b=crudd
972026-02-15T00:00:41.177Z+ gunzip
982026-02-15T00:00:41.648Z+ chmod +x /var/tmp/bins/crudd
992026-02-15T00:00:41.672Z+ for t in "$input/rbins/"*.gz
1002026-02-15T00:00:41.672Z++ basename /input/rbuild/work/rbins/crutest.gz
1012026-02-15T00:00:41.672Z+ b=crutest.gz
1022026-02-15T00:00:41.672Z+ b=crutest
1032026-02-15T00:00:41.672Z+ gunzip
1042026-02-15T00:00:42.192Z+ chmod +x /var/tmp/bins/crutest
1052026-02-15T00:00:42.216Z+ for t in "$input/rbins/"*.gz
1062026-02-15T00:00:42.216Z++ basename /input/rbuild/work/rbins/dsc.gz
1072026-02-15T00:00:42.216Z+ b=dsc.gz
1082026-02-15T00:00:42.216Z+ b=dsc
1092026-02-15T00:00:42.216Z+ gunzip
1102026-02-15T00:00:42.431Z+ chmod +x /var/tmp/bins/dsc
1112026-02-15T00:00:42.455Z+ export BINDIR=/var/tmp/bins
1122026-02-15T00:00:42.455Z+ BINDIR=/var/tmp/bins
1132026-02-15T00:00:42.455Z+ export RUST_BACKTRACE=1
1142026-02-15T00:00:42.456Z+ RUST_BACKTRACE=1
1152026-02-15T00:00:42.456Z+ banner setup
1162026-02-15T00:00:42.456Z
1172026-02-15T00:00:42.456Z #### ###### ##### # # #####
1182026-02-15T00:00:42.456Z # # # # # # #
1192026-02-15T00:00:42.456Z #### ##### # # # # #
1202026-02-15T00:00:42.456Z # # # # # #####
1212026-02-15T00:00:42.456Z # # # # # # #
1222026-02-15T00:00:42.456Z #### ###### # #### #
1232026-02-15T00:00:42.456Z
1242026-02-15T00:00:42.456Z+ pfexec plimit -n 9123456 1147
1252026-02-15T00:00:42.456Z+ echo 'Setup self timeout'
1262026-02-15T00:00:42.456ZSetup self timeout
1272026-02-15T00:00:42.456Z+ jobpid=1147
1282026-02-15T00:00:42.456Z+ echo 'Setup debug logging'
1292026-02-15T00:00:42.456ZSetup debug logging
1302026-02-15T00:00:42.456Z+ mkdir /tmp/debug
1312026-02-15T00:00:42.456Z+ sleep 3600
1322026-02-15T00:00:42.456Z+ psrinfo -v
1332026-02-15T00:00:42.456Z+ df -h
1342026-02-15T00:00:42.456Zdf: cannot statvfs /var/metadata/iso: Permission denied
1352026-02-15T00:00:42.480Z+ true
1362026-02-15T00:00:42.480Z+ prstat -d d -mLc 1
1372026-02-15T00:00:42.480Z+ iostat -T d -xn 1
1382026-02-15T00:00:42.480Z+ mpstat -T d 1
1392026-02-15T00:00:42.480Z+ vmstat -T d -p 1
1402026-02-15T00:00:42.480Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1412026-02-15T00:00:42.480Z+ banner 512-memtest
1422026-02-15T00:00:42.480Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1432026-02-15T00:00:42.480Z####### # #####
1442026-02-15T00:00:42.480Z# ## # # # # ###### # # ##### ###### ####
1452026-02-15T00:00:42.480Z# # # # ## ## # ## ## # # #
1462026-02-15T00:00:42.480Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1472026-02-15T00:00:42.481Z # # # # # # # # # # #
1482026-02-15T00:00:42.481Z# # # # # # # # # # # # #
1492026-02-15T00:00:42.481Z ##### ##### ####### # # ###### # # # ###### ####
1502026-02-15T00:00:42.481Z
1512026-02-15T00:00:42.481Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1522026-02-15T00:00:42.481ZUsing block size 512
1532026-02-15T00:00:42.481ZUsing extent size 131072
1542026-02-15T00:00:42.481ZUsing extent count 160
1552026-02-15T00:00:42.505Z/input/rbuild/work
1562026-02-15T00:00:42.505ZMemory usage test begins at February 15, 2026 at 12:00:41 AM UTC
1572026-02-15T00:00:42.506ZMemory usage values in kilobytes unless specified otherwise
1582026-02-15T00:00:42.530ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1592026-02-15T00:01:42.822Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1602026-02-15T00:01:43.019Z 1216 119332 745 161760 1011 98076 612 161760 1011 160
1612026-02-15T00:01:43.196Z 1218 119972 749 162360 1014 98700 616 162360 1014 160
1622026-02-15T00:01:43.417Z 1217 120264 751 162676 1016 98964 618 162676 1016 160
1632026-02-15T00:01:43.441ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 475 MiB
1642026-02-15T00:01:43.465ZSize of volume user gets : 10737418240
1652026-02-15T00:01:43.465ZSize on disk of all region dirs: 38259933392 or 35.6G
1662026-02-15T00:01:43.465ZSize on disk of a single region: 12753311129 or 11.9G
1672026-02-15T00:01:43.465Z Total Overage with 512 block size: 356.32%
1682026-02-15T00:01:43.465ZRegion Overage with 512 block size: 118.77%
1692026-02-15T00:01:44.525Z
1702026-02-15T00:01:44.552ZMemory usage test finished on February 15, 2026 at 12:01:43 AM UTC
1712026-02-15T00:01:44.552Z
1722026-02-15T00:01:44.552Zreal 1:02.063276892
1732026-02-15T00:01:44.552Zuser 1:41.764793353
1742026-02-15T00:01:44.552Zsys 1:05.247684419
1752026-02-15T00:01:44.552Ztrap 0.821055676
1762026-02-15T00:01:44.552Ztflt 0.004233728
1772026-02-15T00:01:44.552Zdflt 0.030675342
1782026-02-15T00:01:44.552Zkflt 0.000968603
1792026-02-15T00:01:44.552Zlock 1:55:39.558275491
1802026-02-15T00:01:44.552Zslp 4:08.477715315
1812026-02-15T00:01:44.552Zlat 1:58.946414293
1822026-02-15T00:01:44.552Zstop 0.017533309
1832026-02-15T00:01:44.552Z+ banner 4k-memtest
1842026-02-15T00:01:44.552Z#
1852026-02-15T00:01:44.552Z# # # # # # ###### # # ##### ###### #### #####
1862026-02-15T00:01:44.552Z# # # # ## ## # ## ## # # # #
1872026-02-15T00:01:44.552Z# # #### ##### # ## # ##### # ## # # ##### #### #
1882026-02-15T00:01:44.552Z####### # # # # # # # # # # #
1892026-02-15T00:01:44.552Z # # # # # # # # # # # # #
1902026-02-15T00:01:44.552Z # # # # # ###### # # # ###### #### #
1912026-02-15T00:01:44.552Z
1922026-02-15T00:01:44.552Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1932026-02-15T00:01:44.552ZUsing block size 4096
1942026-02-15T00:01:44.552ZUsing extent size 16384
1952026-02-15T00:01:44.552ZUsing extent count 160
1962026-02-15T00:01:44.576Z/input/rbuild/work
1972026-02-15T00:01:44.576ZMemory usage test begins at February 15, 2026 at 12:01:43 AM UTC
1982026-02-15T00:01:44.576ZMemory usage values in kilobytes unless specified otherwise
1992026-02-15T00:01:44.601ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
2002026-02-15T00:02:24.609Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2012026-02-15T00:02:24.807Z 1382 105176 657 146276 914 82684 516 146276 914 160
2022026-02-15T00:02:24.972Z 1384 97956 612 139052 869 75424 471 139052 869 160
2032026-02-15T00:02:25.184Z 1383 107080 669 148176 926 84612 528 148176 926 160
2042026-02-15T00:02:25.211ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 423 MiB
2052026-02-15T00:02:25.237ZSize of volume user gets : 10737418240
2062026-02-15T00:02:25.237ZSize on disk of all region dirs: 32968229075 or 30.7G
2072026-02-15T00:02:25.237ZSize on disk of a single region: 10989409690 or 10.2G
2082026-02-15T00:02:25.237Z Total Overage with 4096 block size: 307.04%
2092026-02-15T00:02:25.238ZRegion Overage with 4096 block size: 102.35%
2102026-02-15T00:02:26.295Z
2112026-02-15T00:02:26.321ZMemory usage test finished on February 15, 2026 at 12:02:25 AM UTC
2122026-02-15T00:02:26.321Z
2132026-02-15T00:02:26.321Zreal 41.762705992
2142026-02-15T00:02:26.321Zuser 39.006429520
2152026-02-15T00:02:26.321Zsys 57.864582644
2162026-02-15T00:02:26.321Ztrap 0.334148541
2172026-02-15T00:02:26.321Ztflt 0.001465178
2182026-02-15T00:02:26.322Zdflt 0.010700365
2192026-02-15T00:02:26.322Zkflt 0.000041111
2202026-02-15T00:02:26.322Zlock 1:12:45.742803437
2212026-02-15T00:02:26.322Zslp 3:07.121671241
2222026-02-15T00:02:26.322Zlat 1:11.272448462
2232026-02-15T00:02:26.322Zstop 0.019305596
2242026-02-15T00:02:31.311Zprocess exited: duration 106221 ms, exit code 0
2252026-02-15T00:02:31.311Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2262026-02-15T00:03:31.344Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2272026-02-15T00:03:31.344Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2282026-02-15T00:03:31.423Zfound 12 output files
2292026-02-15T00:03:31.424Zuploading: /tmp/test_mem_log.txt (2631122 bytes)
2302026-02-15T00:03:32.730Zuploaded: /tmp/test_mem_log.txt
2312026-02-15T00:03:32.730Zuploading: /tmp/debug/df.txt (1270 bytes)
2322026-02-15T00:03:33.828Zuploaded: /tmp/debug/df.txt
2332026-02-15T00:03:33.828Zuploading: /tmp/debug/iostat.txt (62527 bytes)
2342026-02-15T00:03:33.962Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 62527 -> 63634
2352026-02-15T00:03:35.032Zuploaded: /tmp/debug/iostat.txt
2362026-02-15T00:03:35.057Zuploading: /tmp/debug/mpstat.txt (123617 bytes)
2372026-02-15T00:03:35.189Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 123617 -> 126537
2382026-02-15T00:03:36.258Zuploaded: /tmp/debug/mpstat.txt
2392026-02-15T00:03:36.282Zuploading: /tmp/debug/paging.txt (22290 bytes)
2402026-02-15T00:03:36.369Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 22290 -> 23028
2412026-02-15T00:03:37.441Zuploaded: /tmp/debug/paging.txt
2422026-02-15T00:03:37.441Zuploading: /tmp/debug/perf.txt (86928 bytes)
2432026-02-15T00:03:38.620Zuploaded: /tmp/debug/perf.txt
2442026-02-15T00:03:38.620Zuploading: /tmp/debug/prstat.txt (248460 bytes)
2452026-02-15T00:03:38.801Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 248460 -> 257533
2462026-02-15T00:03:39.870Zuploaded: /tmp/debug/prstat.txt
2472026-02-15T00:03:39.894Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2482026-02-15T00:03:40.966Zuploaded: /tmp/debug/psrinfo.txt
2492026-02-15T00:03:40.966Zuploading: /tmp/debug/upinfo.txt (10948 bytes)
2502026-02-15T00:03:42.103Zuploaded: /tmp/debug/upinfo.txt
2512026-02-15T00:03:42.129Zuploading: /tmp/dsc/downstairs-8810.txt (8782 bytes)
2522026-02-15T00:03:43.218Zuploaded: /tmp/dsc/downstairs-8810.txt
2532026-02-15T00:03:43.243Zuploading: /tmp/dsc/downstairs-8820.txt (7617 bytes)
2542026-02-15T00:03:44.334Zuploaded: /tmp/dsc/downstairs-8820.txt
2552026-02-15T00:03:44.334Zuploading: /tmp/dsc/downstairs-8830.txt (7616 bytes)
2562026-02-15T00:03:45.449Zuploaded: /tmp/dsc/downstairs-8830.txt