01K9DBGZ8AJG6RZSC4VX8E8KYF: 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: 01K9DBHHZFD5MTA5W1DPPD5GFW

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12025-11-06T20:13:16.529Zjob dependencies complete; ready to run (waiting for 22 m 25 s)
22025-11-06T20:13:41.641Zjob assigned to worker 01K9DCTME8T8HP1BW2X50C55QP [factory gimlet-EVT22200007-propolis, EVT22200007/47667] (queued for 25 s)
32025-11-06T20:13:49.961Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42025-11-06T20:13:50.001Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52025-11-06T20:13:50.001Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62025-11-06T20:13:59.104Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72025-11-06T20:13:59.104Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82025-11-06T20:13:59.207Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92025-11-06T20:13:59.207Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102025-11-06T20:14:01.200Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112025-11-06T20:14:01.200Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122025-11-06T20:14:01.258Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132025-11-06T20:14:01.258Zdownloading input: /input/rbuild/out/crucible-utils.tar
142025-11-06T20:14:02.759Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152025-11-06T20:14:02.759Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162025-11-06T20:14:02.828Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172025-11-06T20:14:02.828Zdownloading input: /input/rbuild/out/crucible.tar.gz
182025-11-06T20:14:05.780Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192025-11-06T20:14:05.820Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202025-11-06T20:14:07.564Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212025-11-06T20:14:07.564Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222025-11-06T20:14:09.137Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232025-11-06T20:14:09.137Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242025-11-06T20:14:10.646Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252025-11-06T20:14:10.646Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262025-11-06T20:14:12.310Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272025-11-06T20:14:12.310Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282025-11-06T20:14:13.052Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292025-11-06T20:14:13.091Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302025-11-06T20:14:13.158Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312025-11-06T20:14:13.158Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322025-11-06T20:14:13.226Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332025-11-06T20:14:13.226Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342025-11-06T20:14:13.298Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352025-11-06T20:14:13.299Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362025-11-06T20:14:13.374Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372025-11-06T20:14:13.414Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382025-11-06T20:14:17.274Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392025-11-06T20:14:17.274Zstarting task 0: "setup"
402025-11-06T20:14:17.314Z++ uname -s
412025-11-06T20:14:17.314Z+ kern=SunOS
422025-11-06T20:14:17.314Z+ build_user=build
432025-11-06T20:14:17.314Z+ build_uid=12345
442025-11-06T20:14:17.314Z+ work_dir=/work
452025-11-06T20:14:17.314Z+ input_dir=/input
462025-11-06T20:14:17.314Z+ [[ 0 == 12345 ]]
472025-11-06T20:14:17.314Z+ case "$kern" in
482025-11-06T20:14:17.314Z+ groupadd -g 12345 build
492025-11-06T20:14:17.314Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502025-11-06T20:14:19.306Z+ zfs create -o mountpoint=/work rpool/work
512025-11-06T20:14:19.346Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522025-11-06T20:14:19.346Z+ home_fs=zfs
532025-11-06T20:14:19.346Z+ [[ zfs == autofs ]]
542025-11-06T20:14:19.346Z+ mkdir -p /home/build
552025-11-06T20:14:19.346Z+ chown build:build /home/build /work
562025-11-06T20:14:21.349Z+ chmod 0700 /home/build /work
572025-11-06T20:14:21.388Zprocess exited: duration 4078 ms, exit code 0
 
582025-11-06T20:14:21.467Zstarting task 1: "authentication"
592025-11-06T20:14:21.535Zprocess exited: duration 67 ms, exit code 0
 
602025-11-06T20:14:21.615Zstarting task 2: "build"
612025-11-06T20:14:21.655Z+ banner cores
622025-11-06T20:14:21.655Z
632025-11-06T20:14:21.655Z #### #### ##### ###### ####
642025-11-06T20:14:21.655Z # # # # # # # #
652025-11-06T20:14:21.655Z # # # # # ##### ####
662025-11-06T20:14:21.655Z # # # ##### # #
672025-11-06T20:14:21.655Z # # # # # # # # #
682025-11-06T20:14:21.655Z #### #### # # ###### ####
692025-11-06T20:14:21.656Z
702025-11-06T20:14:21.656Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
712025-11-06T20:14:21.656Z+ banner unpack
722025-11-06T20:14:21.656Z
732025-11-06T20:14:21.656Z # # # # ##### ## #### # #
742025-11-06T20:14:21.656Z # # ## # # # # # # # # #
752025-11-06T20:14:21.656Z # # # # # # # # # # ####
762025-11-06T20:14:21.656Z # # # # # ##### ###### # # #
772025-11-06T20:14:21.656Z # # # ## # # # # # # #
782025-11-06T20:14:21.656Z #### # # # # # #### # #
792025-11-06T20:14:21.656Z
802025-11-06T20:14:21.656Z+ mkdir -p /var/tmp/bins
812025-11-06T20:14:21.656Z+ for t in "$input/rbins/"*.gz
822025-11-06T20:14:21.656Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
832025-11-06T20:14:21.656Z+ b=crucible-downstairs.gz
842025-11-06T20:14:21.656Z+ b=crucible-downstairs
852025-11-06T20:14:21.656Z+ gunzip
862025-11-06T20:14:22.143Z+ chmod +x /var/tmp/bins/crucible-downstairs
872025-11-06T20:14:22.183Z+ for t in "$input/rbins/"*.gz
882025-11-06T20:14:22.183Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
892025-11-06T20:14:22.183Z+ b=crucible-hammer.gz
902025-11-06T20:14:22.183Z+ b=crucible-hammer
912025-11-06T20:14:22.183Z+ gunzip
922025-11-06T20:14:22.636Z+ chmod +x /var/tmp/bins/crucible-hammer
932025-11-06T20:14:22.675Z+ for t in "$input/rbins/"*.gz
942025-11-06T20:14:22.675Z++ basename /input/rbuild/work/rbins/crudd.gz
952025-11-06T20:14:22.675Z+ b=crudd.gz
962025-11-06T20:14:22.675Z+ b=crudd
972025-11-06T20:14:22.675Z+ gunzip
982025-11-06T20:14:23.116Z+ chmod +x /var/tmp/bins/crudd
992025-11-06T20:14:23.156Z+ for t in "$input/rbins/"*.gz
1002025-11-06T20:14:23.156Z++ basename /input/rbuild/work/rbins/crutest.gz
1012025-11-06T20:14:23.156Z+ b=crutest.gz
1022025-11-06T20:14:23.156Z+ b=crutest
1032025-11-06T20:14:23.156Z+ gunzip
1042025-11-06T20:14:23.637Z+ chmod +x /var/tmp/bins/crutest
1052025-11-06T20:14:23.677Z+ for t in "$input/rbins/"*.gz
1062025-11-06T20:14:23.677Z++ basename /input/rbuild/work/rbins/dsc.gz
1072025-11-06T20:14:23.677Z+ b=dsc.gz
1082025-11-06T20:14:23.677Z+ b=dsc
1092025-11-06T20:14:23.677Z+ gunzip
1102025-11-06T20:14:23.865Z+ chmod +x /var/tmp/bins/dsc
1112025-11-06T20:14:23.906Z+ export BINDIR=/var/tmp/bins
1122025-11-06T20:14:23.906Z+ BINDIR=/var/tmp/bins
1132025-11-06T20:14:23.906Z+ export RUST_BACKTRACE=1
1142025-11-06T20:14:23.906Z+ RUST_BACKTRACE=1
1152025-11-06T20:14:23.906Z+ banner setup
1162025-11-06T20:14:23.906Z
1172025-11-06T20:14:23.906Z #### ###### ##### # # #####
1182025-11-06T20:14:23.906Z # # # # # # #
1192025-11-06T20:14:23.906Z #### ##### # # # # #
1202025-11-06T20:14:23.906Z # # # # # #####
1212025-11-06T20:14:23.906Z # # # # # # #
1222025-11-06T20:14:23.906Z #### ###### # #### #
1232025-11-06T20:14:23.906Z
1242025-11-06T20:14:23.906Z+ pfexec plimit -n 9123456 1147
1252025-11-06T20:14:23.906Z+ echo 'Setup self timeout'
1262025-11-06T20:14:23.906ZSetup self timeout
1272025-11-06T20:14:23.906Z+ jobpid=1147
1282025-11-06T20:14:23.906Z+ echo 'Setup debug logging'
1292025-11-06T20:14:23.906ZSetup debug logging
1302025-11-06T20:14:23.906Z+ mkdir /tmp/debug
1312025-11-06T20:14:23.906Z+ sleep 3600
1322025-11-06T20:14:23.906Z+ psrinfo -v
1332025-11-06T20:14:23.906Z+ df -h
1342025-11-06T20:14:23.906Zdf: cannot statvfs /var/metadata/iso: Permission denied
1352025-11-06T20:14:23.906Z+ true
1362025-11-06T20:14:23.906Z+ prstat -d d -mLc 1
1372025-11-06T20:14:23.906Z+ iostat -T d -xn 1
1382025-11-06T20:14:23.907Z+ mpstat -T d 1
1392025-11-06T20:14:23.907Z+ vmstat -T d -p 1
1402025-11-06T20:14:23.907Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1412025-11-06T20:14:23.907Z+ banner 512-memtest
1422025-11-06T20:14:23.907Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1432025-11-06T20:14:23.907Z####### # #####
1442025-11-06T20:14:23.907Z# ## # # # # ###### # # ##### ###### ####
1452025-11-06T20:14:23.907Z# # # # ## ## # ## ## # # #
1462025-11-06T20:14:23.907Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1472025-11-06T20:14:23.907Z # # # # # # # # # # #
1482025-11-06T20:14:23.907Z# # # # # # # # # # # # #
1492025-11-06T20:14:23.907Z ##### ##### ####### # # ###### # # # ###### ####
1502025-11-06T20:14:23.907Z
1512025-11-06T20:14:23.907Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1522025-11-06T20:14:23.947ZUsing block size 512
1532025-11-06T20:14:23.947ZUsing extent size 131072
1542025-11-06T20:14:23.947ZUsing extent count 160
1552025-11-06T20:14:23.947Z/input/rbuild/work
1562025-11-06T20:14:23.947ZMemory usage test begins at November 6, 2025 at 08:14:23 PM UTC
1572025-11-06T20:14:23.947ZMemory usage values in kilobytes unless specified otherwise
1582025-11-06T20:14:23.987ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1592025-11-06T20:15:17.402Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1602025-11-06T20:15:17.619Z 1218 121148 757 162620 1016 100200 626 162620 1016 160
1612025-11-06T20:15:17.841Z 1216 119616 747 160952 1005 98968 618 160952 1005 160
1622025-11-06T20:15:18.020Z 1217 118428 740 159772 998 97824 611 159772 998 160
1632025-11-06T20:15:18.060ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 472 MiB
1642025-11-06T20:15:18.060ZSize of volume user gets : 10737418240
1652025-11-06T20:15:18.060ZSize on disk of all region dirs: 38259933392 or 35.6G
1662025-11-06T20:15:18.060ZSize on disk of a single region: 12753311129 or 11.9G
1672025-11-06T20:15:18.060Z Total Overage with 512 block size: 356.32%
1682025-11-06T20:15:18.100ZRegion Overage with 512 block size: 118.77%
1692025-11-06T20:15:19.126Z
1702025-11-06T20:15:19.166ZMemory usage test finished on November 6, 2025 at 08:15:18 PM UTC
1712025-11-06T20:15:19.166Z
1722025-11-06T20:15:19.166Zreal 55.226698940
1732025-11-06T20:15:19.166Zuser 1:23.445980304
1742025-11-06T20:15:19.166Zsys 1:04.647198676
1752025-11-06T20:15:19.166Ztrap 0.784076253
1762025-11-06T20:15:19.166Ztflt 0.009801073
1772025-11-06T20:15:19.166Zdflt 0.030326419
1782025-11-06T20:15:19.166Zkflt 0.001087428
1792025-11-06T20:15:19.166Zlock 1:50:38.214016731
1802025-11-06T20:15:19.166Zslp 3:46.062270096
1812025-11-06T20:15:19.166Zlat 1:58.006224948
1822025-11-06T20:15:19.166Zstop 0.237493629
1832025-11-06T20:15:19.166Z+ banner 4k-memtest
1842025-11-06T20:15:19.166Z#
1852025-11-06T20:15:19.166Z# # # # # # ###### # # ##### ###### #### #####
1862025-11-06T20:15:19.166Z# # # # ## ## # ## ## # # # #
1872025-11-06T20:15:19.166Z# # #### ##### # ## # ##### # ## # # ##### #### #
1882025-11-06T20:15:19.166Z####### # # # # # # # # # # #
1892025-11-06T20:15:19.167Z # # # # # # # # # # # # #
1902025-11-06T20:15:19.167Z # # # # # ###### # # # ###### #### #
1912025-11-06T20:15:19.167Z
1922025-11-06T20:15:19.167Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1932025-11-06T20:15:19.167ZUsing block size 4096
1942025-11-06T20:15:19.167ZUsing extent size 16384
1952025-11-06T20:15:19.167ZUsing extent count 160
1962025-11-06T20:15:19.167Z/input/rbuild/work
1972025-11-06T20:15:19.167ZMemory usage test begins at November 6, 2025 at 08:15:18 PM UTC
1982025-11-06T20:15:19.167ZMemory usage values in kilobytes unless specified otherwise
1992025-11-06T20:15:19.207ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
2002025-11-06T20:15:58.612Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2012025-11-06T20:15:58.761Z 1382 94200 588 134216 838 72340 452 134216 838 160
2022025-11-06T20:15:58.912Z 1384 107672 672 147684 923 85768 536 147684 923 160
2032025-11-06T20:15:59.046Z 1383 104516 653 144528 903 82684 516 144528 903 160
2042025-11-06T20:15:59.086ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 416 MiB
2052025-11-06T20:15:59.087ZSize of volume user gets : 10737418240
2062025-11-06T20:15:59.087ZSize on disk of all region dirs: 32968229075 or 30.7G
2072025-11-06T20:15:59.087ZSize on disk of a single region: 10989409690 or 10.2G
2082025-11-06T20:15:59.087Z Total Overage with 4096 block size: 307.04%
2092025-11-06T20:15:59.127ZRegion Overage with 4096 block size: 102.35%
2102025-11-06T20:16:00.151Z
2112025-11-06T20:16:00.191ZMemory usage test finished on November 6, 2025 at 08:15:59 PM UTC
2122025-11-06T20:16:00.191Z
2132025-11-06T20:16:00.191Zreal 41.018330759
2142025-11-06T20:16:00.191Zuser 38.306849042
2152025-11-06T20:16:00.191Zsys 56.805525317
2162025-11-06T20:16:00.191Ztrap 0.322924801
2172025-11-06T20:16:00.191Ztflt 0.001815604
2182025-11-06T20:16:00.191Zdflt 0.009162173
2192025-11-06T20:16:00.191Zkflt 0.000226455
2202025-11-06T20:16:00.191Zlock 1:11:14.109601274
2212025-11-06T20:16:00.191Zslp 3:04.470541474
2222025-11-06T20:16:00.191Zlat 1:09.309540066
2232025-11-06T20:16:00.191Zstop 0.018365671
2242025-11-06T20:16:05.166Zprocess exited: duration 98543 ms, exit code 0
2252025-11-06T20:16:05.166Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2262025-11-06T20:17:05.199Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2272025-11-06T20:17:05.199Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2282025-11-06T20:17:05.325Zfound 12 output files
2292025-11-06T20:17:05.325Zuploading: /tmp/test_mem_log.txt (2643136 bytes)
2302025-11-06T20:17:06.832Zuploaded: /tmp/test_mem_log.txt
2312025-11-06T20:17:06.832Zuploading: /tmp/debug/df.txt (1270 bytes)
2322025-11-06T20:17:07.987Zuploaded: /tmp/debug/df.txt
2332025-11-06T20:17:07.987Zuploading: /tmp/debug/iostat.txt (59946 bytes)
2342025-11-06T20:17:08.215Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 59946 -> 61053
2352025-11-06T20:17:09.333Zuploaded: /tmp/debug/iostat.txt
2362025-11-06T20:17:09.333Zuploading: /tmp/debug/mpstat.txt (118532 bytes)
2372025-11-06T20:17:09.599Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 118532 -> 121452
2382025-11-06T20:17:10.718Zuploaded: /tmp/debug/mpstat.txt
2392025-11-06T20:17:10.718Zuploading: /tmp/debug/paging.txt (21478 bytes)
2402025-11-06T20:17:10.870Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 21478 -> 22058
2412025-11-06T20:17:11.989Zuploaded: /tmp/debug/paging.txt
2422025-11-06T20:17:11.989Zuploading: /tmp/debug/perf.txt (90876 bytes)
2432025-11-06T20:17:13.295Zuploaded: /tmp/debug/perf.txt
2442025-11-06T20:17:13.295Zuploading: /tmp/debug/prstat.txt (235729 bytes)
2452025-11-06T20:17:13.601Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 235729 -> 246114
2462025-11-06T20:17:14.716Zuploaded: /tmp/debug/prstat.txt
2472025-11-06T20:17:14.716Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2482025-11-06T20:17:15.875Zuploaded: /tmp/debug/psrinfo.txt
2492025-11-06T20:17:15.875Zuploading: /tmp/debug/upinfo.txt (9877 bytes)
2502025-11-06T20:17:17.070Zuploaded: /tmp/debug/upinfo.txt
2512025-11-06T20:17:17.070Zuploading: /tmp/dsc/downstairs-8810.txt (8800 bytes)
2522025-11-06T20:17:18.264Zuploaded: /tmp/dsc/downstairs-8810.txt
2532025-11-06T20:17:18.265Zuploading: /tmp/dsc/downstairs-8820.txt (7634 bytes)
2542025-11-06T20:17:19.459Zuploaded: /tmp/dsc/downstairs-8820.txt
2552025-11-06T20:17:19.498Zuploading: /tmp/dsc/downstairs-8830.txt (7636 bytes)
2562025-11-06T20:17:20.654Zuploaded: /tmp/dsc/downstairs-8830.txt