01KPPCTNSQSPJ4M7AJYYCK3JDD: 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: 01KPPCV0149WG7FHTPVPZDHV4W

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-20T21:43:55.737Zjob dependencies complete; ready to run (waiting for 13 m 13 s)
22026-04-20T21:44:15.825Zjob assigned to worker 01KPPDJHC05ZQQBJ60CJNEQGFN [factory aws, i-0a86ff60698696ee8] (queued for 20 s)
32026-04-20T21:44:22.415Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-04-20T21:44:22.442Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-04-20T21:44:22.446Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-04-20T21:44:23.789Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-04-20T21:44:23.793Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-04-20T21:44:23.821Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-04-20T21:44:23.828Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-04-20T21:44:24.152Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-04-20T21:44:24.155Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-04-20T21:44:24.180Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-04-20T21:44:24.180Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-04-20T21:44:24.402Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-04-20T21:44:24.405Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-04-20T21:44:24.431Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-04-20T21:44:24.434Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-04-20T21:44:24.955Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-04-20T21:44:24.963Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-04-20T21:44:25.256Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-04-20T21:44:25.260Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-04-20T21:44:25.630Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-04-20T21:44:25.630Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-04-20T21:44:25.889Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-04-20T21:44:25.893Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-04-20T21:44:26.182Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-04-20T21:44:26.185Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-04-20T21:44:26.370Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-04-20T21:44:26.374Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-04-20T21:44:26.400Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-04-20T21:44:26.404Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-04-20T21:44:26.428Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-04-20T21:44:26.436Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-04-20T21:44:26.474Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-04-20T21:44:26.479Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-04-20T21:44:26.634Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-04-20T21:44:26.642Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-04-20T21:44:27.215Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-04-20T21:44:27.215Zstarting task 0: "setup"
402026-04-20T21:44:27.220Z++ uname -s
412026-04-20T21:44:27.223Z+ kern=SunOS
422026-04-20T21:44:27.223Z+ build_user=build
432026-04-20T21:44:27.223Z+ build_uid=12345
442026-04-20T21:44:27.223Z+ work_dir=/work
452026-04-20T21:44:27.223Z+ input_dir=/input
462026-04-20T21:44:27.223Z+ [[ 0 == 12345 ]]
472026-04-20T21:44:27.223Z+ case "$kern" in
482026-04-20T21:44:27.223Z+ groupadd -g 12345 build
492026-04-20T21:44:27.225Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-04-20T21:44:29.232Z+ zfs create -o mountpoint=/work rpool/work
512026-04-20T21:44:29.388Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-04-20T21:44:29.414Z+ home_fs=zfs
532026-04-20T21:44:29.414Z+ [[ zfs == autofs ]]
542026-04-20T21:44:29.414Z+ mkdir -p /home/build
552026-04-20T21:44:29.415Z+ chown build:build /home/build /work
562026-04-20T21:44:31.392Z+ chmod 0700 /home/build /work
572026-04-20T21:44:31.396Zprocess exited: duration 4179 ms, exit code 0
 
582026-04-20T21:44:31.404Zstarting task 1: "authentication"
592026-04-20T21:44:31.420Zprocess exited: duration 16 ms, exit code 0
 
602026-04-20T21:44:31.426Zstarting task 2: "build"
612026-04-20T21:44:31.430Z+ banner cores
622026-04-20T21:44:31.433Z
632026-04-20T21:44:31.433Z #### #### ##### ###### ####
642026-04-20T21:44:31.433Z # # # # # # # #
652026-04-20T21:44:31.433Z # # # # # ##### ####
662026-04-20T21:44:31.433Z # # # ##### # #
672026-04-20T21:44:31.433Z # # # # # # # # #
682026-04-20T21:44:31.433Z #### #### # # ###### ####
692026-04-20T21:44:31.433Z
702026-04-20T21:44:31.433Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
712026-04-20T21:44:31.438Z+ banner unpack
722026-04-20T21:44:31.441Z
732026-04-20T21:44:31.441Z # # # # ##### ## #### # #
742026-04-20T21:44:31.441Z # # ## # # # # # # # # #
752026-04-20T21:44:31.441Z # # # # # # # # # # ####
762026-04-20T21:44:31.441Z # # # # # ##### ###### # # #
772026-04-20T21:44:31.441Z # # # ## # # # # # # #
782026-04-20T21:44:31.441Z #### # # # # # #### # #
792026-04-20T21:44:31.441Z
802026-04-20T21:44:31.441Z+ mkdir -p /var/tmp/bins
812026-04-20T21:44:31.441Z+ for t in "$input/rbins/"*.gz
822026-04-20T21:44:31.444Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
832026-04-20T21:44:31.444Z+ b=crucible-downstairs.gz
842026-04-20T21:44:31.444Z+ b=crucible-downstairs
852026-04-20T21:44:31.444Z+ gunzip
862026-04-20T21:44:31.791Z+ chmod +x /var/tmp/bins/crucible-downstairs
872026-04-20T21:44:31.795Z+ for t in "$input/rbins/"*.gz
882026-04-20T21:44:31.795Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
892026-04-20T21:44:31.795Z+ b=crucible-hammer.gz
902026-04-20T21:44:31.795Z+ b=crucible-hammer
912026-04-20T21:44:31.795Z+ gunzip
922026-04-20T21:44:32.122Z+ chmod +x /var/tmp/bins/crucible-hammer
932026-04-20T21:44:32.129Z+ for t in "$input/rbins/"*.gz
942026-04-20T21:44:32.129Z++ basename /input/rbuild/work/rbins/crudd.gz
952026-04-20T21:44:32.129Z+ b=crudd.gz
962026-04-20T21:44:32.129Z+ b=crudd
972026-04-20T21:44:32.129Z+ gunzip
982026-04-20T21:44:32.446Z+ chmod +x /var/tmp/bins/crudd
992026-04-20T21:44:32.451Z+ for t in "$input/rbins/"*.gz
1002026-04-20T21:44:32.452Z++ basename /input/rbuild/work/rbins/crutest.gz
1012026-04-20T21:44:32.452Z+ b=crutest.gz
1022026-04-20T21:44:32.452Z+ b=crutest
1032026-04-20T21:44:32.452Z+ gunzip
1042026-04-20T21:44:32.801Z+ chmod +x /var/tmp/bins/crutest
1052026-04-20T21:44:32.806Z+ for t in "$input/rbins/"*.gz
1062026-04-20T21:44:32.806Z++ basename /input/rbuild/work/rbins/dsc.gz
1072026-04-20T21:44:32.806Z+ b=dsc.gz
1082026-04-20T21:44:32.806Z+ b=dsc
1092026-04-20T21:44:32.806Z+ gunzip
1102026-04-20T21:44:32.949Z+ chmod +x /var/tmp/bins/dsc
1112026-04-20T21:44:32.957Z+ export BINDIR=/var/tmp/bins
1122026-04-20T21:44:32.958Z+ BINDIR=/var/tmp/bins
1132026-04-20T21:44:32.958Z+ export RUST_BACKTRACE=1
1142026-04-20T21:44:32.958Z+ RUST_BACKTRACE=1
1152026-04-20T21:44:32.958Z+ banner setup
1162026-04-20T21:44:32.958Z
1172026-04-20T21:44:32.958Z #### ###### ##### # # #####
1182026-04-20T21:44:32.958Z # # # # # # #
1192026-04-20T21:44:32.958Z #### ##### # # # # #
1202026-04-20T21:44:32.958Z # # # # # #####
1212026-04-20T21:44:32.958Z # # # # # # #
1222026-04-20T21:44:32.958Z #### ###### # #### #
1232026-04-20T21:44:32.958Z
1242026-04-20T21:44:32.958Z+ pfexec plimit -n 9123456 1088
1252026-04-20T21:44:32.961Z+ echo 'Setup self timeout'
1262026-04-20T21:44:32.961ZSetup self timeout
1272026-04-20T21:44:32.962Z+ jobpid=1088
1282026-04-20T21:44:32.962Z+ echo 'Setup debug logging'
1292026-04-20T21:44:32.962ZSetup debug logging
1302026-04-20T21:44:32.962Z+ mkdir /tmp/debug
1312026-04-20T21:44:32.962Z+ sleep 3600
1322026-04-20T21:44:32.962Z+ psrinfo -v
1332026-04-20T21:44:32.962Z+ df -h
1342026-04-20T21:44:32.966Z+ prstat -d d -mLc 1
1352026-04-20T21:44:32.973Z+ iostat -T d -xn 1
1362026-04-20T21:44:32.973Z+ mpstat -T d 1
1372026-04-20T21:44:32.973Z+ vmstat -T d -p 1
1382026-04-20T21:44:32.973Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1392026-04-20T21:44:32.973Z+ banner 512-memtest
1402026-04-20T21:44:32.973Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1412026-04-20T21:44:32.973Z####### # #####
1422026-04-20T21:44:32.973Z# ## # # # # ###### # # ##### ###### ####
1432026-04-20T21:44:32.973Z# # # # ## ## # ## ## # # #
1442026-04-20T21:44:32.973Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1452026-04-20T21:44:32.973Z # # # # # # # # # # #
1462026-04-20T21:44:32.973Z# # # # # # # # # # # # #
1472026-04-20T21:44:32.973Z ##### ##### ####### # # ###### # # # ###### ####
1482026-04-20T21:44:32.973Z
1492026-04-20T21:44:32.974Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1502026-04-20T21:44:32.977ZUsing block size 512
1512026-04-20T21:44:32.977ZUsing extent size 131072
1522026-04-20T21:44:32.977ZUsing extent count 160
1532026-04-20T21:44:32.980Z/input/rbuild/work
1542026-04-20T21:44:32.989ZMemory usage test begins at April 20, 2026 at 09:44:31 PM UTC
1552026-04-20T21:44:32.993ZMemory usage values in kilobytes unless specified otherwise
1562026-04-20T21:44:33.011ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1572026-04-20T21:45:01.354Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1582026-04-20T21:45:01.465Z 1159 119180 744 163192 1019 97504 609 163192 1019 160
1592026-04-20T21:45:01.567Z 1157 119292 745 163292 1020 97628 610 163292 1020 160
1602026-04-20T21:45:01.665Z 1158 108100 675 152160 951 86216 538 152160 951 160
1612026-04-20T21:45:01.668ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 467 MiB
1622026-04-20T21:45:01.684ZSize of volume user gets : 10737418240
1632026-04-20T21:45:01.684ZSize on disk of all region dirs: 74726433 or 35.6G
1642026-04-20T21:45:01.684ZSize on disk of a single region: 24908811 or 11.9G
1652026-04-20T21:45:01.689Z Total Overage with 512 block size: 0.70%
1662026-04-20T21:45:01.692ZRegion Overage with 512 block size: 0.23%
1672026-04-20T21:45:02.699Z
1682026-04-20T21:45:02.706ZMemory usage test finished on April 20, 2026 at 09:45:01 PM UTC
1692026-04-20T21:45:02.706Z
1702026-04-20T21:45:02.706Zreal 29.726249279
1712026-04-20T21:45:02.706Zuser 37.171047375
1722026-04-20T21:45:02.706Zsys 27.460633418
1732026-04-20T21:45:02.706Ztrap 0.358810864
1742026-04-20T21:45:02.707Ztflt 0.001568303
1752026-04-20T21:45:02.707Zdflt 0.015521747
1762026-04-20T21:45:02.707Zkflt 0.001165369
1772026-04-20T21:45:02.707Zlock 55:35.075530472
1782026-04-20T21:45:02.707Zslp 2:11.442207305
1792026-04-20T21:45:02.707Zlat 53.925171965
1802026-04-20T21:45:02.707Zstop 0.004933654
1812026-04-20T21:45:02.707Z+ banner 4k-memtest
1822026-04-20T21:45:02.710Z#
1832026-04-20T21:45:02.711Z# # # # # # ###### # # ##### ###### #### #####
1842026-04-20T21:45:02.711Z# # # # ## ## # ## ## # # # #
1852026-04-20T21:45:02.711Z# # #### ##### # ## # ##### # ## # # ##### #### #
1862026-04-20T21:45:02.711Z####### # # # # # # # # # # #
1872026-04-20T21:45:02.711Z # # # # # # # # # # # # #
1882026-04-20T21:45:02.711Z # # # # # ###### # # # ###### #### #
1892026-04-20T21:45:02.711Z
1902026-04-20T21:45:02.711Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1912026-04-20T21:45:02.711ZUsing block size 4096
1922026-04-20T21:45:02.711ZUsing extent size 16384
1932026-04-20T21:45:02.711ZUsing extent count 160
1942026-04-20T21:45:02.715Z/input/rbuild/work
1952026-04-20T21:45:02.720ZMemory usage test begins at April 20, 2026 at 09:45:01 PM UTC
1962026-04-20T21:45:02.722ZMemory usage values in kilobytes unless specified otherwise
1972026-04-20T21:45:02.738ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1982026-04-20T21:45:25.124Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1992026-04-20T21:45:25.190Z 1324 41604 260 84292 526 18696 116 84292 526 160
2002026-04-20T21:45:25.255Z 1323 39548 247 82248 514 16640 104 82248 514 160
2012026-04-20T21:45:25.319Z 1325 39480 246 82176 513 16640 104 82176 513 160
2022026-04-20T21:45:25.322ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 242 MiB
2032026-04-20T21:45:25.336ZSize of volume user gets : 10737418240
2042026-04-20T21:45:25.336ZSize on disk of all region dirs: 64391073 or 30.7G
2052026-04-20T21:45:25.336ZSize on disk of a single region: 21463691 or 10.2G
2062026-04-20T21:45:25.339Z Total Overage with 4096 block size: 0.60%
2072026-04-20T21:45:25.342ZRegion Overage with 4096 block size: 0.20%
2082026-04-20T21:45:26.351Z
2092026-04-20T21:45:26.363ZMemory usage test finished on April 20, 2026 at 09:45:24 PM UTC
2102026-04-20T21:45:26.363Z
2112026-04-20T21:45:26.363Zreal 23.642934844
2122026-04-20T21:45:26.363Zuser 16.805414144
2132026-04-20T21:45:26.363Zsys 25.553855838
2142026-04-20T21:45:26.363Ztrap 0.115591736
2152026-04-20T21:45:26.363Ztflt 0.000674460
2162026-04-20T21:45:26.363Zdflt 0.002134422
2172026-04-20T21:45:26.363Zkflt 0.000001738
2182026-04-20T21:45:26.363Zlock 40:21.465234650
2192026-04-20T21:45:26.363Zslp 1:52.684293583
2202026-04-20T21:45:26.363Zlat 16.876386549
2212026-04-20T21:45:26.363Zstop 0.005336874
2222026-04-20T21:45:31.362Zprocess exited: duration 54919 ms, exit code 0
2232026-04-20T21:45:31.362Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2242026-04-20T21:46:31.373Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2252026-04-20T21:46:31.373Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2262026-04-20T21:46:31.385Zfound 12 output files
2272026-04-20T21:46:31.385Zuploading: /tmp/test_mem_log.txt (2639143 bytes)
2282026-04-20T21:46:32.414Zuploaded: /tmp/test_mem_log.txt
2292026-04-20T21:46:32.417Zuploading: /tmp/debug/df.txt (1270 bytes)
2302026-04-20T21:46:33.425Zuploaded: /tmp/debug/df.txt
2312026-04-20T21:46:33.425Zuploading: /tmp/debug/iostat.txt (35558 bytes)
2322026-04-20T21:46:33.431Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 35558 -> 36154
2332026-04-20T21:46:34.439Zuploaded: /tmp/debug/iostat.txt
2342026-04-20T21:46:34.439Zuploading: /tmp/debug/mpstat.txt (86928 bytes)
2352026-04-20T21:46:34.448Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 86928 -> 89109
2362026-04-20T21:46:35.455Zuploaded: /tmp/debug/mpstat.txt
2372026-04-20T21:46:35.455Zuploading: /tmp/debug/paging.txt (15343 bytes)
2382026-04-20T21:46:35.460Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15343 -> 15953
2392026-04-20T21:46:36.466Zuploaded: /tmp/debug/paging.txt
2402026-04-20T21:46:36.467Zuploading: /tmp/debug/perf.txt (52175 bytes)
2412026-04-20T21:46:36.475Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 52175 -> 104222
2422026-04-20T21:46:37.483Zuploaded: /tmp/debug/perf.txt
2432026-04-20T21:46:37.483Zuploading: /tmp/debug/prstat.txt (162995 bytes)
2442026-04-20T21:46:37.492Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 162995 -> 170755
2452026-04-20T21:46:38.500Zuploaded: /tmp/debug/prstat.txt
2462026-04-20T21:46:38.500Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2472026-04-20T21:46:39.509Zuploaded: /tmp/debug/psrinfo.txt
2482026-04-20T21:46:39.509Zuploading: /tmp/debug/upinfo.txt (4760 bytes)
2492026-04-20T21:46:40.518Zuploaded: /tmp/debug/upinfo.txt
2502026-04-20T21:46:40.518Zuploading: /tmp/dsc/downstairs-8810.txt (8898 bytes)
2512026-04-20T21:46:41.528Zuploaded: /tmp/dsc/downstairs-8810.txt
2522026-04-20T21:46:41.528Zuploading: /tmp/dsc/downstairs-8820.txt (7716 bytes)
2532026-04-20T21:46:42.539Zuploaded: /tmp/dsc/downstairs-8820.txt
2542026-04-20T21:46:42.539Zuploading: /tmp/dsc/downstairs-8830.txt (7716 bytes)
2552026-04-20T21:46:43.551Zuploaded: /tmp/dsc/downstairs-8830.txt