01M2S8091PKC4T6XCKQ4KEX220: 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: 01M2S817ETASRXEQ2P5ZBD5K29

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-09-18T03:26:37.117Zjob dependencies complete; ready to run (waiting for 13 m 48 s)
22026-09-18T03:27:06.366Zjob assigned to worker 01M2S8T5MJ48QJ35HAFJH77YQY [factory aws, i-026a27feed4b1bf4e] (queued for 29 s)
32026-09-18T03:27:13.461Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42026-09-18T03:27:13.493Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52026-09-18T03:27:13.493Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62026-09-18T03:27:16.717Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72026-09-18T03:27:16.759Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82026-09-18T03:27:16.830Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92026-09-18T03:27:16.830Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102026-09-18T03:27:17.610Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112026-09-18T03:27:17.645Zdownloading input: /input/rbuild/out/crucible-utils.sha256.txt
122026-09-18T03:27:17.700Zdownloaded input: /input/rbuild/out/crucible-utils.sha256.txt
132026-09-18T03:27:17.750Zdownloading input: /input/rbuild/out/crucible-utils.tar
142026-09-18T03:27:18.479Zdownloaded input: /input/rbuild/out/crucible-utils.tar
152026-09-18T03:27:18.479Zdownloading input: /input/rbuild/out/crucible.sha256.txt
162026-09-18T03:27:21.160Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
172026-09-18T03:27:21.160Zdownloading input: /input/rbuild/out/crucible.tar.gz
182026-09-18T03:27:23.172Zdownloaded input: /input/rbuild/out/crucible.tar.gz
192026-09-18T03:27:23.250Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
202026-09-18T03:27:24.227Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
212026-09-18T03:27:24.227Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
222026-09-18T03:27:24.953Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
232026-09-18T03:27:24.953Zdownloading input: /input/rbuild/work/rbins/crudd.gz
242026-09-18T03:27:25.542Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
252026-09-18T03:27:25.616Zdownloading input: /input/rbuild/work/rbins/crutest.gz
262026-09-18T03:27:26.751Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
272026-09-18T03:27:26.915Zdownloading input: /input/rbuild/work/rbins/dsc.gz
282026-09-18T03:27:27.384Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
292026-09-18T03:27:27.508Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
302026-09-18T03:27:27.508Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
312026-09-18T03:27:27.508Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
322026-09-18T03:27:27.657Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
332026-09-18T03:27:27.657Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
342026-09-18T03:27:27.769Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
352026-09-18T03:27:27.769Zdownloading input: /input/rbuild/work/scripts/upstairs_info.d
362026-09-18T03:27:27.871Zdownloaded input: /input/rbuild/work/scripts/upstairs_info.d
372026-09-18T03:27:27.980Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
382026-09-18T03:27:29.755Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
392026-09-18T03:27:29.755Zstarting task 0: "setup"
402026-09-18T03:27:29.833Z++ uname -s
412026-09-18T03:27:29.833Z+ kern=SunOS
422026-09-18T03:27:29.834Z+ build_user=build
432026-09-18T03:27:29.834Z+ build_uid=12345
442026-09-18T03:27:29.834Z+ work_dir=/work
452026-09-18T03:27:29.834Z+ input_dir=/input
462026-09-18T03:27:29.834Z+ [[ 0 == 12345 ]]
472026-09-18T03:27:29.834Z+ case "$kern" in
482026-09-18T03:27:29.834Z+ groupadd -g 12345 build
492026-09-18T03:27:29.834Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
502026-09-18T03:27:31.814Z+ zfs create -o mountpoint=/work rpool/work
512026-09-18T03:27:31.931Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
522026-09-18T03:27:32.025Z+ home_fs=zfs
532026-09-18T03:27:32.025Z+ [[ zfs == autofs ]]
542026-09-18T03:27:32.025Z+ mkdir -p /home/build
552026-09-18T03:27:32.025Z+ chown build:build /home/build /work
562026-09-18T03:27:32.907Z+ chmod 0700 /home/build /work
572026-09-18T03:27:32.970Zprocess exited: duration 3146 ms, exit code 0
 
582026-09-18T03:27:33.103Zstarting task 1: "authentication"
592026-09-18T03:27:33.173ZWARNING: job store has no value for "GITHUB_TOKEN"; waiting for a value...
602026-09-18T03:27:35.262Zprocess exited: duration 2176 ms, exit code 0
 
612026-09-18T03:27:35.405Zstarting task 2: "build"
622026-09-18T03:27:35.490Z+ banner cores
632026-09-18T03:27:35.490Z
642026-09-18T03:27:35.490Z #### #### ##### ###### ####
652026-09-18T03:27:35.490Z # # # # # # # #
662026-09-18T03:27:35.491Z # # # # # ##### ####
672026-09-18T03:27:35.491Z # # # ##### # #
682026-09-18T03:27:35.491Z # # # # # # # # #
692026-09-18T03:27:35.491Z #### #### # # ###### ####
702026-09-18T03:27:35.491Z
712026-09-18T03:27:35.491Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
722026-09-18T03:27:35.491Z+ banner unpack
732026-09-18T03:27:35.491Z
742026-09-18T03:27:35.491Z # # # # ##### ## #### # #
752026-09-18T03:27:35.491Z # # ## # # # # # # # # #
762026-09-18T03:27:35.491Z # # # # # # # # # # ####
772026-09-18T03:27:35.492Z # # # # # ##### ###### # # #
782026-09-18T03:27:35.492Z # # # ## # # # # # # #
792026-09-18T03:27:35.492Z #### # # # # # #### # #
802026-09-18T03:27:35.492Z
812026-09-18T03:27:35.492Z+ mkdir -p /var/tmp/bins
822026-09-18T03:27:35.492Z+ for t in "$input/rbins/"*.gz
832026-09-18T03:27:35.492Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
842026-09-18T03:27:35.492Z+ b=crucible-downstairs.gz
852026-09-18T03:27:35.492Z+ b=crucible-downstairs
862026-09-18T03:27:35.492Z+ gunzip
872026-09-18T03:27:35.740Z+ chmod +x /var/tmp/bins/crucible-downstairs
882026-09-18T03:27:35.866Z+ for t in "$input/rbins/"*.gz
892026-09-18T03:27:35.866Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
902026-09-18T03:27:35.866Z+ b=crucible-hammer.gz
912026-09-18T03:27:35.866Z+ b=crucible-hammer
922026-09-18T03:27:35.866Z+ gunzip
932026-09-18T03:27:35.987Z+ chmod +x /var/tmp/bins/crucible-hammer
942026-09-18T03:27:35.987Z+ for t in "$input/rbins/"*.gz
952026-09-18T03:27:35.987Z++ basename /input/rbuild/work/rbins/crudd.gz
962026-09-18T03:27:35.988Z+ b=crudd.gz
972026-09-18T03:27:35.988Z+ b=crudd
982026-09-18T03:27:35.988Z+ gunzip
992026-09-18T03:27:36.192Z+ chmod +x /var/tmp/bins/crudd
1002026-09-18T03:27:36.223Z+ for t in "$input/rbins/"*.gz
1012026-09-18T03:27:36.223Z++ basename /input/rbuild/work/rbins/crutest.gz
1022026-09-18T03:27:36.223Z+ b=crutest.gz
1032026-09-18T03:27:36.223Z+ b=crutest
1042026-09-18T03:27:36.224Z+ gunzip
1052026-09-18T03:27:36.518Z+ chmod +x /var/tmp/bins/crutest
1062026-09-18T03:27:36.611Z+ for t in "$input/rbins/"*.gz
1072026-09-18T03:27:36.611Z++ basename /input/rbuild/work/rbins/dsc.gz
1082026-09-18T03:27:36.611Z+ b=dsc.gz
1092026-09-18T03:27:36.611Z+ b=dsc
1102026-09-18T03:27:36.611Z+ gunzip
1112026-09-18T03:27:36.716Z+ chmod +x /var/tmp/bins/dsc
1122026-09-18T03:27:36.716Z+ export BINDIR=/var/tmp/bins
1132026-09-18T03:27:36.716Z+ BINDIR=/var/tmp/bins
1142026-09-18T03:27:36.716Z+ export RUST_BACKTRACE=1
1152026-09-18T03:27:36.716Z+ RUST_BACKTRACE=1
1162026-09-18T03:27:36.717Z+ banner setup
1172026-09-18T03:27:36.717Z
1182026-09-18T03:27:36.717Z #### ###### ##### # # #####
1192026-09-18T03:27:36.717Z # # # # # # #
1202026-09-18T03:27:36.717Z #### ##### # # # # #
1212026-09-18T03:27:36.717Z # # # # # #####
1222026-09-18T03:27:36.717Z # # # # # # #
1232026-09-18T03:27:36.717Z #### ###### # #### #
1242026-09-18T03:27:36.717Z
1252026-09-18T03:27:36.717Z+ pfexec plimit -n 9123456 1088
1262026-09-18T03:27:36.717Z+ echo 'Setup self timeout'
1272026-09-18T03:27:36.717ZSetup self timeout
1282026-09-18T03:27:36.717Z+ jobpid=1088
1292026-09-18T03:27:36.717Z+ echo 'Setup debug logging'
1302026-09-18T03:27:36.718Z+ mkdir /tmp/debug
1312026-09-18T03:27:36.718ZSetup debug logging
1322026-09-18T03:27:36.718Z+ sleep 3600
1332026-09-18T03:27:36.718Z+ psrinfo -v
1342026-09-18T03:27:36.718Z+ df -h
1352026-09-18T03:27:36.718Z+ prstat -d d -mLc 1
1362026-09-18T03:27:36.718Z+ iostat -T d -xn 1
1372026-09-18T03:27:36.718Z+ mpstat -T d 1
1382026-09-18T03:27:36.718Z+ vmstat -T d -p 1
1392026-09-18T03:27:36.718Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1402026-09-18T03:27:36.718Z+ banner 512-memtest
1412026-09-18T03:27:36.718Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/upstairs_info.d
1422026-09-18T03:27:36.718Z####### # #####
1432026-09-18T03:27:36.718Z# ## # # # # ###### # # ##### ###### ####
1442026-09-18T03:27:36.718Z# # # # ## ## # ## ## # # #
1452026-09-18T03:27:36.718Z###### # ##### ##### # ## # ##### # ## # # ##### ####
1462026-09-18T03:27:36.719Z # # # # # # # # # # #
1472026-09-18T03:27:36.719Z# # # # # # # # # # # # #
1482026-09-18T03:27:36.719Z ##### ##### ####### # # ###### # # # ###### ####
1492026-09-18T03:27:36.719Z
1502026-09-18T03:27:36.719Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 512 -e 131072 -c 160
1512026-09-18T03:27:36.719ZUsing block size 512
1522026-09-18T03:27:36.719ZUsing extent size 131072
1532026-09-18T03:27:36.719ZUsing extent count 160
1542026-09-18T03:27:36.719Z/input/rbuild/work
1552026-09-18T03:27:36.719ZMemory usage test begins at September 18, 2026 at 03:27:34 AM UTC
1562026-09-18T03:27:36.719ZMemory usage values in kilobytes unless specified otherwise
1572026-09-18T03:27:36.719ZRegion with ES:131072 EC:160 BS:512 Size: 10 GiB Extent Size: 64 MiB
1582026-09-18T03:28:04.570Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
1592026-09-18T03:28:04.650Z 1157 79544 497 115244 720 64088 400 115244 720 160
1602026-09-18T03:28:04.745Z 1159 112820 705 148468 927 97420 608 148468 927 160
1612026-09-18T03:28:04.849Z 1158 112408 702 148220 926 96812 605 148220 926 160
1622026-09-18T03:28:04.852ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 402 MiB
1632026-09-18T03:28:04.869ZSize of volume user gets : 10737418240
1642026-09-18T03:28:04.869ZSize on disk of all region dirs: 74726433 or 35.6G
1652026-09-18T03:28:04.869ZSize on disk of a single region: 24908811 or 11.9G
1662026-09-18T03:28:04.876Z Total Overage with 512 block size: 0.70%
1672026-09-18T03:28:04.876ZRegion Overage with 512 block size: 0.23%
1682026-09-18T03:28:07.319Z
1692026-09-18T03:28:07.345ZMemory usage test finished on September 18, 2026 at 03:28:04 AM UTC
1702026-09-18T03:28:07.345Z
1712026-09-18T03:28:07.345Zreal 29.282966119
1722026-09-18T03:28:07.345Zuser 35.884676656
1732026-09-18T03:28:07.345Zsys 27.171764047
1742026-09-18T03:28:07.345Ztrap 0.409440863
1752026-09-18T03:28:07.345Ztflt 0.002007345
1762026-09-18T03:28:07.345Zdflt 0.033127720
1772026-09-18T03:28:07.345Zkflt 0.001456232
1782026-09-18T03:28:07.345Zlock 1:00:33.844165419
1792026-09-18T03:28:07.345Zslp 2:10.380614339
1802026-09-18T03:28:07.345Zlat 51.616915051
1812026-09-18T03:28:07.345Zstop 0.004892530
1822026-09-18T03:28:07.345Z+ banner 4k-memtest
1832026-09-18T03:28:07.345Z#
1842026-09-18T03:28:07.345Z# # # # # # ###### # # ##### ###### #### #####
1852026-09-18T03:28:07.345Z# # # # ## ## # ## ## # # # #
1862026-09-18T03:28:07.345Z# # #### ##### # ## # ##### # ## # # ##### #### #
1872026-09-18T03:28:07.345Z####### # # # # # # # # # # #
1882026-09-18T03:28:07.346Z # # # # # # # # # # # # #
1892026-09-18T03:28:07.346Z # # # # # ###### # # # ###### #### #
1902026-09-18T03:28:07.346Z
1912026-09-18T03:28:07.346Z+ ptime -m bash /input/rbuild/work/scripts/test_mem.sh -b 4096 -e 16384 -c 160
1922026-09-18T03:28:07.346ZUsing block size 4096
1932026-09-18T03:28:07.346ZUsing extent size 16384
1942026-09-18T03:28:07.346ZUsing extent count 160
1952026-09-18T03:28:07.346Z/input/rbuild/work
1962026-09-18T03:28:07.346ZMemory usage test begins at September 18, 2026 at 03:28:04 AM UTC
1972026-09-18T03:28:07.346ZMemory usage values in kilobytes unless specified otherwise
1982026-09-18T03:28:07.346ZRegion with ES:16384 EC:160 BS:4096 Size: 10 GiB Extent Size: 64 MiB
1992026-09-18T03:28:27.997Z PID RSS RSS/EC VSZ VSZ/EC HEAP HEAP/EC TOTAL TOTAL/EC EC
2002026-09-18T03:28:28.060Z 1323 31072 194 65392 408 14584 91 65392 408 160
2012026-09-18T03:28:28.120Z 1324 31960 199 66280 414 15548 97 66280 414 160
2022026-09-18T03:28:28.180Z 1325 32024 200 66344 414 15548 97 66344 414 160
2032026-09-18T03:28:28.183ZRegion:10 GiB Extent:64 MiB Total downstairs (pmap -x): 193 MiB
2042026-09-18T03:28:28.196ZSize of volume user gets : 10737418240
2052026-09-18T03:28:28.196ZSize on disk of all region dirs: 64391073 or 30.7G
2062026-09-18T03:28:28.196ZSize on disk of a single region: 21463691 or 10.2G
2072026-09-18T03:28:28.199Z Total Overage with 4096 block size: 0.60%
2082026-09-18T03:28:28.202ZRegion Overage with 4096 block size: 0.20%
2092026-09-18T03:28:29.210Z
2102026-09-18T03:28:29.215ZMemory usage test finished on September 18, 2026 at 03:28:27 AM UTC
2112026-09-18T03:28:29.215Z
2122026-09-18T03:28:29.215Zreal 23.320316240
2132026-09-18T03:28:29.217Zuser 16.366912571
2142026-09-18T03:28:29.217Zsys 24.822523838
2152026-09-18T03:28:29.217Ztrap 0.107953778
2162026-09-18T03:28:29.217Ztflt 0.000413773
2172026-09-18T03:28:29.217Zdflt 0.001888132
2182026-09-18T03:28:29.217Zkflt 0.000026073
2192026-09-18T03:28:29.217Zlock 39:58.670660379
2202026-09-18T03:28:29.217Zslp 1:51.508493410
2212026-09-18T03:28:29.217Zlat 16.818959162
2222026-09-18T03:28:29.217Zstop 0.004310713
2232026-09-18T03:28:34.216Zprocess exited: duration 53849 ms, exit code 0
2242026-09-18T03:28:34.216Zexec warning: : stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2252026-09-18T03:29:34.248Zexec warning: : stdout descriptor may be held open by a background process; giving up!
2262026-09-18T03:29:34.249Zexec warning: : stderr descriptor may be held open by a background process; giving up!
 
2272026-09-18T03:29:34.261Zfound 12 output files
2282026-09-18T03:29:34.261Zuploading: /tmp/test_mem_log.txt (2641398 bytes)
2292026-09-18T03:29:35.295Zuploaded: /tmp/test_mem_log.txt
2302026-09-18T03:29:35.295Zuploading: /tmp/debug/df.txt (1270 bytes)
2312026-09-18T03:29:36.305Zuploaded: /tmp/debug/df.txt
2322026-09-18T03:29:36.305Zuploading: /tmp/debug/iostat.txt (35730 bytes)
2332026-09-18T03:29:36.310Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 35730 -> 36334
2342026-09-18T03:29:37.320Zuploaded: /tmp/debug/iostat.txt
2352026-09-18T03:29:37.320Zuploading: /tmp/debug/mpstat.txt (86678 bytes)
2362026-09-18T03:29:37.327Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 86678 -> 88871
2372026-09-18T03:29:38.335Zuploaded: /tmp/debug/mpstat.txt
2382026-09-18T03:29:38.335Zuploading: /tmp/debug/paging.txt (15702 bytes)
2392026-09-18T03:29:38.340Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 15702 -> 16328
2402026-09-18T03:29:39.348Zuploaded: /tmp/debug/paging.txt
2412026-09-18T03:29:39.348Zuploading: /tmp/debug/perf.txt (52590 bytes)
2422026-09-18T03:29:39.355Zupload warning: file "/tmp/debug/perf.txt" changed size mid upload: 52590 -> 105051
2432026-09-18T03:29:40.363Zuploaded: /tmp/debug/perf.txt
2442026-09-18T03:29:40.363Zuploading: /tmp/debug/prstat.txt (161967 bytes)
2452026-09-18T03:29:40.370Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 161967 -> 169750
2462026-09-18T03:29:41.379Zuploaded: /tmp/debug/prstat.txt
2472026-09-18T03:29:41.379Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2482026-09-18T03:29:42.388Zuploaded: /tmp/debug/psrinfo.txt
2492026-09-18T03:29:42.388Zuploading: /tmp/debug/upinfo.txt (4760 bytes)
2502026-09-18T03:29:43.396Zuploaded: /tmp/debug/upinfo.txt
2512026-09-18T03:29:43.396Zuploading: /tmp/dsc/downstairs-8810.txt (8885 bytes)
2522026-09-18T03:29:44.407Zuploaded: /tmp/dsc/downstairs-8810.txt
2532026-09-18T03:29:44.407Zuploading: /tmp/dsc/downstairs-8820.txt (7710 bytes)
2542026-09-18T03:29:45.418Zuploaded: /tmp/dsc/downstairs-8820.txt
2552026-09-18T03:29:45.418Zuploading: /tmp/dsc/downstairs-8830.txt (7710 bytes)
2562026-09-18T03:29:46.441Zuploaded: /tmp/dsc/downstairs-8830.txt