01KP58JBQPT55W4AVYKHBX1SMT: 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: 01KP58JN8JG6FT7RQ2KXE90GV4

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-14T06:02:07.270Zjob dependencies complete; ready to run (waiting for 12 m 58 s)
22026-04-14T06:02:33.331Zjob assigned to worker 01KP599M6C1JHCE1BG3BTWAZEX [factory aws, i-0aff77aa75d3c47f3] (queued for 26 s)
32026-04-14T06:02:39.988Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-04-14T06:02:40.015Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-04-14T06:02:40.015Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-04-14T06:02:41.971Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-04-14T06:02:41.971Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-04-14T06:02:42.012Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-04-14T06:02:42.013Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-04-14T06:02:42.565Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-04-14T06:02:42.568Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-04-14T06:02:42.593Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-04-14T06:02:42.596Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-04-14T06:02:42.842Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-04-14T06:02:42.842Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-04-14T06:02:42.871Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-04-14T06:02:42.875Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-04-14T06:02:43.471Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-04-14T06:02:43.475Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-04-14T06:02:43.929Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-04-14T06:02:43.933Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-04-14T06:02:44.377Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-04-14T06:02:44.380Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-04-14T06:02:44.769Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-04-14T06:02:44.773Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-04-14T06:02:45.157Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-04-14T06:02:45.161Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-04-14T06:02:45.429Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-04-14T06:02:45.433Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-04-14T06:02:45.456Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-04-14T06:02:45.456Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-04-14T06:02:45.481Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-04-14T06:02:45.481Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-04-14T06:02:45.526Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-04-14T06:02:45.526Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-04-14T06:02:45.560Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-04-14T06:02:45.563Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-04-14T06:02:46.351Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-04-14T06:02:46.351Zstarting task 0: "setup"
402026-04-14T06:02:46.356Z++ uname -s
412026-04-14T06:02:46.360Z+ kern=SunOS
422026-04-14T06:02:46.360Z+ build_user=build
432026-04-14T06:02:46.360Z+ build_uid=12345
442026-04-14T06:02:46.360Z+ work_dir=/work
452026-04-14T06:02:46.360Z+ input_dir=/input
462026-04-14T06:02:46.360Z+ [[ 0 == 12345 ]]
472026-04-14T06:02:46.360Z+ case "$kern" in
482026-04-14T06:02:46.360Z+ groupadd -g 12345 build
492026-04-14T06:02:46.360Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-04-14T06:02:48.366Z+ zfs create -o mountpoint=/work rpool/work
512026-04-14T06:02:48.489Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-04-14T06:02:48.493Z+ home_fs=zfs
532026-04-14T06:02:48.493Z+ [[ zfs == autofs ]]
542026-04-14T06:02:48.493Z+ mkdir -p /home/build
552026-04-14T06:02:48.493Z+ chown build:build /home/build /work
562026-04-14T06:02:50.495Z+ chmod 0700 /home/build /work
572026-04-14T06:02:50.498Zprocess exited: duration 4145 ms, exit code 0
 
582026-04-14T06:02:50.505Zstarting task 1: "authentication"
592026-04-14T06:02:50.521Zprocess exited: duration 15 ms, exit code 0
 
602026-04-14T06:02:50.527Zstarting task 2: "build"
612026-04-14T06:02:50.531Z+ banner cores
622026-04-14T06:02:50.534Z
632026-04-14T06:02:50.534Z #### #### ##### ###### ####
642026-04-14T06:02:50.534Z # # # # # # # #
652026-04-14T06:02:50.534Z # # # # # ##### ####
662026-04-14T06:02:50.534Z # # # ##### # #
672026-04-14T06:02:50.534Z # # # # # # # # #
682026-04-14T06:02:50.534Z #### #### # # ###### ####
692026-04-14T06:02:50.534Z
702026-04-14T06:02:50.534Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
712026-04-14T06:02:50.538Z+ banner unpack
722026-04-14T06:02:50.541Z
732026-04-14T06:02:50.542Z # # # # ##### ## #### # #
742026-04-14T06:02:50.542Z # # ## # # # # # # # # #
752026-04-14T06:02:50.542Z # # # # # # # # # # ####
762026-04-14T06:02:50.542Z # # # # # ##### ###### # # #
772026-04-14T06:02:50.542Z # # # ## # # # # # # #
782026-04-14T06:02:50.542Z #### # # # # # #### # #
792026-04-14T06:02:50.542Z
802026-04-14T06:02:50.542Z+ mkdir -p /var/tmp/bins
812026-04-14T06:02:50.542Z+ for t in "$input/rbins/"*.gz
822026-04-14T06:02:50.542Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
832026-04-14T06:02:50.545Z+ b=crucible-downstairs.gz
842026-04-14T06:02:50.545Z+ b=crucible-downstairs
852026-04-14T06:02:50.545Z+ gunzip
862026-04-14T06:02:50.887Z+ chmod +x /var/tmp/bins/crucible-downstairs
872026-04-14T06:02:50.891Z+ for t in "$input/rbins/"*.gz
882026-04-14T06:02:50.891Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
892026-04-14T06:02:50.891Z+ b=crucible-hammer.gz
902026-04-14T06:02:50.891Z+ b=crucible-hammer
912026-04-14T06:02:50.891Z+ gunzip
922026-04-14T06:02:51.214Z+ chmod +x /var/tmp/bins/crucible-hammer
932026-04-14T06:02:51.217Z+ for t in "$input/rbins/"*.gz
942026-04-14T06:02:51.217Z++ basename /input/rbuild/work/rbins/crudd.gz
952026-04-14T06:02:51.218Z+ b=crudd.gz
962026-04-14T06:02:51.218Z+ b=crudd
972026-04-14T06:02:51.218Z+ gunzip
982026-04-14T06:02:51.535Z+ chmod +x /var/tmp/bins/crudd
992026-04-14T06:02:51.539Z+ for t in "$input/rbins/"*.gz
1002026-04-14T06:02:51.539Z++ basename /input/rbuild/work/rbins/crutest.gz
1012026-04-14T06:02:51.539Z+ b=crutest.gz
1022026-04-14T06:02:51.539Z+ b=crutest
1032026-04-14T06:02:51.539Z+ gunzip
1042026-04-14T06:02:51.886Z+ chmod +x /var/tmp/bins/crutest
1052026-04-14T06:02:51.890Z+ for t in "$input/rbins/"*.gz
1062026-04-14T06:02:51.890Z++ basename /input/rbuild/work/rbins/dsc.gz
1072026-04-14T06:02:51.890Z+ b=dsc.gz
1082026-04-14T06:02:51.890Z+ b=dsc
1092026-04-14T06:02:51.890Z+ gunzip
1102026-04-14T06:02:52.034Z+ chmod +x /var/tmp/bins/dsc
1112026-04-14T06:02:52.038Z+ export BINDIR=/var/tmp/bins
1122026-04-14T06:02:52.038Z+ BINDIR=/var/tmp/bins
1132026-04-14T06:02:52.038Z+ export RUST_BACKTRACE=1
1142026-04-14T06:02:52.038Z+ RUST_BACKTRACE=1
1152026-04-14T06:02:52.038Z+ banner setup
1162026-04-14T06:02:52.038Z
1172026-04-14T06:02:52.038Z #### ###### ##### # # #####
1182026-04-14T06:02:52.038Z # # # # # # #
1192026-04-14T06:02:52.038Z #### ##### # # # # #
1202026-04-14T06:02:52.038Z # # # # # #####
1212026-04-14T06:02:52.038Z # # # # # # #
1222026-04-14T06:02:52.038Z #### ###### # #### #
1232026-04-14T06:02:52.038Z
1242026-04-14T06:02:52.038Z+ pfexec plimit -n 9123456 1090
1252026-04-14T06:02:52.042Z+ echo 'Setup self timeout'
1262026-04-14T06:02:52.042ZSetup self timeout
1272026-04-14T06:02:52.042Z+ jobpid=1090
1282026-04-14T06:02:52.042ZSetup debug logging
1292026-04-14T06:02:52.042Z+ echo 'Setup debug logging'
1302026-04-14T06:02:52.042Z+ mkdir /tmp/debug
1312026-04-14T06:02:52.042Z+ sleep 3600
1322026-04-14T06:02:52.046Z+ psrinfo -v
1332026-04-14T06:02:52.046Z+ df -h
1342026-04-14T06:02:52.050Z+ prstat -d d -mLc 1
1352026-04-14T06:02:52.053Z+ iostat -T d -xn 1
1362026-04-14T06:02:52.053Z+ mpstat -T d 1
1372026-04-14T06:02:52.053Z+ vmstat -T d -p 1
1382026-04-14T06:02:52.053Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1392026-04-14T06:02:52.053Z+ banner 512-memtest
1402026-04-14T06:02:52.053Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1412026-04-14T06:02:52.057Z####### # #####
1422026-04-14T06:02:52.057Z# ## # # # # ###### # # ##### ###### ####
1432026-04-14T06:02:52.057Z# # # # ## ## # ## ## # # #
1442026-04-14T06:02:52.057Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1452026-04-14T06:02:52.057Z # # # # # # # # # # #
1462026-04-14T06:02:52.057Z# # # # # # # # # # # # #
1472026-04-14T06:02:52.057Z ##### ##### ####### # # ###### # # # ###### ####
1482026-04-14T06:02:52.057Z
1492026-04-14T06:02:52.057Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1502026-04-14T06:02:52.060ZUsing block size 512
1512026-04-14T06:02:52.060ZUsing extent size 131072
1522026-04-14T06:02:52.060ZUsing extent count 160
1532026-04-14T06:02:52.064Z/input/rbuild/work
1542026-04-14T06:02:52.069ZMemory usage test begins at April 14, 2026 at 06:02:50 AM UTC
1552026-04-14T06:02:52.073ZMemory usage values in kilobytes unless specified otherwise
1562026-04-14T06:02:52.088ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1572026-04-14T06:03:20.948Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1582026-04-14T06:03:21.077Z 1160 119420 746 163532 1022 97340 608 163532 1022 160
1592026-04-14T06:03:21.185Z 1159 119488 746 164004 1025 96932 605 164004 1025 160
1602026-04-14T06:03:21.281Z 1161 101992 637 146032 912 79944 499 146032 912 160
1612026-04-14T06:03:21.285ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 462 MiB
1622026-04-14T06:03:21.300ZSize of volume user gets : 10737418240
1632026-04-14T06:03:21.300ZSize on disk of all region dirs: 74726433 or 35.6G
1642026-04-14T06:03:21.301ZSize on disk of a single region: 24908811 or 11.9G
1652026-04-14T06:03:21.307Z Total Overage with 512 block size: 0.70%
1662026-04-14T06:03:21.307ZRegion Overage with 512 block size: 0.23%
1672026-04-14T06:03:22.317Z
1682026-04-14T06:03:22.322ZMemory usage test finished on April 14, 2026 at 06:03:20 AM UTC
1692026-04-14T06:03:22.322Z
1702026-04-14T06:03:22.322Zreal 30.255070323
1712026-04-14T06:03:22.322Zuser 38.294108389
1722026-04-14T06:03:22.322Zsys 27.421675720
1732026-04-14T06:03:22.323Ztrap 0.471480593
1742026-04-14T06:03:22.323Ztflt 0.001144858
1752026-04-14T06:03:22.323Zdflt 0.040403592
1762026-04-14T06:03:22.323Zkflt 0.000845384
1772026-04-14T06:03:22.323Zlock 1:22:07.375342311
1782026-04-14T06:03:22.323Zslp 2:13.904404410
1792026-04-14T06:03:22.323Zlat 53.605339152
1802026-04-14T06:03:22.323Zstop 0.005146957
1812026-04-14T06:03:22.323Z+ banner 4k-memtest
1822026-04-14T06:03:22.323Z#
1832026-04-14T06:03:22.323Z# # # # # # ###### # # ##### ###### #### #####
1842026-04-14T06:03:22.323Z# # # # ## ## # ## ## # # # #
1852026-04-14T06:03:22.323Z# # #### ##### # ## # ##### # ## # # ##### #### #
1862026-04-14T06:03:22.323Z####### # # # # # # # # # # #
1872026-04-14T06:03:22.323Z # # # # # # # # # # # # #
1882026-04-14T06:03:22.324Z # # # # # ###### # # # ###### #### #
1892026-04-14T06:03:22.324Z
1902026-04-14T06:03:22.324Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1912026-04-14T06:03:22.330ZUsing block size 4096
1922026-04-14T06:03:22.330ZUsing extent size 16384
1932026-04-14T06:03:22.330ZUsing extent count 160
1942026-04-14T06:03:22.330Z/input/rbuild/work
1952026-04-14T06:03:22.335ZMemory usage test begins at April 14, 2026 at 06:03:20 AM UTC
1962026-04-14T06:03:22.335ZMemory usage values in kilobytes unless specified otherwise
1972026-04-14T06:03:22.351ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1982026-04-14T06:03:44.568Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1992026-04-14T06:03:44.638Z 1325 41788 261 84396 527 18756 117 84396 527 160
2002026-04-14T06:03:44.703Z 1326 43696 273 86304 539 20684 129 86304 539 160
2012026-04-14T06:03:44.770Z 1327 48816 305 91412 571 25824 161 91412 571 160
2022026-04-14T06:03:44.773ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 255 MiB
2032026-04-14T06:03:44.788ZSize of volume user gets : 10737418240
2042026-04-14T06:03:44.788ZSize on disk of all region dirs: 64391073 or 30.7G
2052026-04-14T06:03:44.788ZSize on disk of a single region: 21463691 or 10.2G
2062026-04-14T06:03:44.792Z Total Overage with 4096 block size: 0.60%
2072026-04-14T06:03:44.795ZRegion Overage with 4096 block size: 0.20%
2082026-04-14T06:03:45.801Z
2092026-04-14T06:03:45.805ZMemory usage test finished on April 14, 2026 at 06:03:44 AM UTC
2102026-04-14T06:03:45.808Z
2112026-04-14T06:03:45.808Zreal 23.480209078
2122026-04-14T06:03:45.808Zuser 16.712058318
2132026-04-14T06:03:45.808Zsys 25.152190330
2142026-04-14T06:03:45.808Ztrap 0.106302109
2152026-04-14T06:03:45.808Ztflt 0.000447939
2162026-04-14T06:03:45.808Zdflt 0.001981861
2172026-04-14T06:03:45.808Zkflt 0.000032269
2182026-04-14T06:03:45.808Zlock 40:03.301606990
2192026-04-14T06:03:45.808Zslp 1:52.083848306
2202026-04-14T06:03:45.808Zlat 16.300452160
2212026-04-14T06:03:45.808Zstop 0.004667845
2222026-04-14T06:03:50.809Zprocess exited: duration 55269 ms, exit code 0
2232026-04-14T06:03:50.809Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2242026-04-14T06:04:50.860Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2252026-04-14T06:04:50.860Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2262026-04-14T06:04:50.874Zfound 12 output files
2272026-04-14T06:04:50.874Zuploading: /tmp/test_mem_log.txt (2639893 bytes)
2282026-04-14T06:04:51.911Zuploaded: /tmp/test_mem_log.txt
2292026-04-14T06:04:51.911Zuploading: /tmp/debug/df.txt (1270 bytes)
2302026-04-14T06:04:52.924Zuploaded: /tmp/debug/df.txt
2312026-04-14T06:04:52.924Zuploading: /tmp/debug/iostat.txt (35558 bytes)
2322026-04-14T06:04:52.933Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 35558 -> 36154
2332026-04-14T06:04:53.942Zuploaded: /tmp/debug/iostat.txt
2342026-04-14T06:04:53.942Zuploading: /tmp/debug/mpstat.txt (86926 bytes)
2352026-04-14T06:04:53.953Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 86926 -> 89107
2362026-04-14T06:04:54.960Zuploaded: /tmp/debug/mpstat.txt
2372026-04-14T06:04:54.960Zuploading: /tmp/debug/paging.txt (15343 bytes)
2382026-04-14T06:04:54.967Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15343 -> 15953
2392026-04-14T06:04:55.974Zuploaded: /tmp/debug/paging.txt
2402026-04-14T06:04:55.975Zuploading: /tmp/debug/perf.txt (53073 bytes)
2412026-04-14T06:04:55.986Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 53073 -> 106017
2422026-04-14T06:04:56.994Zuploaded: /tmp/debug/perf.txt
2432026-04-14T06:04:56.994Zuploading: /tmp/debug/prstat.txt (162674 bytes)
2442026-04-14T06:04:57.006Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 162674 -> 170429
2452026-04-14T06:04:58.014Zuploaded: /tmp/debug/prstat.txt
2462026-04-14T06:04:58.014Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2472026-04-14T06:04:59.026Zuploaded: /tmp/debug/psrinfo.txt
2482026-04-14T06:04:59.026Zuploading: /tmp/debug/upinfo.txt (4760 bytes)
2492026-04-14T06:05:00.036Zuploaded: /tmp/debug/upinfo.txt
2502026-04-14T06:05:00.036Zuploading: /tmp/dsc/downstairs-8810.txt (8918 bytes)
2512026-04-14T06:05:01.048Zuploaded: /tmp/dsc/downstairs-8810.txt
2522026-04-14T06:05:01.048Zuploading: /tmp/dsc/downstairs-8820.txt (7738 bytes)
2532026-04-14T06:05:02.061Zuploaded: /tmp/dsc/downstairs-8820.txt
2542026-04-14T06:05:02.061Zuploading: /tmp/dsc/downstairs-8830.txt (7735 bytes)
2552026-04-14T06:05:03.073Zuploaded: /tmp/dsc/downstairs-8830.txt