01KY5BDVNWVG6GECRCJZH9B9F5: 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: 01KY5BF068FA90BMB4SQE19RA5

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-07-22T16:59:14.119Zjob dependencies complete; ready to run (waiting for 14 m 4 s)
22026-07-22T16:59:21.159Zjob assigned to worker 01KY5C7R8NK1JD53HT0MF8SKH1 [factory aws, i-08019fc3c0c33e1ed] (queued for 7 s)
32026-07-22T16:59:28.114Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-07-22T16:59:28.130Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-07-22T16:59:28.130Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-07-22T16:59:29.676Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-07-22T16:59:29.695Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-07-22T16:59:29.711Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-07-22T16:59:29.711Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-07-22T16:59:30.119Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-07-22T16:59:30.133Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-07-22T16:59:30.149Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-07-22T16:59:30.149Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-07-22T16:59:30.385Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-07-22T16:59:30.385Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-07-22T16:59:30.402Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-07-22T16:59:30.402Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-07-22T16:59:30.922Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-07-22T16:59:30.938Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-07-22T16:59:31.382Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-07-22T16:59:31.383Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-07-22T16:59:31.731Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-07-22T16:59:31.752Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-07-22T16:59:32.184Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-07-22T16:59:32.201Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-07-22T16:59:32.586Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-07-22T16:59:32.622Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-07-22T16:59:32.724Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-07-22T16:59:32.730Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-07-22T16:59:32.730Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-07-22T16:59:32.730Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-07-22T16:59:32.738Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-07-22T16:59:32.738Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-07-22T16:59:32.742Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-07-22T16:59:32.743Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-07-22T16:59:32.761Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-07-22T16:59:32.761Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-07-22T16:59:33.286Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-07-22T16:59:33.286Zstarting task 0: "setup"
402026-07-22T16:59:33.314Z++ uname -s
412026-07-22T16:59:33.314Z+ kern=SunOS
422026-07-22T16:59:33.314Z+ build_user=build
432026-07-22T16:59:33.314Z+ build_uid=12345
442026-07-22T16:59:33.314Z+ work_dir=/work
452026-07-22T16:59:33.314Z+ input_dir=/input
462026-07-22T16:59:33.314Z+ [[ 0 == 12345 ]]
472026-07-22T16:59:33.314Z+ case "$kern" in
482026-07-22T16:59:33.314Z+ groupadd -g 12345 build
492026-07-22T16:59:33.314Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-07-22T16:59:35.296Z+ zfs create -o mountpoint=/work rpool/work
512026-07-22T16:59:35.476Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-07-22T16:59:35.482Z+ home_fs=zfs
532026-07-22T16:59:35.482Z+ [[ zfs == autofs ]]
542026-07-22T16:59:35.482Z+ mkdir -p /home/build
552026-07-22T16:59:35.482Z+ chown build:build /home/build /work
562026-07-22T16:59:37.484Z+ chmod 0700 /home/build /work
572026-07-22T16:59:37.489Zprocess exited: duration 4201 ms, exit code 0
 
582026-07-22T16:59:37.510Zstarting task 1: "authentication"
592026-07-22T16:59:37.529ZWARNING: job store has no value for "GITHUB_TOKEN"; waiting for a value...
602026-07-22T16:59:45.566Zprocess exited: duration 8052 ms, exit code 0
 
612026-07-22T16:59:45.578Zstarting task 2: "build"
622026-07-22T16:59:45.597Z+ banner cores
632026-07-22T16:59:45.601Z
642026-07-22T16:59:45.601Z #### #### ##### ###### ####
652026-07-22T16:59:45.601Z # # # # # # # #
662026-07-22T16:59:45.601Z # # # # # ##### ####
672026-07-22T16:59:45.601Z # # # ##### # #
682026-07-22T16:59:45.601Z # # # # # # # # #
692026-07-22T16:59:45.601Z #### #### # # ###### ####
702026-07-22T16:59:45.601Z
712026-07-22T16:59:45.601Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
722026-07-22T16:59:45.601Z+ banner unpack
732026-07-22T16:59:45.601Z
742026-07-22T16:59:45.602Z # # # # ##### ## #### # #
752026-07-22T16:59:45.602Z # # ## # # # # # # # # #
762026-07-22T16:59:45.602Z # # # # # # # # # # ####
772026-07-22T16:59:45.602Z # # # # # ##### ###### # # #
782026-07-22T16:59:45.602Z # # # ## # # # # # # #
792026-07-22T16:59:45.602Z #### # # # # # #### # #
802026-07-22T16:59:45.602Z
812026-07-22T16:59:45.602Z+ mkdir -p /var/tmp/bins
822026-07-22T16:59:45.602Z+ for t in "$input/rbins/"*.gz
832026-07-22T16:59:45.602Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
842026-07-22T16:59:45.602Z+ b=crucible-downstairs.gz
852026-07-22T16:59:45.602Z+ b=crucible-downstairs
862026-07-22T16:59:45.602Z+ gunzip
872026-07-22T16:59:45.881Z+ chmod +x /var/tmp/bins/crucible-downstairs
882026-07-22T16:59:45.891Z+ for t in "$input/rbins/"*.gz
892026-07-22T16:59:45.891Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
902026-07-22T16:59:45.891Z+ b=crucible-hammer.gz
912026-07-22T16:59:45.891Z+ b=crucible-hammer
922026-07-22T16:59:45.891Z+ gunzip
932026-07-22T16:59:46.138Z+ chmod +x /var/tmp/bins/crucible-hammer
942026-07-22T16:59:46.145Z+ for t in "$input/rbins/"*.gz
952026-07-22T16:59:46.145Z++ basename /input/rbuild/work/rbins/crudd.gz
962026-07-22T16:59:46.145Z+ b=crudd.gz
972026-07-22T16:59:46.145Z+ b=crudd
982026-07-22T16:59:46.145Z+ gunzip
992026-07-22T16:59:46.386Z+ chmod +x /var/tmp/bins/crudd
1002026-07-22T16:59:46.394Z+ for t in "$input/rbins/"*.gz
1012026-07-22T16:59:46.394Z++ basename /input/rbuild/work/rbins/crutest.gz
1022026-07-22T16:59:46.394Z+ b=crutest.gz
1032026-07-22T16:59:46.394Z+ b=crutest
1042026-07-22T16:59:46.394Z+ gunzip
1052026-07-22T16:59:46.669Z+ chmod +x /var/tmp/bins/crutest
1062026-07-22T16:59:46.673Z+ for t in "$input/rbins/"*.gz
1072026-07-22T16:59:46.673Z++ basename /input/rbuild/work/rbins/dsc.gz
1082026-07-22T16:59:46.674Z+ b=dsc.gz
1092026-07-22T16:59:46.674Z+ b=dsc
1102026-07-22T16:59:46.674Z+ gunzip
1112026-07-22T16:59:46.805Z+ chmod +x /var/tmp/bins/dsc
1122026-07-22T16:59:46.808Z+ export BINDIR=/var/tmp/bins
1132026-07-22T16:59:46.808Z+ BINDIR=/var/tmp/bins
1142026-07-22T16:59:46.809Z+ export RUST_BACKTRACE=1
1152026-07-22T16:59:46.809Z+ RUST_BACKTRACE=1
1162026-07-22T16:59:46.809Z+ banner setup
1172026-07-22T16:59:46.809Z
1182026-07-22T16:59:46.809Z #### ###### ##### # # #####
1192026-07-22T16:59:46.809Z # # # # # # #
1202026-07-22T16:59:46.809Z #### ##### # # # # #
1212026-07-22T16:59:46.809Z # # # # # #####
1222026-07-22T16:59:46.809Z # # # # # # #
1232026-07-22T16:59:46.809Z #### ###### # #### #
1242026-07-22T16:59:46.809Z
1252026-07-22T16:59:46.809Z+ pfexec plimit -n 9123456 1088
1262026-07-22T16:59:46.813Z+ echo 'Setup self timeout'
1272026-07-22T16:59:46.814ZSetup self timeout
1282026-07-22T16:59:46.814Z+ jobpid=1088
1292026-07-22T16:59:46.814ZSetup debug logging
1302026-07-22T16:59:46.814Z+ echo 'Setup debug logging'
1312026-07-22T16:59:46.814Z+ mkdir /tmp/debug
1322026-07-22T16:59:46.814Z+ sleep 3600
1332026-07-22T16:59:46.814Z+ psrinfo -v
1342026-07-22T16:59:46.818Z+ df -h
1352026-07-22T16:59:46.818Z+ prstat -d d -mLc 1
1362026-07-22T16:59:46.821Z+ iostat -T d -xn 1
1372026-07-22T16:59:46.821Z+ mpstat -T d 1
1382026-07-22T16:59:46.821Z+ vmstat -T d -p 1
1392026-07-22T16:59:46.821Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1402026-07-22T16:59:46.821Z+ banner 512-memtest
1412026-07-22T16:59:46.821Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1422026-07-22T16:59:46.825Z####### # #####
1432026-07-22T16:59:46.825Z# ## # # # # ###### # # ##### ###### ####
1442026-07-22T16:59:46.825Z# # # # ## ## # ## ## # # #
1452026-07-22T16:59:46.825Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1462026-07-22T16:59:46.825Z # # # # # # # # # # #
1472026-07-22T16:59:46.825Z# # # # # # # # # # # # #
1482026-07-22T16:59:46.825Z ##### ##### ####### # # ###### # # # ###### ####
1492026-07-22T16:59:46.825Z
1502026-07-22T16:59:46.825Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1512026-07-22T16:59:46.832ZUsing block size 512
1522026-07-22T16:59:46.832ZUsing extent size 131072
1532026-07-22T16:59:46.832ZUsing extent count 160
1542026-07-22T16:59:46.832Z/input/rbuild/work
1552026-07-22T16:59:46.841ZMemory usage test begins at July 22, 2026 at 04:59:45 PM UTC
1562026-07-22T16:59:46.867ZMemory usage values in kilobytes unless specified otherwise
1572026-07-22T16:59:46.872ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1582026-07-22T17:00:15.307Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1592026-07-22T17:00:15.425Z 1158 112776 704 148636 928 97604 610 148636 928 160
1602026-07-22T17:00:15.503Z 1159 104604 653 140524 878 89152 557 140524 878 160
1612026-07-22T17:00:15.604Z 1157 113008 706 149000 931 97472 609 149000 931 160
1622026-07-22T17:00:15.613ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 427 MiB
1632026-07-22T17:00:15.623ZSize of volume user gets : 10737418240
1642026-07-22T17:00:15.623ZSize on disk of all region dirs: 74726433 or 35.6G
1652026-07-22T17:00:15.623ZSize on disk of a single region: 24908811 or 11.9G
1662026-07-22T17:00:15.632Z Total Overage with 512 block size: 0.70%
1672026-07-22T17:00:15.632ZRegion Overage with 512 block size: 0.23%
1682026-07-22T17:00:16.637Z
1692026-07-22T17:00:16.647ZMemory usage test finished on July 22, 2026 at 05:00:15 PM UTC
1702026-07-22T17:00:16.648Z
1712026-07-22T17:00:16.648Zreal 29.813720367
1722026-07-22T17:00:16.648Zuser 37.707855690
1732026-07-22T17:00:16.648Zsys 26.853089571
1742026-07-22T17:00:16.648Ztrap 0.364187574
1752026-07-22T17:00:16.648Ztflt 0.000948316
1762026-07-22T17:00:16.648Zdflt 0.011643028
1772026-07-22T17:00:16.648Zkflt 0.001261015
1782026-07-22T17:00:16.648Zlock 59:25.114186224
1792026-07-22T17:00:16.648Zslp 2:12.579575219
1802026-07-22T17:00:16.648Zlat 54.586602415
1812026-07-22T17:00:16.648Zstop 0.004944546
1822026-07-22T17:00:16.648Z+ banner 4k-memtest
1832026-07-22T17:00:16.648Z#
1842026-07-22T17:00:16.648Z# # # # # # ###### # # ##### ###### #### #####
1852026-07-22T17:00:16.648Z# # # # ## ## # ## ## # # # #
1862026-07-22T17:00:16.648Z# # #### ##### # ## # ##### # ## # # ##### #### #
1872026-07-22T17:00:16.648Z####### # # # # # # # # # # #
1882026-07-22T17:00:16.648Z # # # # # # # # # # # # #
1892026-07-22T17:00:16.648Z # # # # # ###### # # # ###### #### #
1902026-07-22T17:00:16.648Z
1912026-07-22T17:00:16.648Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1922026-07-22T17:00:16.648ZUsing block size 4096
1932026-07-22T17:00:16.648ZUsing extent size 16384
1942026-07-22T17:00:16.649ZUsing extent count 160
1952026-07-22T17:00:16.663Z/input/rbuild/work
1962026-07-22T17:00:16.663ZMemory usage test begins at July 22, 2026 at 05:00:15 PM UTC
1972026-07-22T17:00:16.663ZMemory usage values in kilobytes unless specified otherwise
1982026-07-22T17:00:16.677ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1992026-07-22T17:00:38.931Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2002026-07-22T17:00:38.994Z 1325 33124 207 67672 422 16640 104 67672 422 160
2012026-07-22T17:00:39.054Z 1323 29892 186 64440 402 13492 84 64440 402 160
2022026-07-22T17:00:39.115Z 1324 34200 213 69772 436 18760 117 69772 436 160
2032026-07-22T17:00:39.118ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 197 MiB
2042026-07-22T17:00:39.132ZSize of volume user gets : 10737418240
2052026-07-22T17:00:39.132ZSize on disk of all region dirs: 64391073 or 30.7G
2062026-07-22T17:00:39.132ZSize on disk of a single region: 21463691 or 10.2G
2072026-07-22T17:00:39.137Z Total Overage with 4096 block size: 0.60%
2082026-07-22T17:00:39.140ZRegion Overage with 4096 block size: 0.20%
2092026-07-22T17:00:40.144Z
2102026-07-22T17:00:40.147ZMemory usage test finished on July 22, 2026 at 05:00:38 PM UTC
2112026-07-22T17:00:40.148Z
2122026-07-22T17:00:40.151Zreal 23.502902978
2132026-07-22T17:00:40.151Zuser 16.476644213
2142026-07-22T17:00:40.151Zsys 24.945756672
2152026-07-22T17:00:40.151Ztrap 0.103760266
2162026-07-22T17:00:40.151Ztflt 0.000379295
2172026-07-22T17:00:40.151Zdflt 0.002258200
2182026-07-22T17:00:40.151Zkflt 0.000018918
2192026-07-22T17:00:40.151Zlock 40:21.420704747
2202026-07-22T17:00:40.151Zslp 1:52.046722984
2212026-07-22T17:00:40.151Zlat 16.385296308
2222026-07-22T17:00:40.151Zstop 0.004379414
2232026-07-22T17:00:45.150Zprocess exited: duration 54567 ms, exit code 0
2242026-07-22T17:00:45.150Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2252026-07-22T17:01:45.177Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2262026-07-22T17:01:45.177Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2272026-07-22T17:01:45.191Zfound 12 output files
2282026-07-22T17:01:45.191Zuploading: /tmp/test_mem_log.txt (2644771 bytes)
2292026-07-22T17:01:46.227Zuploaded: /tmp/test_mem_log.txt
2302026-07-22T17:01:46.227Zuploading: /tmp/debug/df.txt (1270 bytes)
2312026-07-22T17:01:47.236Zuploaded: /tmp/debug/df.txt
2322026-07-22T17:01:47.236Zuploading: /tmp/debug/iostat.txt (35443 bytes)
2332026-07-22T17:01:47.241Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 35443 -> 36037
2342026-07-22T17:01:48.249Zuploaded: /tmp/debug/iostat.txt
2352026-07-22T17:01:48.250Zuploading: /tmp/debug/mpstat.txt (86799 bytes)
2362026-07-22T17:01:48.258Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 86799 -> 88977
2372026-07-22T17:01:49.266Zuploaded: /tmp/debug/mpstat.txt
2382026-07-22T17:01:49.267Zuploading: /tmp/debug/paging.txt (15224 bytes)
2392026-07-22T17:01:49.272Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15224 -> 15830
2402026-07-22T17:01:50.280Zuploaded: /tmp/debug/paging.txt
2412026-07-22T17:01:50.280Zuploading: /tmp/debug/perf.txt (52589 bytes)
2422026-07-22T17:01:50.286Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 52589 -> 105050
2432026-07-22T17:01:51.297Zuploaded: /tmp/debug/perf.txt
2442026-07-22T17:01:51.297Zuploading: /tmp/debug/prstat.txt (162753 bytes)
2452026-07-22T17:01:51.303Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 162753 -> 170524
2462026-07-22T17:01:52.313Zuploaded: /tmp/debug/prstat.txt
2472026-07-22T17:01:52.313Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2482026-07-22T17:01:53.340Zuploaded: /tmp/debug/psrinfo.txt
2492026-07-22T17:01:53.340Zuploading: /tmp/debug/upinfo.txt (4760 bytes)
2502026-07-22T17:01:54.350Zuploaded: /tmp/debug/upinfo.txt
2512026-07-22T17:01:54.350Zuploading: /tmp/dsc/downstairs-8810.txt (8892 bytes)
2522026-07-22T17:01:55.362Zuploaded: /tmp/dsc/downstairs-8810.txt
2532026-07-22T17:01:55.362Zuploading: /tmp/dsc/downstairs-8820.txt (7717 bytes)
2542026-07-22T17:01:56.372Zuploaded: /tmp/dsc/downstairs-8820.txt
2552026-07-22T17:01:56.372Zuploading: /tmp/dsc/downstairs-8830.txt (7717 bytes)
2562026-07-22T17:01:57.382Zuploaded: /tmp/dsc/downstairs-8830.txt