01HCN263AG5KGKM5PVQWAKP5VF: 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: 01HCN26KP1R6SNMRK8QH7DDB9F

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12023-10-13T18:13:47.241Zjob dependencies complete; ready to run (waiting for 29 m 48 s)
22023-10-13T18:15:36.229Zjob assigned to worker 01HCN3WD81AD2M3MT6ED6N60NB (queued for 1 m 48 s)
32023-10-13T18:15:36.353Zdownloading input: /input/rbuild/out/crucible-nightly.sha256.txt
42023-10-13T18:15:36.388Zdownloaded input: /input/rbuild/out/crucible-nightly.sha256.txt
52023-10-13T18:15:36.391Zdownloading input: /input/rbuild/out/crucible-nightly.tar.gz
62023-10-13T18:15:56.208Zdownloaded input: /input/rbuild/out/crucible-nightly.tar.gz
72023-10-13T18:15:56.214Zdownloading input: /input/rbuild/out/crucible-pantry.sha256.txt
82023-10-13T18:15:56.222Zdownloaded input: /input/rbuild/out/crucible-pantry.sha256.txt
92023-10-13T18:15:56.225Zdownloading input: /input/rbuild/out/crucible-pantry.tar.gz
102023-10-13T18:16:08.736Zdownloaded input: /input/rbuild/out/crucible-pantry.tar.gz
112023-10-13T18:16:08.743Zdownloading input: /input/rbuild/out/crucible.sha256.txt
122023-10-13T18:16:08.752Zdownloaded input: /input/rbuild/out/crucible.sha256.txt
132023-10-13T18:16:08.755Zdownloading input: /input/rbuild/out/crucible.tar.gz
142023-10-13T18:16:19.543Zdownloaded input: /input/rbuild/out/crucible.tar.gz
152023-10-13T18:16:19.970Zdownloading input: /input/rbuild/work/rbins/crucible-downstairs.gz
162023-10-13T18:16:22.790Zdownloaded input: /input/rbuild/work/rbins/crucible-downstairs.gz
172023-10-13T18:16:22.793Zdownloading input: /input/rbuild/work/rbins/crucible-hammer.gz
182023-10-13T18:16:25.629Zdownloaded input: /input/rbuild/work/rbins/crucible-hammer.gz
192023-10-13T18:16:25.641Zdownloading input: /input/rbuild/work/rbins/crudd.gz
202023-10-13T18:16:28.956Zdownloaded input: /input/rbuild/work/rbins/crudd.gz
212023-10-13T18:16:28.966Zdownloading input: /input/rbuild/work/rbins/crutest.gz
222023-10-13T18:16:29.930Zdownloaded input: /input/rbuild/work/rbins/crutest.gz
232023-10-13T18:16:29.934Zdownloading input: /input/rbuild/work/rbins/dsc.gz
242023-10-13T18:16:34.587Zdownloaded input: /input/rbuild/work/rbins/dsc.gz
252023-10-13T18:16:34.604Zdownloading input: /input/rbuild/work/scripts/crudd-speed-battery.sh
262023-10-13T18:16:34.620Zdownloaded input: /input/rbuild/work/scripts/crudd-speed-battery.sh
272023-10-13T18:16:34.630Zdownloading input: /input/rbuild/work/scripts/perf-downstairs-tick.d
282023-10-13T18:16:34.640Zdownloaded input: /input/rbuild/work/scripts/perf-downstairs-tick.d
292023-10-13T18:16:34.646Zdownloading input: /input/rbuild/work/scripts/test_mem.sh
302023-10-13T18:16:34.658Zdownloaded input: /input/rbuild/work/scripts/test_mem.sh
312023-10-13T18:16:34.663Zdownloading input: /input/rbuild/work/scripts/test_perf.sh
322023-10-13T18:16:34.679Zdownloaded input: /input/rbuild/work/scripts/test_perf.sh
332023-10-13T18:16:34.692Zdownloading input: /input/rbuild/tmp/cargo-test-out.log
342023-10-13T18:16:35.753Zdownloaded input: /input/rbuild/tmp/cargo-test-out.log
 
352023-10-13T18:16:36.186Zstarting task 0: "setup"
362023-10-13T18:16:36.785Z++ uname -s
372023-10-13T18:16:36.793Z+ kern=SunOS
382023-10-13T18:16:36.801Z+ case "$kern" in
392023-10-13T18:16:36.807Z+ groupadd -g 12345 build
402023-10-13T18:16:36.818Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
412023-10-13T18:16:38.507Z+ zfs create -o mountpoint=/work rpool/work
422023-10-13T18:16:38.582Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
432023-10-13T18:16:38.619Z+ home_fs=zfs
442023-10-13T18:16:38.667Z+ [[ zfs == autofs ]]
452023-10-13T18:16:39.118Z+ mkdir -p /home/build
462023-10-13T18:16:39.135Z+ chown build:build /home/build /work
472023-10-13T18:16:40.599Z+ chmod 0700 /home/build /work
482023-10-13T18:16:41.029Zprocess exited: duration 4376 ms, exit code 0
 
492023-10-13T18:16:41.471Zstarting task 1: "authentication"
502023-10-13T18:16:41.509ZWARNING: job store has no value for "GITHUB_TOKEN"; waiting for a value...
512023-10-13T18:18:09.209Zprocess exited: duration 87720 ms, exit code 0
 
522023-10-13T18:18:10.100Zstarting task 2: "build"
532023-10-13T18:18:10.139Z+ banner cores
542023-10-13T18:18:10.183Z
552023-10-13T18:18:10.211Z #### #### ##### ###### ####
562023-10-13T18:18:10.222Z # # # # # # # #
572023-10-13T18:18:10.233Z # # # # # ##### ####
582023-10-13T18:18:10.238Z # # # ##### # #
592023-10-13T18:18:10.242Z # # # # # # # # #
602023-10-13T18:18:10.246Z #### #### # # ###### ####
612023-10-13T18:18:10.250Z
622023-10-13T18:18:10.254Z+ pfexec coreadm -i /tmp/core.%f.%p -g /tmp/core.%f.%p -e global -e log -e proc-setid -e global-setid
632023-10-13T18:18:10.259Z+ banner unpack
642023-10-13T18:18:10.263Z
652023-10-13T18:18:10.267Z # # # # ##### ## #### # #
662023-10-13T18:18:10.270Z # # ## # # # # # # # # #
672023-10-13T18:18:10.273Z # # # # # # # # # # ####
682023-10-13T18:18:10.277Z # # # # # ##### ###### # # #
692023-10-13T18:18:10.281Z # # # ## # # # # # # #
702023-10-13T18:18:10.286Z #### # # # # # #### # #
712023-10-13T18:18:10.290Z
722023-10-13T18:18:10.293Z+ mkdir -p /var/tmp/bins
732023-10-13T18:18:10.297Z+ for t in "$input/rbins/"*.gz
742023-10-13T18:18:10.300Z++ basename /input/rbuild/work/rbins/crucible-downstairs.gz
752023-10-13T18:18:10.305Z+ b=crucible-downstairs.gz
762023-10-13T18:18:10.310Z+ b=crucible-downstairs
772023-10-13T18:18:10.313Z+ gunzip
782023-10-13T18:18:10.865Z+ chmod +x /var/tmp/bins/crucible-downstairs
792023-10-13T18:18:10.869Z+ for t in "$input/rbins/"*.gz
802023-10-13T18:18:10.872Z++ basename /input/rbuild/work/rbins/crucible-hammer.gz
812023-10-13T18:18:10.875Z+ b=crucible-hammer.gz
822023-10-13T18:18:10.879Z+ b=crucible-hammer
832023-10-13T18:18:10.882Z+ gunzip
842023-10-13T18:18:11.411Z+ chmod +x /var/tmp/bins/crucible-hammer
852023-10-13T18:18:11.415Z+ for t in "$input/rbins/"*.gz
862023-10-13T18:18:11.418Z++ basename /input/rbuild/work/rbins/crudd.gz
872023-10-13T18:18:11.421Z+ b=crudd.gz
882023-10-13T18:18:11.426Z+ b=crudd
892023-10-13T18:18:11.429Z+ gunzip
902023-10-13T18:18:11.927Z+ chmod +x /var/tmp/bins/crudd
912023-10-13T18:18:11.932Z+ for t in "$input/rbins/"*.gz
922023-10-13T18:18:11.952Z++ basename /input/rbuild/work/rbins/crutest.gz
932023-10-13T18:18:11.962Z+ b=crutest.gz
942023-10-13T18:18:11.969Z+ b=crutest
952023-10-13T18:18:11.987Z+ gunzip
962023-10-13T18:18:12.530Z+ chmod +x /var/tmp/bins/crutest
972023-10-13T18:18:12.533Z+ for t in "$input/rbins/"*.gz
982023-10-13T18:18:12.536Z++ basename /input/rbuild/work/rbins/dsc.gz
992023-10-13T18:18:12.539Z+ b=dsc.gz
1002023-10-13T18:18:12.546Z+ b=dsc
1012023-10-13T18:18:12.549Z+ gunzip
1022023-10-13T18:18:12.896Z+ chmod +x /var/tmp/bins/dsc
1032023-10-13T18:18:12.899Z+ export BINDIR=/var/tmp/bins
1042023-10-13T18:18:12.902Z+ BINDIR=/var/tmp/bins
1052023-10-13T18:18:12.905Z+ banner setup
1062023-10-13T18:18:12.908Z
1072023-10-13T18:18:12.911Z #### ###### ##### # # #####
1082023-10-13T18:18:12.914Z # # # # # # #
1092023-10-13T18:18:12.916Z #### ##### # # # # #
1102023-10-13T18:18:12.919Z # # # # # #####
1112023-10-13T18:18:12.923Z # # # # # # #
1122023-10-13T18:18:12.925Z #### ###### # #### #
1132023-10-13T18:18:12.928Z
1142023-10-13T18:18:12.931Z+ pfexec plimit -n 9123456 1125
1152023-10-13T18:18:12.934Z+ echo 'Setup self timeout'
1162023-10-13T18:18:12.937ZSetup self timeout
1172023-10-13T18:18:12.940Z+ jobpid=1125
1182023-10-13T18:18:12.943Z+ echo 'Setup debug logging'
1192023-10-13T18:18:12.947ZSetup debug logging
1202023-10-13T18:18:12.950Z+ mkdir /tmp/debug
1212023-10-13T18:18:12.952Z+ sleep 2400
1222023-10-13T18:18:12.955Z+ psrinfo -v
1232023-10-13T18:18:12.958Z+ df -h
1242023-10-13T18:18:12.961Z+ prstat -d d -mLc 1
1252023-10-13T18:18:12.964Z+ iostat -T d -xn 1
1262023-10-13T18:18:12.967Z+ mpstat -T d 1
1272023-10-13T18:18:12.970Z+ vmstat -T d -p 1
1282023-10-13T18:18:12.973Z+ banner start
1292023-10-13T18:18:12.976Z+ pfexec dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1302023-10-13T18:18:12.979Z
1312023-10-13T18:18:12.982Z #### ##### ## ##### #####
1322023-10-13T18:18:12.984Z # # # # # # #
1332023-10-13T18:18:12.987Z #### # # # # # #
1342023-10-13T18:18:12.991Z # # ###### ##### #
1352023-10-13T18:18:12.994Z # # # # # # # #
1362023-10-13T18:18:13.000Z #### # # # # # #
1372023-10-13T18:18:13.007Z
1382023-10-13T18:18:13.012Z+ bash /input/rbuild/work/scripts/test_perf.sh
1392023-10-13T18:23:35.411Z+ echo '0 was our result'
1402023-10-13T18:23:35.421Z0 was our result
1412023-10-13T18:23:35.469ZTest finished
1422023-10-13T18:23:35.473Z+ echo 'Test finished'
1432023-10-13T18:23:35.476Z+ sleep 5
1442023-10-13T18:23:40.361Z+ ps -ef
1452023-10-13T18:23:40.531Z UID PID PPID C STIME TTY TIME CMD
1462023-10-13T18:23:40.534Z root 0 0 0 18:13:50 ? 0:02 sched
1472023-10-13T18:23:40.537Z root 4 0 0 18:13:48 ? 0:00 kcfpoold
1482023-10-13T18:23:40.541Z root 5 0 0 18:13:50 ? 4:10 zpool-rpool
1492023-10-13T18:23:40.544Z root 1 0 0 18:13:51 ? 0:00 /sbin/init
1502023-10-13T18:23:40.550Z root 2 0 0 18:13:51 ? 0:00 pageout
1512023-10-13T18:23:40.553Z root 3 0 0 18:13:51 ? 0:04 fsflush
1522023-10-13T18:23:40.556Z root 891 1 0 18:15:21 ? 0:00 /usr/lib/utmpd
1532023-10-13T18:23:40.559Z root 9 1 0 18:13:52 ? 0:03 /lib/svc/bin/svc.startd
1542023-10-13T18:23:40.562Z root 11 1 0 18:13:52 ? 0:07 /lib/svc/bin/svc.configd
1552023-10-13T18:23:40.565Z build 1148 1125 0 18:18:13 ? 0:00 /bin/bash /tmp/ySIVNlTZuwxiAphx.sh
1562023-10-13T18:23:40.568Z root 83 1 0 18:13:56 ? 0:00 /lib/inet/in.mpathd
1572023-10-13T18:23:40.603Z dladm 46 1 0 18:13:55 ? 0:00 /sbin/dlmgmtd
1582023-10-13T18:23:40.607Z root 152 1 0 18:13:57 ? 0:00 /usr/lib/zones/zonestatd
1592023-10-13T18:23:40.610Z root 447 1 0 18:13:58 ? 0:00 /usr/lib/hal/hald --daemon=yes
1602023-10-13T18:23:40.612Z netadm 54 1 0 18:13:55 ? 0:00 /lib/inet/ipmgmtd
1612023-10-13T18:23:40.615Z netcfg 52 1 0 18:13:55 ? 0:00 /lib/inet/netcfgd
1622023-10-13T18:23:40.618Z root 175 1 0 18:13:57 ? 0:00 /usr/lib/sysevent/syseventd
1632023-10-13T18:23:40.621Z root 642 1 0 18:13:59 ? 0:00 /sbin/dhcpagent
1642023-10-13T18:23:40.624Z root 142 1 0 18:13:57 ? 0:00 /usr/lib/power/powerd
1652023-10-13T18:23:40.627Z root 1093 1 0 18:15:27 ? 0:00 /usr/sbin/sshd
1662023-10-13T18:23:40.630Z root 104 1 0 18:13:57 ? 0:00 /usr/lib/pfexecd
1672023-10-13T18:23:40.633Z root 887 1 0 18:15:21 ? 0:00 /usr/sbin/nscd
1682023-10-13T18:23:40.637Z root 963 961 0 18:15:22 ? 0:00 /usr/lib/autofs/automountd
1692023-10-13T18:23:40.640Z root 836 1 0 18:15:11 ? 0:00 /usr/lib/picl/picld
1702023-10-13T18:23:40.643Z root 186 1 0 18:13:57 ? 0:00 devfsadmd
1712023-10-13T18:23:40.652Z root 286 1 0 18:13:57 ? 0:00 /usr/lib/dbus-daemon --system
1722023-10-13T18:23:40.655Z root 479 447 0 18:13:58 ? 0:00 hald-runner
1732023-10-13T18:23:40.658Z root 585 479 0 18:13:58 ? 0:00 /usr/lib/hal/hald-addon-network-discovery
1742023-10-13T18:23:40.661Z root 961 1 0 18:15:22 ? 0:00 /usr/lib/autofs/automountd
1752023-10-13T18:23:40.664Z build 1150 1148 0 18:18:13 ? 0:00 sleep 2400
1762023-10-13T18:23:40.667Z root 587 479 0 18:13:58 ? 0:00 /usr/lib/hal/hald-addon-cpufreq
1772023-10-13T18:23:40.672Z root 588 479 0 18:13:58 ? 0:00 /usr/lib/hal/hald-addon-acpi
1782023-10-13T18:23:40.675Z root 901 9 0 18:15:22 ? 0:00 /usr/lib/saf/sac -t 300
1792023-10-13T18:23:40.678Z daemon 886 1 0 18:15:21 ? 0:00 /usr/sbin/rpcbind
1802023-10-13T18:23:40.681Z build 1125 1108 0 18:18:10 ? 0:00 /bin/bash /tmp/ySIVNlTZuwxiAphx.sh
1812023-10-13T18:23:40.684Z root 905 901 0 18:15:22 ? 0:00 /usr/lib/saf/ttymon
1822023-10-13T18:23:40.687Z root 1022 1 0 18:15:23 ? 0:00 /usr/lib/fm/fmd/fmd
1832023-10-13T18:23:40.690Z root 941 9 0 18:15:22 console 0:00 /usr/lib/saf/ttymon -g -d /dev/console -l console -m ldterm,ttcompat -h -p ip-1
1842023-10-13T18:23:40.693Z root 1111 1 0 18:15:37 ? 0:00 /usr/perl5/bin/perl /usr/lib/intrd
1852023-10-13T18:23:40.696Z build 1153 1125 0 18:18:13 ? 0:02 prstat -d d -mLc 1
1862023-10-13T18:23:40.699Z root 1011 1 0 18:15:23 ? 0:00 /usr/sbin/syslogd
1872023-10-13T18:23:40.702Z root 917 1 0 18:15:22 ? 0:00 /usr/sbin/cron
1882023-10-13T18:23:40.704Z root 1107 9 0 18:15:35 ? 0:00 /usr/bin/ctrun -l child -o noorphan,regent /opt/buildomat/lib/agent run
1892023-10-13T18:23:40.707Z root 951 1 0 18:15:22 ? 0:00 /usr/lib/inet/in.ndpd
1902023-10-13T18:23:40.710Z root 983 1 0 18:15:23 ? 0:00 /usr/sbin/ntpd -p /var/run/ntp.pid -g
1912023-10-13T18:23:40.713Z build 1156 1125 0 18:18:13 ? 0:00 vmstat -T d -p 1
1922023-10-13T18:23:40.716Z root 1108 1107 0 18:15:35 ? 0:03 /opt/buildomat/lib/agent run
1932023-10-13T18:23:40.719Z build 1155 1125 0 18:18:13 ? 0:00 mpstat -T d 1
1942023-10-13T18:23:40.722Z build 1154 1125 0 18:18:13 ? 0:00 iostat -T d -xn 1
1952023-10-13T18:23:40.725Z root 1037 1 0 18:15:24 ? 0:00 /usr/lib/inet/inetd start
1962023-10-13T18:23:40.728Z root 1157 1125 0 18:18:13 ? 0:02 dtrace -Z -s /input/rbuild/work/scripts/perf-downstairs-tick.d
1972023-10-13T18:23:40.730Z build 1213 1125 0 18:23:40 ? 0:00 ps -ef
1982023-10-13T18:23:45.536Zprocess exited: duration 330439 ms, exit code 0
1992023-10-13T18:23:45.539Zexec warning: stdio descriptors remain open after task exit; waiting 60 seconds for them to close
2002023-10-13T18:24:45.577Zexec warning: stdout descriptor may be held open by a background process; giving up!
2012023-10-13T18:24:45.581Zexec warning: stderr descriptor may be held open by a background process; giving up!
 
2022023-10-13T18:24:45.595Zfound 14 output files
2032023-10-13T18:24:45.651Zuploading: /tmp/perf-ES-16384-EC-1280.csv (1050714 bytes)
2042023-10-13T18:24:46.656Zuploaded: /tmp/perf-ES-16384-EC-1280.csv
2052023-10-13T18:24:46.663Zuploading: /tmp/perf-ES-16384-EC-640.csv (1050707 bytes)
2062023-10-13T18:24:48.099Zuploaded: /tmp/perf-ES-16384-EC-640.csv
2072023-10-13T18:24:48.102Zuploading: /tmp/perfout.txt (45910 bytes)
2082023-10-13T18:24:49.113Zuploaded: /tmp/perfout.txt
2092023-10-13T18:24:49.116Zuploading: /tmp/debug/df.txt (1270 bytes)
2102023-10-13T18:24:50.124Zuploaded: /tmp/debug/df.txt
2112023-10-13T18:24:50.127Zuploading: /tmp/debug/dtrace.txt (231048 bytes)
2122023-10-13T18:24:51.147Zuploaded: /tmp/debug/dtrace.txt
2132023-10-13T18:24:51.159Zuploading: /tmp/debug/iostat.txt (118485 bytes)
2142023-10-13T18:24:51.170Zupload warning: file "/tmp/debug/iostat.txt" changed size mid upload: 118485 -> 120285
2152023-10-13T18:24:52.178Zuploaded: /tmp/debug/iostat.txt
2162023-10-13T18:24:52.181Zuploading: /tmp/debug/mpstat.txt (286590 bytes)
2172023-10-13T18:24:52.195Zupload warning: file "/tmp/debug/mpstat.txt" changed size mid upload: 286590 -> 291693
2182023-10-13T18:24:53.203Zuploaded: /tmp/debug/mpstat.txt
2192023-10-13T18:24:53.210Zuploading: /tmp/debug/paging.txt (51515 bytes)
2202023-10-13T18:24:53.217Zupload warning: file "/tmp/debug/paging.txt" changed size mid upload: 51515 -> 52593
2212023-10-13T18:24:54.224Zuploaded: /tmp/debug/paging.txt
2222023-10-13T18:24:54.232Zuploading: /tmp/debug/prstat.txt (580222 bytes)
2232023-10-13T18:24:54.253Zupload warning: file "/tmp/debug/prstat.txt" changed size mid upload: 580222 -> 590573
2242023-10-13T18:24:55.676Zuploaded: /tmp/debug/prstat.txt
2252023-10-13T18:24:56.085Zuploading: /tmp/debug/psrinfo.txt (1528 bytes)
2262023-10-13T18:24:56.293Zuploaded: /tmp/debug/psrinfo.txt
2272023-10-13T18:24:56.296Zuploading: /tmp/debug/test_perf.txt (51795 bytes)
2282023-10-13T18:24:57.307Zuploaded: /tmp/debug/test_perf.txt
2292023-10-13T18:24:57.314Zuploading: /tmp/dsc/downstairs-8810.txt (4952 bytes)
2302023-10-13T18:24:58.322Zuploaded: /tmp/dsc/downstairs-8810.txt
2312023-10-13T18:24:58.325Zuploading: /tmp/dsc/downstairs-8820.txt (4947 bytes)
2322023-10-13T18:24:59.333Zuploaded: /tmp/dsc/downstairs-8820.txt
2332023-10-13T18:24:59.337Zuploading: /tmp/dsc/downstairs-8830.txt (4951 bytes)
2342023-10-13T18:25:00.345Zuploaded: /tmp/dsc/downstairs-8830.txt