01HAZHJ54HC0RR3MB3ASCPA6S4: test-perf

BasicConfig {
    output_rules: [
        "=/tmp/perf*.csv",
        "/tmp/perfout.txt",
        "%/tmp/debug/*.txt",
        "/tmp/dsc/*.txt",
        "/tmp/core.*",
    ],
    rust_toolchain: None,
    target: Some(
        "helios-2.0",
    ),
    access_repos: [],
    publish: [],
    skip_clone: true,
}

Buildomat Job: 01HAZHJEVV30VAA00EP0HTQ7MY

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12023-09-22T23:30:32.458Zjob dependencies complete; ready to run (waiting for 36 m 55 s)
22023-09-22T23:32:19.282Zjob assigned to worker 01HAZKP6QPF15TC00H84G1F61X (queued for 1 m 46 s)
32023-09-22T23:32:19.647Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42023-09-22T23:32:19.686Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52023-09-22T23:32:19.689Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62023-09-22T23:32:28.287Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72023-09-22T23:32:28.292Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82023-09-22T23:32:28.303Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92023-09-22T23:32:28.306Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102023-09-22T23:32:30.899Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112023-09-22T23:32:30.909Zdownloading input: /input/rbuild/out/crucible.sha256.txt
122023-09-22T23:32:31.321Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
132023-09-22T23:32:31.325Zdownloading input: /input/rbuild/out/crucible.tar.gz
142023-09-22T23:32:36.664Zdownloaded input: /input/rbuild/out/crucible.tar.gz
152023-09-22T23:32:36.669Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
162023-09-22T23:32:39.733Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
172023-09-22T23:32:39.737Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
182023-09-22T23:32:41.575Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
192023-09-22T23:32:41.578Zdownloading input: /input/rbuild/work/rbins/crudd.gz
202023-09-22T23:32:42.437Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
212023-09-22T23:32:42.440Zdownloading input: /input/rbuild/work/rbins/crutest.gz
222023-09-22T23:32:44.559Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
232023-09-22T23:32:44.562Zdownloading input: /input/rbuild/work/rbins/dsc.gz
242023-09-22T23:32:46.614Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
252023-09-22T23:32:46.617Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
262023-09-22T23:32:46.627Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
272023-09-22T23:32:46.630Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
282023-09-22T23:32:46.641Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
292023-09-22T23:32:46.644Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
302023-09-22T23:32:46.659Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
312023-09-22T23:32:46.662Zdownloading input: /input/rbuild/work/scripts/test_perf.sh
322023-09-22T23:32:46.678Zdownloaded input: /input/rbuild/work/scripts/test_perf.sh
332023-09-22T23:32:46.680Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
342023-09-22T23:32:47.219Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
352023-09-22T23:32:47.222Zstarting task 0: "setup"
362023-09-22T23:32:47.416Z++ uname -s
372023-09-22T23:32:47.419Z+ kern=SunOS
382023-09-22T23:32:47.422Z+ case "$kern" in
392023-09-22T23:32:47.425Z+ groupadd -g 12345 build
402023-09-22T23:32:47.428Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
412023-09-22T23:32:49.433Z+ zfs create -o mountpoint=/work rpool/work
422023-09-22T23:32:49.518Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
432023-09-22T23:32:49.521Z+ home_fs=zfs
442023-09-22T23:32:49.524Z+ [[ zfs == autofs ]]
452023-09-22T23:32:49.527Z+ mkdir -p /home/build
462023-09-22T23:32:49.530Z+ chown build:build /home/build /work
472023-09-22T23:32:51.526Z+ chmod 0700 /home/build /work
482023-09-22T23:32:51.529Zprocess exited: duration 4304 ms, exit code 0
 
492023-09-22T23:32:51.535Zstarting task 1: "authentication"
502023-09-22T23:32:51.563Zprocess exited: duration 25 ms, exit code 0
 
512023-09-22T23:32:51.569Zstarting task 2: "build"
522023-09-22T23:32:51.579Z+ banner cores
532023-09-22T23:32:51.637Z
542023-09-22T23:32:51.640Z #### #### ##### ###### ####
552023-09-22T23:32:51.648Z # # # # # # # #
562023-09-22T23:32:51.651Z # # # # # ##### ####
572023-09-22T23:32:51.655Z # # # ##### # #
582023-09-22T23:32:51.658Z # # # # # # # # #
592023-09-22T23:32:51.661Z #### #### # # ###### ####
602023-09-22T23:32:51.664Z
612023-09-22T23:32:51.667Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
622023-09-22T23:32:51.670Z+ banner unpack
632023-09-22T23:32:51.673Z
642023-09-22T23:32:51.676Z # # # # ##### ## #### # #
652023-09-22T23:32:51.679Z # # ## # # # # # # # # #
662023-09-22T23:32:51.683Z # # # # # # # # # # ####
672023-09-22T23:32:51.686Z # # # # # ##### ###### # # #
682023-09-22T23:32:51.689Z # # # ## # # # # # # #
692023-09-22T23:32:51.692Z #### # # # # # #### # #
702023-09-22T23:32:51.695Z
712023-09-22T23:32:51.698Z+ mkdir -p /var/tmp/bins
722023-09-22T23:32:51.701Z+ for t in "$input/rbins/"*.gz
732023-09-22T23:32:51.704Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
742023-09-22T23:32:51.706Z+ b=crucible-downstairs.gz
752023-09-22T23:32:51.709Z+ b=crucible-downstairs
762023-09-22T23:32:51.712Z+ gunzip
772023-09-22T23:32:52.452Z+ chmod +x /var/tmp/bins/crucible-downstairs
782023-09-22T23:32:52.455Z+ for t in "$input/rbins/"*.gz
792023-09-22T23:32:52.458Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
802023-09-22T23:32:52.461Z+ b=crucible-hammer.gz
812023-09-22T23:32:52.464Z+ b=crucible-hammer
822023-09-22T23:32:52.467Z+ gunzip
832023-09-22T23:32:53.002Z+ chmod +x /var/tmp/bins/crucible-hammer
842023-09-22T23:32:53.005Z+ for t in "$input/rbins/"*.gz
852023-09-22T23:32:53.008Z++ basename /input/rbuild/work/rbins/crudd.gz
862023-09-22T23:32:53.011Z+ b=crudd.gz
872023-09-22T23:32:53.014Z+ b=crudd
882023-09-22T23:32:53.017Z+ gunzip
892023-09-22T23:32:53.527Z+ chmod +x /var/tmp/bins/crudd
902023-09-22T23:32:53.530Z+ for t in "$input/rbins/"*.gz
912023-09-22T23:32:53.533Z++ basename /input/rbuild/work/rbins/crutest.gz
922023-09-22T23:32:53.536Z+ b=crutest.gz
932023-09-22T23:32:53.539Z+ b=crutest
942023-09-22T23:32:53.541Z+ gunzip
952023-09-22T23:32:54.131Z+ chmod +x /var/tmp/bins/crutest
962023-09-22T23:32:54.134Z+ for t in "$input/rbins/"*.gz
972023-09-22T23:32:54.137Z++ basename /input/rbuild/work/rbins/dsc.gz
982023-09-22T23:32:54.139Z+ b=dsc.gz
992023-09-22T23:32:54.142Z+ b=dsc
1002023-09-22T23:32:54.145Z+ gunzip
1012023-09-22T23:32:54.498Z+ chmod +x /var/tmp/bins/dsc
1022023-09-22T23:32:54.501Z+ export BINDIR=/var/tmp/bins
1032023-09-22T23:32:54.504Z+ BINDIR=/var/tmp/bins
1042023-09-22T23:32:54.507Z+ banner setup
1052023-09-22T23:32:54.510Z
1062023-09-22T23:32:54.515Z #### ###### ##### # # #####
1072023-09-22T23:32:54.519Z # # # # # # #
1082023-09-22T23:32:54.521Z #### ##### # # # # #
1092023-09-22T23:32:54.524Z # # # # # #####
1102023-09-22T23:32:54.527Z # # # # # # #
1112023-09-22T23:32:54.530Z #### ###### # #### #
1122023-09-22T23:32:54.533Z
1132023-09-22T23:32:54.536Z+ pfexec plimit -n 9123456 1125
1142023-09-22T23:32:54.539Z+ echo 'Setup self timeout'
1152023-09-22T23:32:54.541ZSetup self timeout
1162023-09-22T23:32:54.544Z+ jobpid=1125
1172023-09-22T23:32:54.547Z+ echo 'Setup debug logging'
1182023-09-22T23:32:54.550Z+ mkdir /tmp/debug
1192023-09-22T23:32:54.553ZSetup debug logging
1202023-09-22T23:32:54.555Z+ sleep 2400
1212023-09-22T23:32:54.558Z+ psrinfo -v
1222023-09-22T23:32:54.610Z+ df -h
1232023-09-22T23:32:54.699Z+ prstat -d d -mLc 1
1242023-09-22T23:32:54.703Z+ + + iostat -T d -xn 1
1252023-09-22T23:32:54.706Zmpstat -T d 1
1262023-09-22T23:32:54.709Zvmstat -T d -p 1
1272023-09-22T23:32:54.712Z+ banner start
1282023-09-22T23:32:54.715Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1292023-09-22T23:32:54.718Z
1302023-09-22T23:32:54.721Z #### ##### ## ##### #####
1312023-09-22T23:32:54.724Z # # # # # # #
1322023-09-22T23:32:54.727Z #### # # # # # #
1332023-09-22T23:32:54.729Z # # ###### ##### #
1342023-09-22T23:32:54.732Z # # # # # # # #
1352023-09-22T23:32:54.735Z #### # # # # # #
1362023-09-22T23:32:54.738Z
1372023-09-22T23:32:54.741Z+ bash /input/rbuild/work/scripts/test_perf.sh
1382023-09-22T23:38:35.997Z+ echo '0 was our result'
1392023-09-22T23:38:36.001Z0 was our result
1402023-09-22T23:38:36.004ZTest finished
1412023-09-22T23:38:36.007Z+ echo 'Test finished'
1422023-09-22T23:38:36.009Z+ sleep 5
1432023-09-22T23:38:41.001Z+ ps -ef
1442023-09-22T23:38:41.059Z UID PID PPID C STIME TTY TIME CMD
1452023-09-22T23:38:41.063Z root 0 0 0 23:31:02 ? 0:02 sched
1462023-09-22T23:38:41.066Z root 4 0 0 23:30:59 ? 0:00 kcfpoold
1472023-09-22T23:38:41.068Z root 5 0 0 23:31:02 ? 3:51 zpool-rpool
1482023-09-22T23:38:41.071Z root 1 0 0 23:31:02 ? 0:00 /sbin/init
1492023-09-22T23:38:41.074Z root 2 0 0 23:31:02 ? 0:00 pageout
1502023-09-22T23:38:41.077Z root 3 0 0 23:31:02 ? 0:03 fsflush
1512023-09-22T23:38:41.080Z root 415 1 0 23:31:10 ? 0:00 /usr/lib/hal/hald --daemon=yes
1522023-09-22T23:38:41.083Z root 9 1 0 23:31:03 ? 0:03 /lib/svc/bin/svc.startd
1532023-09-22T23:38:41.086Z root 11 1 0 23:31:04 ? 0:08 /lib/svc/bin/svc.configd
1542023-09-22T23:38:41.089Z netcfg 52 1 0 23:31:07 ? 0:00 /lib/inet/netcfgd
1552023-09-22T23:38:41.093Z root 552 483 0 23:31:10 ? 0:00 /usr/lib/hal/hald-addon-network-discovery
1562023-09-22T23:38:41.096Z dladm 46 1 0 23:31:07 ? 0:00 /sbin/dlmgmtd
1572023-09-22T23:38:41.099Z root 161 1 0 23:31:09 ? 0:00 /usr/lib/zones/zonestatd
1582023-09-22T23:38:41.101Z root 198 1 0 23:31:09 ? 0:00 devfsadmd
1592023-09-22T23:38:41.105Z netadm 54 1 0 23:31:07 ? 0:00 /lib/inet/ipmgmtd
1602023-09-22T23:38:41.108Z root 83 1 0 23:31:08 ? 0:00 /lib/inet/in.mpathd
1612023-09-22T23:38:41.111Z root 204 1 0 23:31:09 ? 0:00 /usr/lib/dbus-daemon --system
1622023-09-22T23:38:41.114Z build 1153 1125 0 23:32:55 ? 0:02 prstat -d d -mLc 1
1632023-09-22T23:38:41.117Z root 170 1 0 23:31:09 ? 0:00 /usr/lib/sysevent/syseventd
1642023-09-22T23:38:41.120Z root 565 483 0 23:31:10 ? 0:00 /usr/lib/hal/hald-addon-acpi
1652023-09-22T23:38:41.123Z root 104 1 0 23:31:08 ? 0:00 /usr/lib/pfexecd
1662023-09-22T23:38:41.126Z root 561 483 0 23:31:10 ? 0:00 /usr/lib/hal/hald-addon-cpufreq
1672023-09-22T23:38:41.129Z root 1111 1 0 23:32:21 ? 0:00 /usr/perl5/bin/perl /usr/lib/intrd
1682023-09-22T23:38:41.132Z root 972 970 0 23:32:08 ? 0:00 /usr/lib/autofs/automountd
1692023-09-22T23:38:41.135Z root 178 1 0 23:31:09 ? 0:00 /usr/lib/power/powerd
1702023-09-22T23:38:41.138Z root 914 1 0 23:32:07 ? 0:00 /usr/sbin/cron
1712023-09-22T23:38:41.141Z root 967 1 0 23:32:08 ? 0:00 /usr/lib/inet/in.ndpd
1722023-09-22T23:38:41.144Z root 1108 1107 0 23:32:18 ? 0:03 /opt/buildomat/lib/agent run
1732023-09-22T23:38:41.148Z root 483 415 0 23:31:10 ? 0:00 hald-runner
1742023-09-22T23:38:41.151Z root 831 1 0 23:32:07 ? 0:00 /usr/lib/picl/picld
1752023-09-22T23:38:41.153Z root 876 1 0 23:32:07 ? 0:00 /usr/lib/utmpd
1762023-09-22T23:38:41.157Z root 877 9 0 23:32:07 console 0:00 /usr/lib/saf/ttymon -g -d /dev/console -l console -m ldterm,ttcompat -h -p ip-1
1772023-09-22T23:38:41.159Z root 862 1 0 23:32:07 ? 0:00 /usr/sbin/nscd
1782023-09-22T23:38:41.162Z root 642 1 0 23:31:11 ? 0:00 /sbin/dhcpagent
1792023-09-22T23:38:41.165Z root 1107 9 0 23:32:18 ? 0:00 /usr/bin/ctrun -l child -o noorphan,regent /opt/buildomat/lib/agent run
1802023-09-22T23:38:41.168Z root 895 9 0 23:32:07 ? 0:00 /usr/lib/saf/sac -t 300
1812023-09-22T23:38:41.171Z build 1148 1125 0 23:32:54 ? 0:00 /bin/bash /tmp/codp47uxak4kJYRH.sh
1822023-09-22T23:38:41.174Z root 1093 1 0 23:32:11 ? 0:00 /usr/sbin/sshd
1832023-09-22T23:38:41.178Z root 970 1 0 23:32:08 ? 0:00 /usr/lib/autofs/automountd
1842023-09-22T23:38:41.181Z build 1125 1108 0 23:32:51 ? 0:00 /bin/bash /tmp/codp47uxak4kJYRH.sh
1852023-09-22T23:38:41.184Z root 905 895 0 23:32:07 ? 0:00 /usr/lib/saf/ttymon
1862023-09-22T23:38:41.187Z root 1013 1 0 23:32:08 ? 0:00 /usr/lib/fm/fmd/fmd
1872023-09-22T23:38:41.190Z build 1150 1148 0 23:32:54 ? 0:00 sleep 2400
1882023-09-22T23:38:41.192Z root 982 1 0 23:32:08 ? 0:00 /usr/sbin/syslogd
1892023-09-22T23:38:41.195Z daemon 948 1 0 23:32:08 ? 0:00 /usr/sbin/rpcbind
1902023-09-22T23:38:41.198Z build 1154 1125 0 23:32:55 ? 0:00 iostat -T d -xn 1
1912023-09-22T23:38:41.201Z root 1037 1 0 23:32:09 ? 0:00 /usr/lib/inet/inetd start
1922023-09-22T23:38:41.204Z root 1008 1 0 23:32:08 ? 0:00 /usr/sbin/ntpd -p /var/run/ntp.pid -g
1932023-09-22T23:38:41.207Z build 1155 1125 0 23:32:55 ? 0:00 mpstat -T d 1
1942023-09-22T23:38:41.210Z build 1156 1125 0 23:32:55 ? 0:00 vmstat -T d -p 1
1952023-09-22T23:38:41.213Z root 1157 1125 0 23:32:55 ? 0:01 dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1962023-09-22T23:38:41.216Z build 1213 1125 0 23:38:41 ? 0:00 ps -ef
1972023-09-22T23:38:46.068Zprocess exited: duration 349506 ms, exit code 0
1982023-09-22T23:38:46.071Zexec warning: stdio descriptors remain open after task exit; waiting 60 seconds for them to close
1992023-09-22T23:39:46.108Zexec warning: stdout descriptor may be held open by a background process; giving up!
2002023-09-22T23:39:46.111Zexec warning: stderr descriptor may be held open by a background process; giving up!
 
2012023-09-22T23:39:46.117Zfound 14 output files
2022023-09-22T23:39:46.127Zuploading: /tmp/perf-ES-16384-EC-1280.csv (1059242 bytes)
2032023-09-22T23:39:47.671Zuploaded: /tmp/perf-ES-16384-EC-1280.csv
2042023-09-22T23:39:47.674Zuploading: /tmp/perf-ES-16384-EC-640.csv (1058676 bytes)
2052023-09-22T23:39:48.706Zuploaded: /tmp/perf-ES-16384-EC-640.csv
2062023-09-22T23:39:48.710Zuploading: /tmp/perfout.txt (45207 bytes)
2072023-09-22T23:39:49.719Zuploaded: /tmp/perfout.txt
2082023-09-22T23:39:49.727Zuploading: /tmp/debug/df.txt (1270 bytes)
2092023-09-22T23:39:50.734Zuploaded: /tmp/debug/df.txt
2102023-09-22T23:39:50.737Zuploading: /tmp/debug/dtrace.txt (210042 bytes)
2112023-09-22T23:39:51.752Zuploaded: /tmp/debug/dtrace.txt
2122023-09-22T23:39:51.755Zuploading: /tmp/debug/iostat.txt (125045 bytes)
2132023-09-22T23:39:51.763Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 125045 -> 126555
2142023-09-22T23:39:52.770Zuploaded: /tmp/debug/iostat.txt
2152023-09-22T23:39:52.773Zuploading: /tmp/debug/mpstat.txt (301296 bytes)
2162023-09-22T23:39:52.896Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 301296 -> 306413
2172023-09-22T23:39:53.903Zuploaded: /tmp/debug/mpstat.txt
2182023-09-22T23:39:53.906Zuploading: /tmp/debug/paging.txt (54840 bytes)
2192023-09-22T23:39:53.911Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 54840 -> 55776
2202023-09-22T23:39:54.917Zuploaded: /tmp/debug/paging.txt
2212023-09-22T23:39:54.921Zuploading: /tmp/debug/prstat.txt (610212 bytes)
2222023-09-22T23:39:54.938Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 610212 -> 621911
2232023-09-22T23:39:55.945Zuploaded: /tmp/debug/prstat.txt
2242023-09-22T23:39:55.948Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2252023-09-22T23:39:56.955Zuploaded: /tmp/debug/psrinfo.txt
2262023-09-22T23:39:56.960Zuploading: /tmp/debug/test_perf.txt (51091 bytes)
2272023-09-22T23:39:57.970Zuploaded: /tmp/debug/test_perf.txt
2282023-09-22T23:39:57.976Zuploading: /tmp/dsc/downstairs-8810.txt (5347 bytes)
2292023-09-22T23:39:58.984Zuploaded: /tmp/dsc/downstairs-8810.txt
2302023-09-22T23:39:58.987Zuploading: /tmp/dsc/downstairs-8820.txt (5347 bytes)
2312023-09-22T23:39:59.994Zuploaded: /tmp/dsc/downstairs-8820.txt
2322023-09-22T23:39:59.997Zuploading: /tmp/dsc/downstairs-8830.txt (5348 bytes)
2332023-09-22T23:40:01.005Zuploaded: /tmp/dsc/downstairs-8830.txt