01K7M84X83DD39MBTNC1KHMMZ5: 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: 01K7M85G1ABRQ5JP7M5CR3NZRQ

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12025-10-15T15:58:45.162Zjob dependencies complete; ready to run (waiting for 23 m 38 s)
22025-10-15T15:59:11.335Zjob assigned to worker 01K7M9GSZZ9PZC1T61JR2EXNJW [factory gimlet-EVT22200007-propolis, EVT22200007/45194] (queued for 26 s)
32025-10-15T15:59:20.867Zdownloading input: /input/rbuild/out/crucible-dtrace.sha256.txt
42025-10-15T15:59:20.899Zdownloaded input: /input/rbuild/out/crucible-dtrace.sha256.txt
52025-10-15T15:59:20.899Zdownloading input: /input/rbuild/out/crucible-dtrace.tar
62025-10-15T15:59:21.010Zdownloaded input: /input/rbuild/out/crucible-dtrace.tar
72025-10-15T15:59:21.010Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
82025-10-15T15:59:21.037Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
92025-10-15T15:59:21.037Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
102025-10-15T15:59:26.359Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
112025-10-15T15:59:26.360Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
122025-10-15T15:59:26.387Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
132025-10-15T15:59:26.387Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
142025-10-15T15:59:27.486Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
152025-10-15T15:59:27.486Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162025-10-15T15:59:27.514Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172025-10-15T15:59:27.514Zdownloading input: /input/rbuild/out/crucible.tar.gz
182025-10-15T15:59:29.154Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192025-10-15T15:59:29.194Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202025-10-15T15:59:30.172Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212025-10-15T15:59:30.172Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222025-10-15T15:59:31.231Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232025-10-15T15:59:31.231Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242025-10-15T15:59:32.220Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252025-10-15T15:59:32.220Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262025-10-15T15:59:33.587Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272025-10-15T15:59:33.587Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282025-10-15T15:59:34.308Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292025-10-15T15:59:34.336Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302025-10-15T15:59:34.437Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312025-10-15T15:59:34.437Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322025-10-15T15:59:34.484Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332025-10-15T15:59:34.484Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342025-10-15T15:59:34.530Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352025-10-15T15:59:34.530Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362025-10-15T15:59:34.575Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372025-10-15T15:59:34.600Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382025-10-15T15:59:37.139Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392025-10-15T15:59:37.139Zstarting task 0: "setup"
402025-10-15T15:59:37.164Z++ uname -s
412025-10-15T15:59:37.164Z+ kern=SunOS
422025-10-15T15:59:37.164Z+ build_user=build
432025-10-15T15:59:37.164Z+ build_uid=12345
442025-10-15T15:59:37.164Z+ work_dir=/work
452025-10-15T15:59:37.164Z+ input_dir=/input
462025-10-15T15:59:37.164Z+ [[ 0 == 12345 ]]
472025-10-15T15:59:37.164Z+ case "$kern" in
482025-10-15T15:59:37.164Z+ groupadd -g 12345 build
492025-10-15T15:59:37.164Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502025-10-15T15:59:39.172Z+ zfs create -o mountpoint=/work rpool/work
512025-10-15T15:59:39.222Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522025-10-15T15:59:39.249Z+ home_fs=zfs
532025-10-15T15:59:39.249Z+ [[ zfs == autofs ]]
542025-10-15T15:59:39.249Z+ mkdir -p /home/build
552025-10-15T15:59:39.249Z+ chown build:build /home/build /work
562025-10-15T15:59:41.233Z+ chmod 0700 /home/build /work
572025-10-15T15:59:41.262Zprocess exited: duration 4099 ms, exit code 0
 
582025-10-15T15:59:41.321Zstarting task 1: "authentication"
592025-10-15T15:59:41.374Zprocess exited: duration 58 ms, exit code 0
 
602025-10-15T15:59:41.424Zstarting task 2: "build"
612025-10-15T15:59:41.449Z+ banner cores
622025-10-15T15:59:41.449Z
632025-10-15T15:59:41.449Z #### #### ##### ###### ####
642025-10-15T15:59:41.449Z # # # # # # # #
652025-10-15T15:59:41.449Z # # # # # ##### ####
662025-10-15T15:59:41.449Z # # # ##### # #
672025-10-15T15:59:41.449Z # # # # # # # # #
682025-10-15T15:59:41.449Z #### #### # # ###### ####
692025-10-15T15:59:41.449Z
702025-10-15T15:59:41.449Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
712025-10-15T15:59:41.474Z+ banner unpack
722025-10-15T15:59:41.474Z
732025-10-15T15:59:41.474Z # # # # ##### ## #### # #
742025-10-15T15:59:41.474Z # # ## # # # # # # # # #
752025-10-15T15:59:41.474Z # # # # # # # # # # ####
762025-10-15T15:59:41.474Z # # # # # ##### ###### # # #
772025-10-15T15:59:41.474Z # # # ## # # # # # # #
782025-10-15T15:59:41.474Z #### # # # # # #### # #
792025-10-15T15:59:41.474Z
802025-10-15T15:59:41.474Z+ mkdir -p /var/tmp/bins
812025-10-15T15:59:41.475Z+ for t in "$input/rbins/"*.gz
822025-10-15T15:59:41.475Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
832025-10-15T15:59:41.475Z+ b=crucible-downstairs.gz
842025-10-15T15:59:41.475Z+ b=crucible-downstairs
852025-10-15T15:59:41.475Z+ gunzip
862025-10-15T15:59:41.982Z+ chmod +x /var/tmp/bins/crucible-downstairs
872025-10-15T15:59:42.018Z+ for t in "$input/rbins/"*.gz
882025-10-15T15:59:42.018Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
892025-10-15T15:59:42.018Z+ b=crucible-hammer.gz
902025-10-15T15:59:42.018Z+ b=crucible-hammer
912025-10-15T15:59:42.018Z+ gunzip
922025-10-15T15:59:42.496Z+ chmod +x /var/tmp/bins/crucible-hammer
932025-10-15T15:59:42.523Z+ for t in "$input/rbins/"*.gz
942025-10-15T15:59:42.523Z++ basename /input/rbuild/work/rbins/crudd.gz
952025-10-15T15:59:42.523Z+ b=crudd.gz
962025-10-15T15:59:42.524Z+ b=crudd
972025-10-15T15:59:42.524Z+ gunzip
982025-10-15T15:59:42.989Z+ chmod +x /var/tmp/bins/crudd
992025-10-15T15:59:43.013Z+ for t in "$input/rbins/"*.gz
1002025-10-15T15:59:43.013Z++ basename /input/rbuild/work/rbins/crutest.gz
1012025-10-15T15:59:43.013Z+ b=crutest.gz
1022025-10-15T15:59:43.013Z+ b=crutest
1032025-10-15T15:59:43.013Z+ gunzip
1042025-10-15T15:59:43.527Z+ chmod +x /var/tmp/bins/crutest
1052025-10-15T15:59:43.552Z+ for t in "$input/rbins/"*.gz
1062025-10-15T15:59:43.552Z++ basename /input/rbuild/work/rbins/dsc.gz
1072025-10-15T15:59:43.552Z+ b=dsc.gz
1082025-10-15T15:59:43.552Z+ b=dsc
1092025-10-15T15:59:43.552Z+ gunzip
1102025-10-15T15:59:43.761Z+ chmod +x /var/tmp/bins/dsc
1112025-10-15T15:59:43.793Z+ export BINDIR=/var/tmp/bins
1122025-10-15T15:59:43.794Z+ BINDIR=/var/tmp/bins
1132025-10-15T15:59:43.794Z+ export RUST_BACKTRACE=1
1142025-10-15T15:59:43.794Z+ RUST_BACKTRACE=1
1152025-10-15T15:59:43.794Z+ banner setup
1162025-10-15T15:59:43.794Z
1172025-10-15T15:59:43.794Z #### ###### ##### # # #####
1182025-10-15T15:59:43.794Z # # # # # # #
1192025-10-15T15:59:43.794Z #### ##### # # # # #
1202025-10-15T15:59:43.794Z # # # # # #####
1212025-10-15T15:59:43.794Z # # # # # # #
1222025-10-15T15:59:43.794Z #### ###### # #### #
1232025-10-15T15:59:43.794Z
1242025-10-15T15:59:43.794Z+ pfexec plimit -n 9123456 1150
1252025-10-15T15:59:43.794Z+ echo 'Setup self timeout'
1262025-10-15T15:59:43.794ZSetup self timeout
1272025-10-15T15:59:43.794Z+ jobpid=1150
1282025-10-15T15:59:43.794ZSetup debug logging
1292025-10-15T15:59:43.794Z+ echo 'Setup debug logging'
1302025-10-15T15:59:43.794Z+ mkdir /tmp/debug
1312025-10-15T15:59:43.794Z+ sleep 3600
1322025-10-15T15:59:43.794Z+ psrinfo -v
1332025-10-15T15:59:43.822Z+ df -h
1342025-10-15T15:59:43.822Zdf: cannot statvfs /var/metadata/iso: Permission denied
1352025-10-15T15:59:43.822Z+ true
1362025-10-15T15:59:43.822Z+ prstat -d d -mLc 1
1372025-10-15T15:59:43.822Z+ iostat -T d -xn 1
1382025-10-15T15:59:43.822Z+ mpstat -T d 1
1392025-10-15T15:59:43.822Z+ vmstat -T d -p 1
1402025-10-15T15:59:43.822Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1412025-10-15T15:59:43.822Z+ banner 512-memtest
1422025-10-15T15:59:43.822Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1432025-10-15T15:59:43.822Z####### # #####
1442025-10-15T15:59:43.822Z# ## # # # # ###### # # ##### ###### ####
1452025-10-15T15:59:43.822Z# # # # ## ## # ## ## # # #
1462025-10-15T15:59:43.822Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1472025-10-15T15:59:43.822Z # # # # # # # # # # #
1482025-10-15T15:59:43.822Z# # # # # # # # # # # # #
1492025-10-15T15:59:43.822Z ##### ##### ####### # # ###### # # # ###### ####
1502025-10-15T15:59:43.823Z
1512025-10-15T15:59:43.823Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1522025-10-15T15:59:43.823ZUsing block size 512
1532025-10-15T15:59:43.823ZUsing extent size 131072
1542025-10-15T15:59:43.823ZUsing extent count 160
1552025-10-15T15:59:43.848Z/input/rbuild/work
1562025-10-15T15:59:43.848ZMemory usage test begins at October 15, 2025 at 03:59:43 PM UTC
1572025-10-15T15:59:43.848ZMemory usage values in kilobytes unless specified otherwise
1582025-10-15T15:59:43.897ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1592025-10-15T16:00:51.927Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1602025-10-15T16:00:52.228Z 1220 122548 765 163828 1023 101868 636 163828 1023 160
1612025-10-15T16:00:52.525Z 1219 121784 761 163088 1019 100984 631 163088 1019 160
1622025-10-15T16:00:52.815Z 1221 122372 764 163656 1022 101792 636 163656 1022 160
1632025-10-15T16:00:52.839ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 479 MiB
1642025-10-15T16:00:52.864ZSize of volume user gets : 10737418240
1652025-10-15T16:00:52.864ZSize on disk of all region dirs: 38259933392 or 35.6G
1662025-10-15T16:00:52.864ZSize on disk of a single region: 12753311129 or 11.9G
1672025-10-15T16:00:52.864Z Total Overage with 512 block size: 356.32%
1682025-10-15T16:00:52.889ZRegion Overage with 512 block size: 118.77%
1692025-10-15T16:00:53.938Z
1702025-10-15T16:00:53.964ZMemory usage test finished on October 15, 2025 at 04:00:53 PM UTC
1712025-10-15T16:00:53.964Z
1722025-10-15T16:00:53.964Zreal 1:10.136732549
1732025-10-15T16:00:53.964Zuser 2:02.677913346
1742025-10-15T16:00:53.964Zsys 1:14.312159195
1752025-10-15T16:00:53.965Ztrap 0.965213950
1762025-10-15T16:00:53.965Ztflt 0.005501743
1772025-10-15T16:00:53.965Zdflt 0.052001817
1782025-10-15T16:00:53.965Zkflt 0.009713728
1792025-10-15T16:00:53.965Zlock 2:17:56.999048968
1802025-10-15T16:00:53.965Zslp 4:37.386995403
1812025-10-15T16:00:53.965Zlat 2:10.565673036
1822025-10-15T16:00:53.965Zstop 0.019671883
1832025-10-15T16:00:53.966Z+ banner 4k-memtest
1842025-10-15T16:00:53.966Z#
1852025-10-15T16:00:53.966Z# # # # # # ###### # # ##### ###### #### #####
1862025-10-15T16:00:53.966Z# # # # ## ## # ## ## # # # #
1872025-10-15T16:00:53.966Z# # #### ##### # ## # ##### # ## # # ##### #### #
1882025-10-15T16:00:53.966Z####### # # # # # # # # # # #
1892025-10-15T16:00:53.966Z # # # # # # # # # # # # #
1902025-10-15T16:00:53.966Z # # # # # ###### # # # ###### #### #
1912025-10-15T16:00:53.966Z
1922025-10-15T16:00:53.967Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1932025-10-15T16:00:53.967ZUsing block size 4096
1942025-10-15T16:00:53.967ZUsing extent size 16384
1952025-10-15T16:00:53.967ZUsing extent count 160
1962025-10-15T16:00:53.992Z/input/rbuild/work
1972025-10-15T16:00:53.992ZMemory usage test begins at October 15, 2025 at 04:00:53 PM UTC
1982025-10-15T16:00:53.992ZMemory usage values in kilobytes unless specified otherwise
1992025-10-15T16:00:54.024ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
2002025-10-15T16:01:36.715Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2012025-10-15T16:01:36.927Z 1385 107056 669 147012 918 85124 532 147012 918 160
2022025-10-15T16:01:37.148Z 1386 104776 654 144764 904 82876 517 144764 904 160
2032025-10-15T16:01:37.359Z 1387 104648 654 144604 903 82748 517 144604 903 160
2042025-10-15T16:01:37.383ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 426 MiB
2052025-10-15T16:01:37.407ZSize of volume user gets : 10737418240
2062025-10-15T16:01:37.407ZSize on disk of all region dirs: 32968229075 or 30.7G
2072025-10-15T16:01:37.407ZSize on disk of a single region: 10989409690 or 10.2G
2082025-10-15T16:01:37.407Z Total Overage with 4096 block size: 307.04%
2092025-10-15T16:01:37.432ZRegion Overage with 4096 block size: 102.35%
2102025-10-15T16:01:38.472Z
2112025-10-15T16:01:38.496ZMemory usage test finished on October 15, 2025 at 04:01:38 PM UTC
2122025-10-15T16:01:38.496Z
2132025-10-15T16:01:38.497Zreal 44.528467004
2142025-10-15T16:01:38.497Zuser 43.959918613
2152025-10-15T16:01:38.497Zsys 1:02.669423708
2162025-10-15T16:01:38.497Ztrap 0.369205196
2172025-10-15T16:01:38.497Ztflt 0.002130727
2182025-10-15T16:01:38.497Zdflt 0.010202363
2192025-10-15T16:01:38.497Zkflt 0.000419907
2202025-10-15T16:01:38.497Zlock 1:19:59.620474179
2212025-10-15T16:01:38.497Zslp 3:17.927531202
2222025-10-15T16:01:38.497Zlat 1:14.225879797
2232025-10-15T16:01:38.497Zstop 0.018204116
2242025-10-15T16:01:43.488Zprocess exited: duration 117064 ms, exit code 0
2252025-10-15T16:01:43.488Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2262025-10-15T16:02:43.487Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2272025-10-15T16:02:43.487Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2282025-10-15T16:02:43.565Zfound 12 output files
2292025-10-15T16:02:43.565Zuploading: /tmp/test_mem_log.txt (2642032 bytes)
2302025-10-15T16:02:44.893Zuploaded: /tmp/test_mem_log.txt
2312025-10-15T16:02:44.893Zuploading: /tmp/debug/df.txt (1270 bytes)
2322025-10-15T16:02:45.986Zuploaded: /tmp/debug/df.txt
2332025-10-15T16:02:45.986Zuploading: /tmp/debug/iostat.txt (66412 bytes)
2342025-10-15T16:02:46.119Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 66412 -> 67516
2352025-10-15T16:02:47.189Zuploaded: /tmp/debug/iostat.txt
2362025-10-15T16:02:47.189Zuploading: /tmp/debug/mpstat.txt (131429 bytes)
2372025-10-15T16:02:47.344Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 131429 -> 134345
2382025-10-15T16:02:48.416Zuploaded: /tmp/debug/mpstat.txt
2392025-10-15T16:02:48.441Zuploading: /tmp/debug/paging.txt (23544 bytes)
2402025-10-15T16:02:48.530Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 23544 -> 24277
2412025-10-15T16:02:49.601Zuploaded: /tmp/debug/paging.txt
2422025-10-15T16:02:49.601Zuploading: /tmp/debug/perf.txt (87894 bytes)
2432025-10-15T16:02:49.756Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 87894 -> 145806
2442025-10-15T16:02:50.824Zuploaded: /tmp/debug/perf.txt
2452025-10-15T16:02:50.824Zuploading: /tmp/debug/prstat.txt (265895 bytes)
2462025-10-15T16:02:51.005Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 265895 -> 276308
2472025-10-15T16:02:52.074Zuploaded: /tmp/debug/prstat.txt
2482025-10-15T16:02:52.075Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2492025-10-15T16:02:53.172Zuploaded: /tmp/debug/psrinfo.txt
2502025-10-15T16:02:53.172Zuploading: /tmp/debug/upinfo.txt (12019 bytes)
2512025-10-15T16:02:54.310Zuploaded: /tmp/debug/upinfo.txt
2522025-10-15T16:02:54.310Zuploading: /tmp/dsc/downstairs-8810.txt (8731 bytes)
2532025-10-15T16:02:55.426Zuploaded: /tmp/dsc/downstairs-8810.txt
2542025-10-15T16:02:55.426Zuploading: /tmp/dsc/downstairs-8820.txt (7568 bytes)
2552025-10-15T16:02:56.542Zuploaded: /tmp/dsc/downstairs-8820.txt
2562025-10-15T16:02:56.542Zuploading: /tmp/dsc/downstairs-8830.txt (7566 bytes)
2572025-10-15T16:02:57.659Zuploaded: /tmp/dsc/downstairs-8830.txt