01KN7FQQNQY7RGGSYK1GNNXAMC: 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: 01KN7FQYM4M404XJ8GJ7DA9HHV

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-02T16:30:02.448Zjob dependencies complete; ready to run (waiting for 12 m 52 s)
22026-04-02T16:30:24.541Zjob assigned to worker 01KN7GEKZXYK142H9J6FW698G2 [factory aws, i-083124d1d57d0ba78] (queued for 22 s)
32026-04-02T16:30:31.265Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-04-02T16:30:31.330Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-04-02T16:30:31.330Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-04-02T16:30:32.715Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-04-02T16:30:32.719Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-04-02T16:30:32.752Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-04-02T16:30:32.753Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-04-02T16:30:33.110Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-04-02T16:30:33.110Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-04-02T16:30:33.143Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-04-02T16:30:33.149Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-04-02T16:30:33.820Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-04-02T16:30:33.820Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-04-02T16:30:33.851Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-04-02T16:30:33.851Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-04-02T16:30:34.593Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-04-02T16:30:34.598Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-04-02T16:30:34.989Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-04-02T16:30:34.995Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-04-02T16:30:35.271Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-04-02T16:30:35.275Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-04-02T16:30:35.643Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-04-02T16:30:35.649Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-04-02T16:30:35.994Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-04-02T16:30:35.999Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-04-02T16:30:36.209Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-04-02T16:30:36.212Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-04-02T16:30:36.248Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-04-02T16:30:36.252Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-04-02T16:30:36.278Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-04-02T16:30:36.281Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-04-02T16:30:36.323Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-04-02T16:30:36.323Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-04-02T16:30:36.352Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-04-02T16:30:36.359Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-04-02T16:30:37.046Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-04-02T16:30:37.046Zstarting task 0: "setup"
402026-04-02T16:30:37.053Z++ uname -s
412026-04-02T16:30:37.058Z+ kern=SunOS
422026-04-02T16:30:37.058Z+ build_user=build
432026-04-02T16:30:37.058Z+ build_uid=12345
442026-04-02T16:30:37.058Z+ work_dir=/work
452026-04-02T16:30:37.058Z+ input_dir=/input
462026-04-02T16:30:37.058Z+ [[ 0 == 12345 ]]
472026-04-02T16:30:37.058Z+ case "$kern" in
482026-04-02T16:30:37.058Z+ groupadd -g 12345 build
492026-04-02T16:30:37.058Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-04-02T16:30:39.065Z+ zfs create -o mountpoint=/work rpool/work
512026-04-02T16:30:39.284Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-04-02T16:30:39.290Z+ home_fs=zfs
532026-04-02T16:30:39.290Z+ [[ zfs == autofs ]]
542026-04-02T16:30:39.290Z+ mkdir -p /home/build
552026-04-02T16:30:39.290Z+ chown build:build /home/build /work
562026-04-02T16:30:41.307Z+ chmod 0700 /home/build /work
572026-04-02T16:30:41.349Zprocess exited: duration 4244 ms, exit code 0
 
582026-04-02T16:30:41.378Zstarting task 1: "authentication"
592026-04-02T16:30:41.399Zprocess exited: duration 24 ms, exit code 0
 
602026-04-02T16:30:41.407Zstarting task 2: "build"
612026-04-02T16:30:41.423Z+ banner cores
622026-04-02T16:30:41.424Z
632026-04-02T16:30:41.424Z #### #### ##### ###### ####
642026-04-02T16:30:41.424Z # # # # # # # #
652026-04-02T16:30:41.424Z # # # # # ##### ####
662026-04-02T16:30:41.424Z # # # ##### # #
672026-04-02T16:30:41.424Z # # # # # # # # #
682026-04-02T16:30:41.424Z #### #### # # ###### ####
692026-04-02T16:30:41.424Z
702026-04-02T16:30:41.424Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
712026-04-02T16:30:41.424Z+ banner unpack
722026-04-02T16:30:41.424Z
732026-04-02T16:30:41.424Z # # # # ##### ## #### # #
742026-04-02T16:30:41.424Z # # ## # # # # # # # # #
752026-04-02T16:30:41.424Z # # # # # # # # # # ####
762026-04-02T16:30:41.424Z # # # # # ##### ###### # # #
772026-04-02T16:30:41.424Z # # # ## # # # # # # #
782026-04-02T16:30:41.424Z #### # # # # # #### # #
792026-04-02T16:30:41.424Z
802026-04-02T16:30:41.424Z+ mkdir -p /var/tmp/bins
812026-04-02T16:30:41.424Z+ for t in "$input/rbins/"*.gz
822026-04-02T16:30:41.424Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
832026-04-02T16:30:41.424Z+ b=crucible-downstairs.gz
842026-04-02T16:30:41.424Z+ b=crucible-downstairs
852026-04-02T16:30:41.424Z+ gunzip
862026-04-02T16:30:41.771Z+ chmod +x /var/tmp/bins/crucible-downstairs
872026-04-02T16:30:41.776Z+ for t in "$input/rbins/"*.gz
882026-04-02T16:30:41.776Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
892026-04-02T16:30:41.776Z+ b=crucible-hammer.gz
902026-04-02T16:30:41.776Z+ b=crucible-hammer
912026-04-02T16:30:41.776Z+ gunzip
922026-04-02T16:30:42.094Z+ chmod +x /var/tmp/bins/crucible-hammer
932026-04-02T16:30:42.104Z+ for t in "$input/rbins/"*.gz
942026-04-02T16:30:42.104Z++ basename /input/rbuild/work/rbins/crudd.gz
952026-04-02T16:30:42.104Z+ b=crudd.gz
962026-04-02T16:30:42.104Z+ b=crudd
972026-04-02T16:30:42.104Z+ gunzip
982026-04-02T16:30:42.406Z+ chmod +x /var/tmp/bins/crudd
992026-04-02T16:30:42.413Z+ for t in "$input/rbins/"*.gz
1002026-04-02T16:30:42.413Z++ basename /input/rbuild/work/rbins/crutest.gz
1012026-04-02T16:30:42.413Z+ b=crutest.gz
1022026-04-02T16:30:42.413Z+ b=crutest
1032026-04-02T16:30:42.413Z+ gunzip
1042026-04-02T16:30:42.775Z+ chmod +x /var/tmp/bins/crutest
1052026-04-02T16:30:42.780Z+ for t in "$input/rbins/"*.gz
1062026-04-02T16:30:42.780Z++ basename /input/rbuild/work/rbins/dsc.gz
1072026-04-02T16:30:42.780Z+ b=dsc.gz
1082026-04-02T16:30:42.780Z+ b=dsc
1092026-04-02T16:30:42.780Z+ gunzip
1102026-04-02T16:30:42.910Z+ chmod +x /var/tmp/bins/dsc
1112026-04-02T16:30:42.915Z+ export BINDIR=/var/tmp/bins
1122026-04-02T16:30:42.915Z+ BINDIR=/var/tmp/bins
1132026-04-02T16:30:42.915Z+ export RUST_BACKTRACE=1
1142026-04-02T16:30:42.915Z+ RUST_BACKTRACE=1
1152026-04-02T16:30:42.915Z+ banner setup
1162026-04-02T16:30:42.915Z
1172026-04-02T16:30:42.915Z #### ###### ##### # # #####
1182026-04-02T16:30:42.915Z # # # # # # #
1192026-04-02T16:30:42.915Z #### ##### # # # # #
1202026-04-02T16:30:42.915Z # # # # # #####
1212026-04-02T16:30:42.915Z # # # # # # #
1222026-04-02T16:30:42.915Z #### ###### # #### #
1232026-04-02T16:30:42.915Z
1242026-04-02T16:30:42.915Z+ pfexec plimit -n 9123456 1090
1252026-04-02T16:30:42.920Z+ echo 'Setup self timeout'
1262026-04-02T16:30:42.920ZSetup self timeout
1272026-04-02T16:30:42.920Z+ jobpid=1090
1282026-04-02T16:30:42.920ZSetup debug logging
1292026-04-02T16:30:42.920Z+ echo 'Setup debug logging'
1302026-04-02T16:30:42.920Z+ mkdir /tmp/debug
1312026-04-02T16:30:42.920Z+ sleep 3600
1322026-04-02T16:30:42.920Z+ psrinfo -v
1332026-04-02T16:30:42.923Z+ df -h
1342026-04-02T16:30:42.929Z+ prstat -d d -mLc 1
1352026-04-02T16:30:42.929Z+ iostat -T d -xn 1
1362026-04-02T16:30:42.929Z+ mpstat -T d 1
1372026-04-02T16:30:42.929Z+ vmstat -T d -p 1
1382026-04-02T16:30:42.929Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1392026-04-02T16:30:42.929Z+ banner 512-memtest
1402026-04-02T16:30:42.929Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1412026-04-02T16:30:42.929Z####### # #####
1422026-04-02T16:30:42.929Z# ## # # # # ###### # # ##### ###### ####
1432026-04-02T16:30:42.929Z# # # # ## ## # ## ## # # #
1442026-04-02T16:30:42.930Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1452026-04-02T16:30:42.930Z # # # # # # # # # # #
1462026-04-02T16:30:42.930Z# # # # # # # # # # # # #
1472026-04-02T16:30:42.930Z ##### ##### ####### # # ###### # # # ###### ####
1482026-04-02T16:30:42.930Z
1492026-04-02T16:30:42.930Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1502026-04-02T16:30:42.934ZUsing block size 512
1512026-04-02T16:30:42.934ZUsing extent size 131072
1522026-04-02T16:30:42.934ZUsing extent count 160
1532026-04-02T16:30:42.939Z/input/rbuild/work
1542026-04-02T16:30:42.946ZMemory usage test begins at April 2, 2026 at 04:30:41 PM UTC
1552026-04-02T16:30:42.949ZMemory usage values in kilobytes unless specified otherwise
1562026-04-02T16:30:42.964ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1572026-04-02T16:31:10.926Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1582026-04-02T16:31:11.047Z 1159 110316 689 154920 968 88576 553 154920 968 160
1592026-04-02T16:31:11.140Z 1160 96524 603 141100 881 74760 467 141100 881 160
1602026-04-02T16:31:11.244Z 1161 119256 745 163820 1023 97684 610 163820 1023 160
1612026-04-02T16:31:11.247ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 449 MiB
1622026-04-02T16:31:11.263ZSize of volume user gets : 10737418240
1632026-04-02T16:31:11.263ZSize on disk of all region dirs: 74726433 or 35.6G
1642026-04-02T16:31:11.263ZSize on disk of a single region: 24908811 or 11.9G
1652026-04-02T16:31:11.266Z Total Overage with 512 block size: 0.70%
1662026-04-02T16:31:11.271ZRegion Overage with 512 block size: 0.23%
1672026-04-02T16:31:12.277Z
1682026-04-02T16:31:12.281ZMemory usage test finished on April 2, 2026 at 04:31:10 PM UTC
1692026-04-02T16:31:12.284Z
1702026-04-02T16:31:12.284Zreal 29.348001079
1712026-04-02T16:31:12.284Zuser 36.352256406
1722026-04-02T16:31:12.284Zsys 26.770341103
1732026-04-02T16:31:12.284Ztrap 0.343525372
1742026-04-02T16:31:12.284Ztflt 0.001245139
1752026-04-02T16:31:12.284Zdflt 0.011632687
1762026-04-02T16:31:12.284Zkflt 0.001073484
1772026-04-02T16:31:12.284Zlock 54:29.560979600
1782026-04-02T16:31:12.284Zslp 2:10.297181615
1792026-04-02T16:31:12.284Zlat 52.320888740
1802026-04-02T16:31:12.284Zstop 0.004766156
1812026-04-02T16:31:12.284Z+ banner 4k-memtest
1822026-04-02T16:31:12.285Z#
1832026-04-02T16:31:12.285Z# # # # # # ###### # # ##### ###### #### #####
1842026-04-02T16:31:12.285Z# # # # ## ## # ## ## # # # #
1852026-04-02T16:31:12.285Z# # #### ##### # ## # ##### # ## # # ##### #### #
1862026-04-02T16:31:12.285Z####### # # # # # # # # # # #
1872026-04-02T16:31:12.285Z # # # # # # # # # # # # #
1882026-04-02T16:31:12.285Z # # # # # ###### # # # ###### #### #
1892026-04-02T16:31:12.285Z
1902026-04-02T16:31:12.285Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1912026-04-02T16:31:12.288ZUsing block size 4096
1922026-04-02T16:31:12.288ZUsing extent size 16384
1932026-04-02T16:31:12.288ZUsing extent count 160
1942026-04-02T16:31:12.293Z/input/rbuild/work
1952026-04-02T16:31:12.299ZMemory usage test begins at April 2, 2026 at 04:31:10 PM UTC
1962026-04-02T16:31:12.299ZMemory usage values in kilobytes unless specified otherwise
1972026-04-02T16:31:12.316ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1982026-04-02T16:31:34.534Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1992026-04-02T16:31:34.606Z 1327 45776 286 89024 556 22936 143 89024 556 160
2002026-04-02T16:31:34.671Z 1326 39416 246 82664 516 16576 103 82664 516 160
2012026-04-02T16:31:34.737Z 1325 40456 252 83704 523 17604 110 83704 523 160
2022026-04-02T16:31:34.741ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 249 MiB
2032026-04-02T16:31:34.755ZSize of volume user gets : 10737418240
2042026-04-02T16:31:34.755ZSize on disk of all region dirs: 64391073 or 30.7G
2052026-04-02T16:31:34.755ZSize on disk of a single region: 21463691 or 10.2G
2062026-04-02T16:31:34.759Z Total Overage with 4096 block size: 0.60%
2072026-04-02T16:31:34.762ZRegion Overage with 4096 block size: 0.20%
2082026-04-02T16:31:35.772Z
2092026-04-02T16:31:35.779ZMemory usage test finished on April 2, 2026 at 04:31:34 PM UTC
2102026-04-02T16:31:35.779Z
2112026-04-02T16:31:35.779Zreal 23.485805283
2122026-04-02T16:31:35.779Zuser 16.578031215
2132026-04-02T16:31:35.779Zsys 25.144659804
2142026-04-02T16:31:35.779Ztrap 0.096777839
2152026-04-02T16:31:35.779Ztflt 0.000524144
2162026-04-02T16:31:35.780Zdflt 0.002171069
2172026-04-02T16:31:35.780Zkflt 0.000021235
2182026-04-02T16:31:35.780Zlock 40:07.871501180
2192026-04-02T16:31:35.780Zslp 1:52.150018691
2202026-04-02T16:31:35.780Zlat 16.448180733
2212026-04-02T16:31:35.780Zstop 0.004724712
2222026-04-02T16:31:40.780Zprocess exited: duration 54363 ms, exit code 0
2232026-04-02T16:31:40.780Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2242026-04-02T16:32:40.785Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2252026-04-02T16:32:40.785Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2262026-04-02T16:32:40.793Zfound 12 output files
2272026-04-02T16:32:40.793Zuploading: /tmp/test_mem_log.txt (2647375 bytes)
2282026-04-02T16:32:41.832Zuploaded: /tmp/test_mem_log.txt
2292026-04-02T16:32:41.836Zuploading: /tmp/debug/df.txt (1270 bytes)
2302026-04-02T16:32:42.847Zuploaded: /tmp/debug/df.txt
2312026-04-02T16:32:42.847Zuploading: /tmp/debug/iostat.txt (35260 bytes)
2322026-04-02T16:32:42.854Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 35260 -> 35856
2332026-04-02T16:32:43.863Zuploaded: /tmp/debug/iostat.txt
2342026-04-02T16:32:43.864Zuploading: /tmp/debug/mpstat.txt (86201 bytes)
2352026-04-02T16:32:43.871Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 86201 -> 88382
2362026-04-02T16:32:44.881Zuploaded: /tmp/debug/mpstat.txt
2372026-04-02T16:32:44.881Zuploading: /tmp/debug/paging.txt (15230 bytes)
2382026-04-02T16:32:44.886Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15230 -> 15840
2392026-04-02T16:32:45.895Zuploaded: /tmp/debug/paging.txt
2402026-04-02T16:32:45.903Zuploading: /tmp/debug/perf.txt (53625 bytes)
2412026-04-02T16:32:45.909Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 53625 -> 107121
2422026-04-02T16:32:46.917Zuploaded: /tmp/debug/perf.txt
2432026-04-02T16:32:46.917Zuploading: /tmp/debug/prstat.txt (163190 bytes)
2442026-04-02T16:32:46.925Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 163190 -> 170967
2452026-04-02T16:32:47.931Zuploaded: /tmp/debug/prstat.txt
2462026-04-02T16:32:47.931Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2472026-04-02T16:32:48.940Zuploaded: /tmp/debug/psrinfo.txt
2482026-04-02T16:32:48.940Zuploading: /tmp/debug/upinfo.txt (4760 bytes)
2492026-04-02T16:32:49.949Zuploaded: /tmp/debug/upinfo.txt
2502026-04-02T16:32:49.950Zuploading: /tmp/dsc/downstairs-8810.txt (8919 bytes)
2512026-04-02T16:32:50.960Zuploaded: /tmp/dsc/downstairs-8810.txt
2522026-04-02T16:32:50.960Zuploading: /tmp/dsc/downstairs-8820.txt (7738 bytes)
2532026-04-02T16:32:51.969Zuploaded: /tmp/dsc/downstairs-8820.txt
2542026-04-02T16:32:51.969Zuploading: /tmp/dsc/downstairs-8830.txt (7742 bytes)
2552026-04-02T16:32:52.978Zuploaded: /tmp/dsc/downstairs-8830.txt