01KX1R4XK7NHQ97K18EJTPSN2X: 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: 01KX1R5Q4DZ5R9P7WPC0ZC47KD

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-07-08T21:07:50.898Zjob dependencies complete; ready to run (waiting for 13 m 13 s)
22026-07-08T21:07:53.924Zjob assigned to worker 01KX1RWQQDFZNNMBMZHT88TSDD [factory aws, i-0a95a89fd7ff1c3f0] (queued for 3 s)
32026-07-08T21:08:01.092Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-07-08T21:08:01.179Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-07-08T21:08:01.179Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-07-08T21:08:02.554Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-07-08T21:08:02.559Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-07-08T21:08:02.593Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-07-08T21:08:02.603Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-07-08T21:08:03.139Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-07-08T21:08:03.144Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-07-08T21:08:03.169Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-07-08T21:08:03.169Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-07-08T21:08:03.498Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-07-08T21:08:03.502Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-07-08T21:08:03.527Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-07-08T21:08:03.527Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-07-08T21:08:04.076Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-07-08T21:08:04.082Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-07-08T21:08:04.489Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-07-08T21:08:04.489Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-07-08T21:08:04.960Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-07-08T21:08:04.974Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-07-08T21:08:05.421Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-07-08T21:08:05.426Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-07-08T21:08:05.883Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-07-08T21:08:05.902Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-07-08T21:08:06.188Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-07-08T21:08:06.197Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-07-08T21:08:06.224Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-07-08T21:08:06.238Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-07-08T21:08:06.266Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-07-08T21:08:06.266Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-07-08T21:08:06.311Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-07-08T21:08:06.312Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-07-08T21:08:06.356Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-07-08T21:08:06.371Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-07-08T21:08:07.345Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-07-08T21:08:07.345Zstarting task 0: "setup"
402026-07-08T21:08:07.374Z++ uname -s
412026-07-08T21:08:07.374Z+ kern=SunOS
422026-07-08T21:08:07.375Z+ build_user=build
432026-07-08T21:08:07.375Z+ build_uid=12345
442026-07-08T21:08:07.375Z+ work_dir=/work
452026-07-08T21:08:07.375Z+ input_dir=/input
462026-07-08T21:08:07.375Z+ [[ 0 == 12345 ]]
472026-07-08T21:08:07.375Z+ case "$kern" in
482026-07-08T21:08:07.375Z+ groupadd -g 12345 build
492026-07-08T21:08:07.375Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-07-08T21:08:09.385Z+ zfs create -o mountpoint=/work rpool/work
512026-07-08T21:08:09.569Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-07-08T21:08:09.590Z+ home_fs=zfs
532026-07-08T21:08:09.590Z+ [[ zfs == autofs ]]
542026-07-08T21:08:09.590Z+ mkdir -p /home/build
552026-07-08T21:08:09.590Z+ chown build:build /home/build /work
562026-07-08T21:08:11.573Z+ chmod 0700 /home/build /work
572026-07-08T21:08:11.576Zprocess exited: duration 4231 ms, exit code 0
 
582026-07-08T21:08:11.583Zstarting task 1: "authentication"
592026-07-08T21:08:11.599ZWARNING: job store has no value for "GITHUB_TOKEN"; waiting for a value...
602026-07-08T21:08:19.625Zprocess exited: duration 8038 ms, exit code 0
 
612026-07-08T21:08:19.632Zstarting task 2: "build"
622026-07-08T21:08:19.637Z+ banner cores
632026-07-08T21:08:19.640Z
642026-07-08T21:08:19.640Z #### #### ##### ###### ####
652026-07-08T21:08:19.640Z # # # # # # # #
662026-07-08T21:08:19.640Z # # # # # ##### ####
672026-07-08T21:08:19.640Z # # # ##### # #
682026-07-08T21:08:19.640Z # # # # # # # # #
692026-07-08T21:08:19.640Z #### #### # # ###### ####
702026-07-08T21:08:19.640Z
712026-07-08T21:08:19.640Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
722026-07-08T21:08:19.646Z+ banner unpack
732026-07-08T21:08:19.650Z
742026-07-08T21:08:19.650Z # # # # ##### ## #### # #
752026-07-08T21:08:19.650Z # # ## # # # # # # # # #
762026-07-08T21:08:19.650Z # # # # # # # # # # ####
772026-07-08T21:08:19.650Z # # # # # ##### ###### # # #
782026-07-08T21:08:19.650Z # # # ## # # # # # # #
792026-07-08T21:08:19.650Z #### # # # # # #### # #
802026-07-08T21:08:19.650Z
812026-07-08T21:08:19.650Z+ mkdir -p /var/tmp/bins
822026-07-08T21:08:19.650Z+ for t in "$input/rbins/"*.gz
832026-07-08T21:08:19.650Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
842026-07-08T21:08:19.650Z+ b=crucible-downstairs.gz
852026-07-08T21:08:19.650Z+ b=crucible-downstairs
862026-07-08T21:08:19.650Z+ gunzip
872026-07-08T21:08:19.932Z+ chmod +x /var/tmp/bins/crucible-downstairs
882026-07-08T21:08:19.935Z+ for t in "$input/rbins/"*.gz
892026-07-08T21:08:19.935Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
902026-07-08T21:08:19.938Z+ b=crucible-hammer.gz
912026-07-08T21:08:19.938Z+ b=crucible-hammer
922026-07-08T21:08:19.938Z+ gunzip
932026-07-08T21:08:20.197Z+ chmod +x /var/tmp/bins/crucible-hammer
942026-07-08T21:08:20.200Z+ for t in "$input/rbins/"*.gz
952026-07-08T21:08:20.200Z++ basename /input/rbuild/work/rbins/crudd.gz
962026-07-08T21:08:20.200Z+ b=crudd.gz
972026-07-08T21:08:20.200Z+ b=crudd
982026-07-08T21:08:20.200Z+ gunzip
992026-07-08T21:08:20.444Z+ chmod +x /var/tmp/bins/crudd
1002026-07-08T21:08:20.448Z+ for t in "$input/rbins/"*.gz
1012026-07-08T21:08:20.448Z++ basename /input/rbuild/work/rbins/crutest.gz
1022026-07-08T21:08:20.448Z+ b=crutest.gz
1032026-07-08T21:08:20.448Z+ b=crutest
1042026-07-08T21:08:20.448Z+ gunzip
1052026-07-08T21:08:20.725Z+ chmod +x /var/tmp/bins/crutest
1062026-07-08T21:08:20.729Z+ for t in "$input/rbins/"*.gz
1072026-07-08T21:08:20.729Z++ basename /input/rbuild/work/rbins/dsc.gz
1082026-07-08T21:08:20.729Z+ b=dsc.gz
1092026-07-08T21:08:20.729Z+ b=dsc
1102026-07-08T21:08:20.729Z+ gunzip
1112026-07-08T21:08:20.861Z+ chmod +x /var/tmp/bins/dsc
1122026-07-08T21:08:20.864Z+ export BINDIR=/var/tmp/bins
1132026-07-08T21:08:20.864Z+ BINDIR=/var/tmp/bins
1142026-07-08T21:08:20.864Z+ export RUST_BACKTRACE=1
1152026-07-08T21:08:20.864Z+ RUST_BACKTRACE=1
1162026-07-08T21:08:20.864Z+ banner setup
1172026-07-08T21:08:20.864Z
1182026-07-08T21:08:20.864Z #### ###### ##### # # #####
1192026-07-08T21:08:20.864Z # # # # # # #
1202026-07-08T21:08:20.865Z #### ##### # # # # #
1212026-07-08T21:08:20.865Z # # # # # #####
1222026-07-08T21:08:20.865Z # # # # # # #
1232026-07-08T21:08:20.865Z #### ###### # #### #
1242026-07-08T21:08:20.865Z
1252026-07-08T21:08:20.865Z+ pfexec plimit -n 9123456 1088
1262026-07-08T21:08:20.869Z+ echo 'Setup self timeout'
1272026-07-08T21:08:20.869ZSetup self timeout
1282026-07-08T21:08:20.869Z+ jobpid=1088
1292026-07-08T21:08:20.869Z+ echo 'Setup debug logging'
1302026-07-08T21:08:20.869ZSetup debug logging
1312026-07-08T21:08:20.869Z+ mkdir /tmp/debug
1322026-07-08T21:08:20.869Z+ sleep 3600
1332026-07-08T21:08:20.872Z+ psrinfo -v
1342026-07-08T21:08:20.872Z+ df -h
1352026-07-08T21:08:20.877Z+ prstat -d d -mLc 1
1362026-07-08T21:08:20.880Z+ iostat -T d -xn 1
1372026-07-08T21:08:20.880Z+ mpstat -T d 1
1382026-07-08T21:08:20.880Z+ vmstat -T d -p 1
1392026-07-08T21:08:20.880Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1402026-07-08T21:08:20.880Z+ banner 512-memtest
1412026-07-08T21:08:20.880Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1422026-07-08T21:08:20.884Z####### # #####
1432026-07-08T21:08:20.884Z# ## # # # # ###### # # ##### ###### ####
1442026-07-08T21:08:20.884Z# # # # ## ## # ## ## # # #
1452026-07-08T21:08:20.884Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1462026-07-08T21:08:20.884Z # # # # # # # # # # #
1472026-07-08T21:08:20.884Z# # # # # # # # # # # # #
1482026-07-08T21:08:20.884Z ##### ##### ####### # # ###### # # # ###### ####
1492026-07-08T21:08:20.884Z
1502026-07-08T21:08:20.884Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1512026-07-08T21:08:20.887ZUsing block size 512
1522026-07-08T21:08:20.887ZUsing extent size 131072
1532026-07-08T21:08:20.887ZUsing extent count 160
1542026-07-08T21:08:20.891Z/input/rbuild/work
1552026-07-08T21:08:20.897ZMemory usage test begins at July 8, 2026 at 09:08:20 PM UTC
1562026-07-08T21:08:20.901ZMemory usage values in kilobytes unless specified otherwise
1572026-07-08T21:08:20.917ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1582026-07-08T21:08:49.404Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1592026-07-08T21:08:49.505Z 1157 112572 703 148516 928 96960 606 148516 928 160
1602026-07-08T21:08:49.601Z 1158 102628 641 138392 864 87240 545 138392 864 160
1612026-07-08T21:08:49.694Z 1159 112876 705 148652 929 97612 610 148652 929 160
1622026-07-08T21:08:49.698ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 425 MiB
1632026-07-08T21:08:49.714ZSize of volume user gets : 10737418240
1642026-07-08T21:08:49.714ZSize on disk of all region dirs: 74726433 or 35.6G
1652026-07-08T21:08:49.714ZSize on disk of a single region: 24908811 or 11.9G
1662026-07-08T21:08:49.717Z Total Overage with 512 block size: 0.70%
1672026-07-08T21:08:49.720ZRegion Overage with 512 block size: 0.23%
1682026-07-08T21:08:50.727Z
1692026-07-08T21:08:50.732ZMemory usage test finished on July 8, 2026 at 09:08:49 PM UTC
1702026-07-08T21:08:50.732Z
1712026-07-08T21:08:50.732Zreal 29.844318706
1722026-07-08T21:08:50.732Zuser 37.241011706
1732026-07-08T21:08:50.732Zsys 27.210214777
1742026-07-08T21:08:50.732Ztrap 0.421055661
1752026-07-08T21:08:50.732Ztflt 0.001086261
1762026-07-08T21:08:50.732Zdflt 0.015583302
1772026-07-08T21:08:50.733Zkflt 0.012556668
1782026-07-08T21:08:50.733Zlock 59:35.760950700
1792026-07-08T21:08:50.733Zslp 2:11.922718266
1802026-07-08T21:08:50.733Zlat 52.230253939
1812026-07-08T21:08:50.733Zstop 0.004996217
1822026-07-08T21:08:50.733Z+ banner 4k-memtest
1832026-07-08T21:08:50.737Z#
1842026-07-08T21:08:50.737Z# # # # # # ###### # # ##### ###### #### #####
1852026-07-08T21:08:50.737Z# # # # ## ## # ## ## # # # #
1862026-07-08T21:08:50.737Z# # #### ##### # ## # ##### # ## # # ##### #### #
1872026-07-08T21:08:50.737Z####### # # # # # # # # # # #
1882026-07-08T21:08:50.737Z # # # # # # # # # # # # #
1892026-07-08T21:08:50.737Z # # # # # ###### # # # ###### #### #
1902026-07-08T21:08:50.737Z
1912026-07-08T21:08:50.737Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1922026-07-08T21:08:50.741ZUsing block size 4096
1932026-07-08T21:08:50.741ZUsing extent size 16384
1942026-07-08T21:08:50.741ZUsing extent count 160
1952026-07-08T21:08:50.744Z/input/rbuild/work
1962026-07-08T21:08:50.748ZMemory usage test begins at July 8, 2026 at 09:08:49 PM UTC
1972026-07-08T21:08:50.751ZMemory usage values in kilobytes unless specified otherwise
1982026-07-08T21:08:50.765ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1992026-07-08T21:09:13.054Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2002026-07-08T21:09:13.113Z 1323 29976 187 64424 402 13428 83 64424 402 160
2012026-07-08T21:09:13.173Z 1324 34228 213 68688 429 17732 110 68688 429 160
2022026-07-08T21:09:13.232Z 1325 32164 201 66624 416 15676 97 66624 416 160
2032026-07-08T21:09:13.266ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 195 MiB
2042026-07-08T21:09:13.273ZSize of volume user gets : 10737418240
2052026-07-08T21:09:13.273ZSize on disk of all region dirs: 64391073 or 30.7G
2062026-07-08T21:09:13.273ZSize on disk of a single region: 21463691 or 10.2G
2072026-07-08T21:09:13.273Z Total Overage with 4096 block size: 0.60%
2082026-07-08T21:09:13.273ZRegion Overage with 4096 block size: 0.20%
2092026-07-08T21:09:14.263Z
2102026-07-08T21:09:14.266ZMemory usage test finished on July 8, 2026 at 09:09:13 PM UTC
2112026-07-08T21:09:14.269Z
2122026-07-08T21:09:14.269Zreal 23.528659062
2132026-07-08T21:09:14.269Zuser 16.633503129
2142026-07-08T21:09:14.269Zsys 25.112834443
2152026-07-08T21:09:14.269Ztrap 0.106875280
2162026-07-08T21:09:14.269Ztflt 0.000456774
2172026-07-08T21:09:14.269Zdflt 0.001997941
2182026-07-08T21:09:14.269Zkflt 0.000038531
2192026-07-08T21:09:14.269Zlock 40:15.069245633
2202026-07-08T21:09:14.269Zslp 1:52.195447981
2212026-07-08T21:09:14.269Zlat 16.779456019
2222026-07-08T21:09:14.269Zstop 0.004816735
2232026-07-08T21:09:19.273Zprocess exited: duration 54629 ms, exit code 0
2242026-07-08T21:09:19.273Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2252026-07-08T21:10:19.284Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2262026-07-08T21:10:19.285Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2272026-07-08T21:10:19.297Zfound 12 output files
2282026-07-08T21:10:19.298Zuploading: /tmp/test_mem_log.txt (2651732 bytes)
2292026-07-08T21:10:20.334Zuploaded: /tmp/test_mem_log.txt
2302026-07-08T21:10:20.334Zuploading: /tmp/debug/df.txt (1270 bytes)
2312026-07-08T21:10:21.346Zuploaded: /tmp/debug/df.txt
2322026-07-08T21:10:21.346Zuploading: /tmp/debug/iostat.txt (35442 bytes)
2332026-07-08T21:10:21.353Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 35442 -> 36036
2342026-07-08T21:10:22.362Zuploaded: /tmp/debug/iostat.txt
2352026-07-08T21:10:22.362Zuploading: /tmp/debug/mpstat.txt (86802 bytes)
2362026-07-08T21:10:22.371Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 86802 -> 88980
2372026-07-08T21:10:23.382Zuploaded: /tmp/debug/mpstat.txt
2382026-07-08T21:10:23.382Zuploading: /tmp/debug/paging.txt (15224 bytes)
2392026-07-08T21:10:23.391Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15224 -> 15830
2402026-07-08T21:10:24.401Zuploaded: /tmp/debug/paging.txt
2412026-07-08T21:10:24.401Zuploading: /tmp/debug/perf.txt (53142 bytes)
2422026-07-08T21:10:24.412Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 53142 -> 106155
2432026-07-08T21:10:25.421Zuploaded: /tmp/debug/perf.txt
2442026-07-08T21:10:25.421Zuploading: /tmp/debug/prstat.txt (162775 bytes)
2452026-07-08T21:10:25.431Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 162775 -> 170524
2462026-07-08T21:10:26.438Zuploaded: /tmp/debug/prstat.txt
2472026-07-08T21:10:26.438Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2482026-07-08T21:10:27.454Zuploaded: /tmp/debug/psrinfo.txt
2492026-07-08T21:10:27.454Zuploading: /tmp/debug/upinfo.txt (4760 bytes)
2502026-07-08T21:10:28.469Zuploaded: /tmp/debug/upinfo.txt
2512026-07-08T21:10:28.469Zuploading: /tmp/dsc/downstairs-8810.txt (8937 bytes)
2522026-07-08T21:10:29.507Zuploaded: /tmp/dsc/downstairs-8810.txt
2532026-07-08T21:10:29.507Zuploading: /tmp/dsc/downstairs-8820.txt (7761 bytes)
2542026-07-08T21:10:30.556Zuploaded: /tmp/dsc/downstairs-8820.txt
2552026-07-08T21:10:30.556Zuploading: /tmp/dsc/downstairs-8830.txt (7759 bytes)
2562026-07-08T21:10:31.581Zuploaded: /tmp/dsc/downstairs-8830.txt