01KTB53XJVQ7X103R0DFNZX1J7: falcon

BasicConfig {
    output_rules: [
        "/work/*",
    ],
    rust_toolchain: None,
    target: Some(
        "lab-3.0-gimlet",
    ),
    access_repos: [],
    publish: [],
    skip_clone: true,
}

Buildomat Job: 01KTB54MQ34JNFT035BXBV71YJ

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-06-05T06:01:23.077Zjob dependencies complete; ready to run (waiting for 14 m 4 s)
22026-06-05T06:10:51.597Zjob assigned to worker 01KTB69Q93J0WK1VZBZ3S76T9N [factory edgar, gimlet/BRM42220010/1085] (queued for 9 m 28 s)
32026-06-05T06:10:52.298Zdownloading input: /input/build/work/debug/ddmadm
42026-06-05T06:11:14.988Zdownloaded input: /input/build/work/debug/ddmadm
52026-06-05T06:11:14.988Zdownloading input: /input/build/work/debug/ddmd
62026-06-05T06:11:42.101Zdownloaded input: /input/build/work/debug/ddmd
72026-06-05T06:11:42.101Zdownloading input: /input/build/work/debug/mgadm
82026-06-05T06:12:09.360Zdownloaded input: /input/build/work/debug/mgadm
92026-06-05T06:12:09.360Zdownloading input: /input/build/work/debug/mgd
102026-06-05T06:12:39.637Zdownloaded input: /input/build/work/debug/mgd
112026-06-05T06:12:39.661Zdownloading input: /input/build/work/release/ddmadm
122026-06-05T06:12:41.781Zdownloaded input: /input/build/work/release/ddmadm
132026-06-05T06:12:41.781Zdownloading input: /input/build/work/release/ddmd
142026-06-05T06:12:44.334Zdownloaded input: /input/build/work/release/ddmd
152026-06-05T06:12:44.334Zdownloading input: /input/build/work/release/falcon-lab
162026-06-05T06:12:46.252Zdownloaded input: /input/build/work/release/falcon-lab
172026-06-05T06:12:46.277Zdownloading input: /input/build/work/release/mgadm
182026-06-05T06:12:48.874Zdownloaded input: /input/build/work/release/mgadm
192026-06-05T06:12:48.874Zdownloading input: /input/build/work/release/mgd
202026-06-05T06:12:52.214Zdownloaded input: /input/build/work/release/mgd
212026-06-05T06:12:52.240Zdownloading input: /input/build-interop/work/testbed.tar.gz
222026-06-05T06:13:01.232Zdownloaded input: /input/build-interop/work/testbed.tar.gz
232026-06-05T06:13:01.233Zdownloading input: /input/build-interop/work/dhcp-server
242026-06-05T06:13:03.829Zdownloaded input: /input/build-interop/work/dhcp-server
 
252026-06-05T06:13:03.829Zstarting task 0: "setup"
262026-06-05T06:13:03.854Z++ uname -s
272026-06-05T06:13:03.854Z+ kern=SunOS
282026-06-05T06:13:03.854Z+ build_user=build
292026-06-05T06:13:03.854Z+ build_uid=12345
302026-06-05T06:13:03.854Z+ work_dir=/work
312026-06-05T06:13:03.854Z+ input_dir=/input
322026-06-05T06:13:03.854Z+ [[ 0 == 12345 ]]
332026-06-05T06:13:03.854Z+ case "$kern" in
342026-06-05T06:13:03.854Z+ groupadd -g 12345 build
352026-06-05T06:13:03.879Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-06-05T06:13:05.868Z+ zfs create -o mountpoint=/work rpool/work
372026-06-05T06:13:06.933Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-06-05T06:13:06.957Z+ home_fs=
392026-06-05T06:13:06.957Z+ [[ '' == autofs ]]
402026-06-05T06:13:06.957Z+ mkdir -p /home/build
412026-06-05T06:13:06.957Z+ chown build:build /home/build /work
422026-06-05T06:13:07.945Z+ chmod 0700 /home/build /work
432026-06-05T06:13:07.970Zprocess exited: duration 4119 ms, exit code 0
 
442026-06-05T06:13:08.019Zstarting task 1: "authentication"
452026-06-05T06:13:08.113Zprocess exited: duration 94 ms, exit code 0
 
462026-06-05T06:13:08.163Zstarting task 2: "build"
472026-06-05T06:13:08.187Z+ set -e
482026-06-05T06:13:08.188Z+ set -o pipefail
492026-06-05T06:13:08.188Z+ banner zpool
502026-06-05T06:13:08.188Z
512026-06-05T06:13:08.188Z ###### ##### #### #### #
522026-06-05T06:13:08.188Z # # # # # # # #
532026-06-05T06:13:08.188Z # # # # # # # #
542026-06-05T06:13:08.188Z # ##### # # # # #
552026-06-05T06:13:08.189Z # # # # # # #
562026-06-05T06:13:08.189Z ###### # #### #### ######
572026-06-05T06:13:08.189Z
582026-06-05T06:13:08.189Z++ pfexec diskinfo -pH
592026-06-05T06:13:08.189Z++ sort -k8 -n -r
602026-06-05T06:13:08.213Z++ head -1
612026-06-05T06:13:08.213Z++ awk '{print $2}'
622026-06-05T06:13:08.238Z+ DISK=c9t0014EE81000BC3B1d0
632026-06-05T06:13:08.239Z+ export DISK
642026-06-05T06:13:08.239Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
652026-06-05T06:13:08.303Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
662026-06-05T06:13:08.330Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
672026-06-05T06:13:08.444Z+ trim_response=true
682026-06-05T06:13:08.468Z+ [[ true =~ true ]]
692026-06-05T06:13:08.468Z+ pfexec zpool trim cpool
702026-06-05T06:13:08.468Z+ true
712026-06-05T06:13:08.468Z++ zpool status -t cpool
722026-06-05T06:13:08.492Z+ trim_status=' pool: cpool
732026-06-05T06:13:08.493Z state: ONLINE
742026-06-05T06:13:08.493Z scan: none requested
752026-06-05T06:13:08.493Zconfig:
762026-06-05T06:13:08.493Z
772026-06-05T06:13:08.493Z NAME STATE READ WRITE CKSUM
782026-06-05T06:13:08.493Z cpool ONLINE 0 0 0
792026-06-05T06:13:08.493Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at June 5, 2026 at 06:13:08 AM UTC)
802026-06-05T06:13:08.493Z
812026-06-05T06:13:08.493Zerrors: No known data errors'
822026-06-05T06:13:08.493Z+ [[ pool: cpool
832026-06-05T06:13:08.493Z state: ONLINE
842026-06-05T06:13:08.493Z scan: none requested
852026-06-05T06:13:08.493Zconfig:
862026-06-05T06:13:08.493Z
872026-06-05T06:13:08.493Z NAME STATE READ WRITE CKSUM
882026-06-05T06:13:08.493Z cpool ONLINE 0 0 0
892026-06-05T06:13:08.493Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at June 5, 2026 at 06:13:08 AM UTC)
902026-06-05T06:13:08.493Z
912026-06-05T06:13:08.493Zerrors: No known data errors =~ 100% ]]
922026-06-05T06:13:08.493Z+ sleep 10
932026-06-05T06:13:18.476Z+ true
942026-06-05T06:13:18.501Z++ zpool status -t cpool
952026-06-05T06:13:18.501Z+ trim_status=' pool: cpool
962026-06-05T06:13:18.501Z state: ONLINE
972026-06-05T06:13:18.501Z scan: none requested
982026-06-05T06:13:18.501Zconfig:
992026-06-05T06:13:18.501Z
1002026-06-05T06:13:18.501Z NAME STATE READ WRITE CKSUM
1012026-06-05T06:13:18.501Z cpool ONLINE 0 0 0
1022026-06-05T06:13:18.501Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at June 5, 2026 at 06:13:08 AM UTC)
1032026-06-05T06:13:18.501Z
1042026-06-05T06:13:18.501Zerrors: No known data errors'
1052026-06-05T06:13:18.501Z+ [[ pool: cpool
1062026-06-05T06:13:18.501Z state: ONLINE
1072026-06-05T06:13:18.501Z scan: none requested
1082026-06-05T06:13:18.501Zconfig:
1092026-06-05T06:13:18.501Z
1102026-06-05T06:13:18.501Z NAME STATE READ WRITE CKSUM
1112026-06-05T06:13:18.501Z cpool ONLINE 0 0 0
1122026-06-05T06:13:18.501Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at June 5, 2026 at 06:13:08 AM UTC)
1132026-06-05T06:13:18.501Z
1142026-06-05T06:13:18.501Zerrors: No known data errors =~ 100% ]]
1152026-06-05T06:13:18.501Z+ sleep 10
1162026-06-05T06:13:28.491Z+ true
1172026-06-05T06:13:28.515Z++ zpool status -t cpool
1182026-06-05T06:13:28.515Z+ trim_status=' pool: cpool
1192026-06-05T06:13:28.515Z state: ONLINE
1202026-06-05T06:13:28.515Z scan: none requested
1212026-06-05T06:13:28.515Zconfig:
1222026-06-05T06:13:28.515Z
1232026-06-05T06:13:28.515Z NAME STATE READ WRITE CKSUM
1242026-06-05T06:13:28.515Z cpool ONLINE 0 0 0
1252026-06-05T06:13:28.515Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at June 5, 2026 at 06:13:22 AM UTC)
1262026-06-05T06:13:28.516Z
1272026-06-05T06:13:28.516Zerrors: No known data errors'
1282026-06-05T06:13:28.516Z+ [[ pool: cpool
1292026-06-05T06:13:28.516Z state: ONLINE
1302026-06-05T06:13:28.516Z scan: none requested
1312026-06-05T06:13:28.516Zconfig:
1322026-06-05T06:13:28.516Z
1332026-06-05T06:13:28.516Z NAME STATE READ WRITE CKSUM
1342026-06-05T06:13:28.516Z cpool ONLINE 0 0 0
1352026-06-05T06:13:28.516Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at June 5, 2026 at 06:13:22 AM UTC)
1362026-06-05T06:13:28.516Z
1372026-06-05T06:13:28.516Zerrors: No known data errors =~ 100% ]]
1382026-06-05T06:13:28.516Z+ break
1392026-06-05T06:13:28.516Z+ pfexec chown 12345 /ci
1402026-06-05T06:13:28.516Z+ cd /ci
1412026-06-05T06:13:28.516Z+ export FALCON_DATASET=cpool/falcon
1422026-06-05T06:13:28.516Z+ FALCON_DATASET=cpool/falcon
1432026-06-05T06:13:28.516Z+ banner setup
1442026-06-05T06:13:28.516Z
1452026-06-05T06:13:28.516Z #### ###### ##### # # #####
1462026-06-05T06:13:28.516Z # # # # # # #
1472026-06-05T06:13:28.516Z #### ##### # # # # #
1482026-06-05T06:13:28.516Z # # # # # #####
1492026-06-05T06:13:28.516Z # # # # # # #
1502026-06-05T06:13:28.516Z #### ###### # #### #
1512026-06-05T06:13:28.518Z
1522026-06-05T06:13:28.518Z+ cp /input/build-interop/work/dhcp-server .
1532026-06-05T06:13:28.547Z+ cp /input/build/work/release/falcon-lab .
1542026-06-05T06:13:28.599Z+ cp /input/build/work/release/mgd .
1552026-06-05T06:13:28.695Z+ cp /input/build/work/release/ddmd .
1562026-06-05T06:13:28.778Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1572026-06-05T06:13:28.803Z+ mkdir -p cargo-bay
1582026-06-05T06:13:28.803Z+ mv mgd cargo-bay/
1592026-06-05T06:13:28.803Z+ mv ddmd cargo-bay/
1602026-06-05T06:13:28.803Z+ export EXT_INTERFACE=igb0
1612026-06-05T06:13:28.803Z+ EXT_INTERFACE=igb0
1622026-06-05T06:13:28.803Z++ bmat address ls -f extra -Ho first
1632026-06-05T06:13:28.827Z+ first=10.151.6.164
1642026-06-05T06:13:28.827Z++ bmat address ls -f extra -Ho last
1652026-06-05T06:13:28.851Z+ last=10.151.6.227
1662026-06-05T06:13:28.851Z++ bmat address ls -f extra -Ho gateway
1672026-06-05T06:13:28.875Z+ gw=10.151.6.1
1682026-06-05T06:13:28.876Z++ ipadm show-addr igb0/dhcp -po ADDR
1692026-06-05T06:13:28.876Z++ sed 's#/.*##g'
1702026-06-05T06:13:28.900Z+ server=10.151.6.100
1712026-06-05T06:13:28.900Z+ RUST_LOG=debug
1722026-06-05T06:13:28.900Z+ pfexec ./falcon-lab run trio-unnumbered
1732026-06-05T06:13:28.900Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1742026-06-05T06:13:28.900ZJun 05 06:13:28.881 DEBG using default route interface igb0
1752026-06-05T06:13:28.900ZJun 05 06:13:28.882 DEBG using default route interface igb0
1762026-06-05T06:13:28.900ZJun 05 06:13:28.882 DEBG using default route interface igb0
1772026-06-05T06:13:28.900ZJun 05 06:13:28.882 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1782026-06-05T06:13:28.900ZJun 05 06:13:28.882 INFO starting preflight for deployment mgtriou
1792026-06-05T06:13:28.900ZJun 05 06:13:28.882 INFO propolis-server binary not found
1802026-06-05T06:13:28.900ZJun 05 06:13:28.882 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1812026-06-05T06:13:34.604ZJun 05 06:13:34.588 INFO ovmf fd not found
1822026-06-05T06:13:34.604ZJun 05 06:13:34.588 INFO downloading ovmf
1832026-06-05T06:13:35.039ZJun 05 06:13:35.023 INFO base image for helios-3.0 does not exist, attempting to install
1842026-06-05T06:13:35.039ZJun 05 06:13:35.023 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-3.0_0.raw.xz
1852026-06-05T06:14:04.076ZJun 05 06:14:04.068 INFO extracting image to /tmp/helios-3.0_0.raw
1862026-06-05T06:14:26.874ZJun 05 06:14:26.867 INFO creating zvol cpool/falcon/img/helios-3.0 of size 4508880896
1872026-06-05T06:14:26.923ZJun 05 06:14:26.917 INFO copying image data to zvol
1882026-06-05T06:14:37.284ZJun 05 06:14:37.259 INFO creating zfs snapshot cpool/falcon/img/helios-3.0@base
1892026-06-05T06:14:40.647ZJun 05 06:14:40.641 INFO base image for debian-13.2 does not exist, attempting to install
1902026-06-05T06:14:40.647ZJun 05 06:14:40.641 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1912026-06-05T06:15:01.404ZJun 05 06:15:01.397 INFO extracting image to /tmp/debian-13.2_0.raw
1922026-06-05T06:15:15.215ZJun 05 06:15:15.209 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1932026-06-05T06:15:15.240ZJun 05 06:15:15.235 INFO copying image data to zvol
1942026-06-05T06:15:21.685ZJun 05 06:15:21.675 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1952026-06-05T06:15:25.013ZJun 05 06:15:25.007 INFO base image for eos-4.35 does not exist, attempting to install
1962026-06-05T06:15:25.013ZJun 05 06:15:25.007 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1972026-06-05T06:16:08.719ZJun 05 06:16:08.712 INFO extracting image to /tmp/eos-4.35_0.raw
1982026-06-05T06:17:14.451ZJun 05 06:17:14.442 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1992026-06-05T06:17:14.476ZJun 05 06:17:14.467 INFO copying image data to zvol
2002026-06-05T06:18:03.043ZJun 05 06:18:03.034 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
2012026-06-05T06:18:06.468ZJun 05 06:18:06.453 INFO creating links
2022026-06-05T06:18:06.468ZJun 05 06:18:06.453 DEBG destroying link mgtriou_ox_sn_vnic0
2032026-06-05T06:18:06.468ZJun 05 06:18:06.453 DEBG destroying link mgtriou_ox_sn_sim0
2042026-06-05T06:18:06.468ZJun 05 06:18:06.453 INFO creating simnet link 'mgtriou_ox_sn_sim0'
2052026-06-05T06:18:06.493ZJun 05 06:18:06.456 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
2062026-06-05T06:18:06.493ZJun 05 06:18:06.463 DEBG link pair created
2072026-06-05T06:18:06.493ZJun 05 06:18:06.463 DEBG destroying link mgtriou_cr1_vn_vnic0
2082026-06-05T06:18:06.493ZJun 05 06:18:06.463 DEBG destroying link mgtriou_cr1_vn_sim0
2092026-06-05T06:18:06.493ZJun 05 06:18:06.463 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
2102026-06-05T06:18:06.493ZJun 05 06:18:06.464 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
2112026-06-05T06:18:06.493ZJun 05 06:18:06.471 DEBG link pair created
2122026-06-05T06:18:06.493ZJun 05 06:18:06.471 DEBG destroying link mgtriou_ox_sn_vnic1
2132026-06-05T06:18:06.493ZJun 05 06:18:06.471 DEBG destroying link mgtriou_ox_sn_sim1
2142026-06-05T06:18:06.493ZJun 05 06:18:06.471 INFO creating simnet link 'mgtriou_ox_sn_sim1'
2152026-06-05T06:18:06.493ZJun 05 06:18:06.473 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
2162026-06-05T06:18:06.493ZJun 05 06:18:06.479 DEBG link pair created
2172026-06-05T06:18:06.493ZJun 05 06:18:06.479 DEBG destroying link mgtriou_cr2_vn_vnic0
2182026-06-05T06:18:06.493ZJun 05 06:18:06.479 DEBG destroying link mgtriou_cr2_vn_sim0
2192026-06-05T06:18:06.493ZJun 05 06:18:06.479 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
2202026-06-05T06:18:06.493ZJun 05 06:18:06.481 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
2212026-06-05T06:18:06.517ZJun 05 06:18:06.488 DEBG link pair created
2222026-06-05T06:18:06.517ZJun 05 06:18:06.488 INFO creating external links
2232026-06-05T06:18:06.517ZJun 05 06:18:06.488 DEBG destroying external link mgtriou_ox_vn_vnic2
2242026-06-05T06:18:06.517ZJun 05 06:18:06.488 INFO creating external link mgtriou_ox_vn_vnic2
2252026-06-05T06:18:06.517ZJun 05 06:18:06.491 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
2262026-06-05T06:18:06.517ZJun 05 06:18:06.491 DEBG destroying external link mgtriou_cr1_vn_vnic1
2272026-06-05T06:18:06.518ZJun 05 06:18:06.491 INFO creating external link mgtriou_cr1_vn_vnic1
2282026-06-05T06:18:06.518ZJun 05 06:18:06.493 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
2292026-06-05T06:18:06.518ZJun 05 06:18:06.493 DEBG destroying external link mgtriou_cr2_vn_vnic1
2302026-06-05T06:18:06.518ZJun 05 06:18:06.493 INFO creating external link mgtriou_cr2_vn_vnic1
2312026-06-05T06:18:06.518ZJun 05 06:18:06.494 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
2322026-06-05T06:18:06.518ZJun 05 06:18:06.494 INFO creating nodes
2332026-06-05T06:18:06.518ZJun 05 06:18:06.494 INFO ox: launching node
2342026-06-05T06:18:06.518ZJun 05 06:18:06.496 INFO cr1: launching node
2352026-06-05T06:18:06.518ZJun 05 06:18:06.498 INFO cr2: launching node
2362026-06-05T06:18:06.541ZJun 05 06:18:06.511 INFO launched instance ox with pid 862 on port 63242
2372026-06-05T06:18:06.541ZJun 05 06:18:06.512 INFO ox: instance ensure
2382026-06-05T06:18:06.541ZJun 05 06:18:06.512 INFO launched instance cr1 with pid 863 on port 61068
2392026-06-05T06:18:06.541ZJun 05 06:18:06.512 INFO cr1: instance ensure
2402026-06-05T06:18:06.541ZJun 05 06:18:06.512 INFO launched instance cr2 with pid 864 on port 35410
2412026-06-05T06:18:06.542ZJun 05 06:18:06.512 INFO cr2: instance ensure
2422026-06-05T06:18:08.879ZJun 05 06:18:08.870 INFO cr2: instance ensure completed after 0 retries
2432026-06-05T06:18:08.879ZJun 05 06:18:08.870 INFO cr2: instance run
2442026-06-05T06:18:08.904ZJun 05 06:18:08.870 DEBG [sc] cr2: starting
2452026-06-05T06:18:08.904ZJun 05 06:18:08.870 DEBG [sc] cr2: connecting to [::1]:35410
2462026-06-05T06:18:08.904ZJun 05 06:18:08.871 DEBG [sc] cr2 waiting for prompt
2472026-06-05T06:18:08.949ZJun 05 06:18:08.940 INFO cr1: instance ensure completed after 0 retries
2482026-06-05T06:18:08.949ZJun 05 06:18:08.940 INFO cr1: instance run
2492026-06-05T06:18:08.974ZJun 05 06:18:08.940 DEBG [sc] cr1: starting
2502026-06-05T06:18:08.975ZJun 05 06:18:08.940 DEBG [sc] cr1: connecting to [::1]:61068
2512026-06-05T06:18:08.975ZJun 05 06:18:08.941 DEBG [sc] cr1 waiting for prompt
2522026-06-05T06:18:08.999ZJun 05 06:18:08.964 INFO ox: instance ensure completed after 0 retries
2532026-06-05T06:18:08.999ZJun 05 06:18:08.964 INFO ox: instance run
2542026-06-05T06:18:09.000ZJun 05 06:18:08.965 DEBG [sc] ox: starting
2552026-06-05T06:18:09.000ZJun 05 06:18:08.965 DEBG [sc] ox: connecting to [::1]:63242
2562026-06-05T06:18:09.001ZJun 05 06:18:08.966 DEBG [sc] ox waiting for prompt
2572026-06-05T06:18:26.066ZJun 05 06:18:26.055 DEBG [sc] cr1: logging in
2582026-06-05T06:18:26.489ZJun 05 06:18:26.479 INFO cr1: mounting /opt/cargo-bay
2592026-06-05T06:18:26.489ZJun 05 06:18:26.479 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2602026-06-05T06:18:26.513ZJun 05 06:18:26.502 DEBG [sc] cr1: executing command `cd`
2612026-06-05T06:18:26.537ZJun 05 06:18:26.513 INFO cr1: finished mounting /opt/cargo-bay
2622026-06-05T06:18:26.537ZJun 05 06:18:26.513 DEBG [sc] cr1: executing command `hostname cr1`
2632026-06-05T06:18:26.537ZJun 05 06:18:26.524 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2642026-06-05T06:18:26.561ZJun 05 06:18:26.535 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2652026-06-05T06:18:26.561ZJun 05 06:18:26.546 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2662026-06-05T06:18:26.587ZJun 05 06:18:26.557 INFO cr1: logging out
2672026-06-05T06:18:28.643ZJun 05 06:18:28.623 INFO cr1: logged out
2682026-06-05T06:18:29.500ZJun 05 06:18:29.483 DEBG [sc] ox: logging in
2692026-06-05T06:18:29.729ZJun 05 06:18:29.713 INFO ox: mounting /opt/cargo-bay
2702026-06-05T06:18:29.729ZJun 05 06:18:29.713 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2712026-06-05T06:18:30.559ZJun 05 06:18:30.550 DEBG [sc] cr2: logging in
2722026-06-05T06:18:30.994ZJun 05 06:18:30.984 INFO cr2: mounting /opt/cargo-bay
2732026-06-05T06:18:30.994ZJun 05 06:18:30.984 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2742026-06-05T06:18:31.018ZJun 05 06:18:31.006 DEBG [sc] cr2: executing command `cd`
2752026-06-05T06:18:31.044ZJun 05 06:18:31.017 INFO cr2: finished mounting /opt/cargo-bay
2762026-06-05T06:18:31.044ZJun 05 06:18:31.017 DEBG [sc] cr2: executing command `hostname cr2`
2772026-06-05T06:18:31.044ZJun 05 06:18:31.027 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2782026-06-05T06:18:31.069ZJun 05 06:18:31.039 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2792026-06-05T06:18:31.069ZJun 05 06:18:31.050 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2802026-06-05T06:18:31.096ZJun 05 06:18:31.061 INFO cr2: logging out
2812026-06-05T06:18:31.234ZJun 05 06:18:31.224 DEBG [sc] ox: executing command `cd`
2822026-06-05T06:18:31.264ZJun 05 06:18:31.235 INFO ox: finished mounting /opt/cargo-bay
2832026-06-05T06:18:31.264ZJun 05 06:18:31.235 DEBG [sc] ox: executing command `hostname ox`
2842026-06-05T06:18:31.264ZJun 05 06:18:31.246 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2852026-06-05T06:18:31.290ZJun 05 06:18:31.257 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2862026-06-05T06:18:31.290ZJun 05 06:18:31.268 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2872026-06-05T06:18:31.314ZJun 05 06:18:31.279 INFO ox: logging out
2882026-06-05T06:18:31.366ZJun 05 06:18:31.357 INFO ox: logged out
2892026-06-05T06:18:34.075ZJun 05 06:18:34.065 INFO cr2: logged out
2902026-06-05T06:18:34.099ZJun 05 06:18:34.065 DEBG [sc] ox: starting
2912026-06-05T06:18:34.099ZJun 05 06:18:34.065 DEBG [sc] ox: connecting to [::1]:63242
2922026-06-05T06:18:34.099ZJun 05 06:18:34.066 DEBG [sc] ox waiting for prompt
2932026-06-05T06:18:34.099ZJun 05 06:18:34.077 DEBG [sc] ox: logging in
2942026-06-05T06:18:34.165ZJun 05 06:18:34.155 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2952026-06-05T06:18:38.975ZJun 05 06:18:38.964 DEBG [sc] ox: starting
2962026-06-05T06:18:38.975ZJun 05 06:18:38.964 DEBG [sc] ox: connecting to [::1]:63242
2972026-06-05T06:18:39.001ZJun 05 06:18:38.964 DEBG [sc] ox waiting for prompt
2982026-06-05T06:18:39.001ZJun 05 06:18:38.975 DEBG [sc] ox: logging in
2992026-06-05T06:18:39.073ZJun 05 06:18:39.063 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
3002026-06-05T06:18:39.164ZJun 05 06:18:39.151 DEBG [sc] ox: starting
3012026-06-05T06:18:39.164ZJun 05 06:18:39.151 DEBG [sc] ox: connecting to [::1]:63242
3022026-06-05T06:18:39.188ZJun 05 06:18:39.152 DEBG [sc] ox waiting for prompt
3032026-06-05T06:18:39.188ZJun 05 06:18:39.162 DEBG [sc] ox: logging in
3042026-06-05T06:18:39.260ZJun 05 06:18:39.250 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
3052026-06-05T06:18:39.326ZJun 05 06:18:39.317 INFO cr1: installing frr
3062026-06-05T06:18:39.326ZJun 05 06:18:39.317 INFO waiting for ceos to initialize
3072026-06-05T06:18:39.326ZJun 05 06:18:39.317 INFO ox: setting up npuvm
3082026-06-05T06:18:39.350ZJun 05 06:18:39.317 DEBG [sc] cr1: starting
3092026-06-05T06:18:39.350ZJun 05 06:18:39.317 DEBG [sc] cr1: connecting to [::1]:61068
3102026-06-05T06:18:39.350ZJun 05 06:18:39.317 DEBG [sc] cr2: starting
3112026-06-05T06:18:39.350ZJun 05 06:18:39.317 DEBG [sc] cr2: connecting to [::1]:35410
3122026-06-05T06:18:39.350ZJun 05 06:18:39.317 DEBG [sc] ox: starting
3132026-06-05T06:18:39.350ZJun 05 06:18:39.317 DEBG [sc] ox: connecting to [::1]:63242
3142026-06-05T06:18:39.350ZJun 05 06:18:39.318 DEBG [sc] cr2 waiting for prompt
3152026-06-05T06:18:39.350ZJun 05 06:18:39.318 DEBG [sc] cr1 waiting for prompt
3162026-06-05T06:18:39.350ZJun 05 06:18:39.318 DEBG [sc] ox waiting for prompt
3172026-06-05T06:18:39.351ZJun 05 06:18:39.328 DEBG [sc] cr2: logging in
3182026-06-05T06:18:39.351ZJun 05 06:18:39.328 DEBG [sc] ox: logging in
3192026-06-05T06:18:39.351ZJun 05 06:18:39.328 DEBG [sc] cr1: logging in
3202026-06-05T06:18:39.426ZJun 05 06:18:39.416 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
3212026-06-05T06:18:39.495ZJun 05 06:18:39.482 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3222026-06-05T06:18:39.718ZJun 05 06:18:39.708 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
3232026-06-05T06:18:41.778ZJun 05 06:18:41.768 INFO cr2: executing eos script show version
3242026-06-05T06:18:41.778ZJun 05 06:18:41.768 DEBG [sc] cr2: starting
3252026-06-05T06:18:41.778ZJun 05 06:18:41.768 DEBG [sc] cr2: connecting to [::1]:35410
3262026-06-05T06:18:41.802ZJun 05 06:18:41.769 DEBG [sc] cr2 waiting for prompt
3272026-06-05T06:18:41.803ZJun 05 06:18:41.779 DEBG [sc] cr2: logging in
3282026-06-05T06:18:41.921ZJun 05 06:18:41.912 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3292026-06-05T06:18:44.690ZJun 05 06:18:44.680 DEBG [sc] ox: starting
3302026-06-05T06:18:44.690ZJun 05 06:18:44.680 DEBG [sc] ox: connecting to [::1]:63242
3312026-06-05T06:18:44.714ZJun 05 06:18:44.681 DEBG [sc] ox waiting for prompt
3322026-06-05T06:18:44.714ZJun 05 06:18:44.692 DEBG [sc] ox: logging in
3332026-06-05T06:18:44.790ZJun 05 06:18:44.780 DEBG [sc] ox: executing command `chmod +x npuvm`
3342026-06-05T06:18:44.856ZJun 05 06:18:44.846 DEBG [sc] ox: starting
3352026-06-05T06:18:44.856ZJun 05 06:18:44.846 DEBG [sc] ox: connecting to [::1]:63242
3362026-06-05T06:18:44.880ZJun 05 06:18:44.846 DEBG [sc] ox waiting for prompt
3372026-06-05T06:18:44.881ZJun 05 06:18:44.857 DEBG [sc] ox: logging in
3382026-06-05T06:18:44.944ZJun 05 06:18:44.935 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3392026-06-05T06:18:49.062ZJun 05 06:18:49.052 INFO cr1: enabling frr daemon bgpd
3402026-06-05T06:18:49.062ZJun 05 06:18:49.052 DEBG [sc] cr1: starting
3412026-06-05T06:18:49.063ZJun 05 06:18:49.052 DEBG [sc] cr1: connecting to [::1]:61068
3422026-06-05T06:18:49.087ZJun 05 06:18:49.053 DEBG [sc] cr1 waiting for prompt
3432026-06-05T06:18:49.087ZJun 05 06:18:49.064 DEBG [sc] cr1: logging in
3442026-06-05T06:18:49.206ZJun 05 06:18:49.196 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3452026-06-05T06:18:51.309ZJun 05 06:18:51.261 DEBG [sc] cr1: starting
3462026-06-05T06:18:51.309ZJun 05 06:18:51.261 DEBG [sc] cr1: connecting to [::1]:61068
3472026-06-05T06:18:51.334ZJun 05 06:18:51.261 DEBG [sc] cr1 waiting for prompt
3482026-06-05T06:18:51.334ZJun 05 06:18:51.271 DEBG [sc] cr1: logging in
3492026-06-05T06:18:51.414ZJun 05 06:18:51.383 INFO ox: npuvm install output:
3502026-06-05T06:18:51.415Zdendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) dendrite-softnpu.tar.gz [########################################] 670 B/670 B (0s) Error: install dendrite error: fetch dendrite image error: HTTP error 404 when downloading image
3512026-06-05T06:18:51.415Z
3522026-06-05T06:18:51.415ZCaused by:
3532026-06-05T06:18:51.415Z 0: fetch dendrite image error: HTTP error 404 when downloading image
3542026-06-05T06:18:51.415Z 1: HTTP error 404 when downloading image
3552026-06-05T06:18:51.415ZJun 05 06:18:51.383 DEBG [sc] ox: starting
3562026-06-05T06:18:51.415ZJun 05 06:18:51.383 DEBG [sc] ox: connecting to [::1]:63242
3572026-06-05T06:18:51.440ZJun 05 06:18:51.383 DEBG [sc] ox waiting for prompt
3582026-06-05T06:18:51.440ZJun 05 06:18:51.415 DEBG [sc] cr1: executing command `systemctl restart frr`
3592026-06-05T06:18:51.466ZJun 05 06:18:51.439 DEBG [sc] ox: logging in
3602026-06-05T06:18:51.537ZJun 05 06:18:51.527 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
3612026-06-05T06:18:51.722ZJun 05 06:18:51.712 DEBG [sc] cr2: starting
3622026-06-05T06:18:51.722ZJun 05 06:18:51.712 DEBG [sc] cr2: connecting to [::1]:35410
3632026-06-05T06:18:51.746ZJun 05 06:18:51.713 DEBG [sc] cr2 waiting for prompt
3642026-06-05T06:18:51.746ZJun 05 06:18:51.724 DEBG [sc] cr2: logging in
3652026-06-05T06:18:51.854ZJun 05 06:18:51.844 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3662026-06-05T06:18:53.955ZJun 05 06:18:53.945 INFO cr2: executing eos script show version
3672026-06-05T06:18:53.955ZJun 05 06:18:53.945 DEBG [sc] cr2: starting
3682026-06-05T06:18:53.955ZJun 05 06:18:53.945 DEBG [sc] cr2: connecting to [::1]:35410
3692026-06-05T06:18:53.984ZJun 05 06:18:53.946 DEBG [sc] cr2 waiting for prompt
3702026-06-05T06:18:53.984ZJun 05 06:18:53.956 DEBG [sc] cr2: logging in
3712026-06-05T06:18:54.109ZJun 05 06:18:54.099 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3722026-06-05T06:18:56.478ZJun 05 06:18:56.458 DEBG [sc] cr2: starting
3732026-06-05T06:18:56.478ZJun 05 06:18:56.458 DEBG [sc] cr2: connecting to [::1]:35410
3742026-06-05T06:18:56.506ZJun 05 06:18:56.459 DEBG [sc] cr2 waiting for prompt
3752026-06-05T06:18:56.507ZJun 05 06:18:56.469 DEBG [sc] cr2: logging in
3762026-06-05T06:18:56.635ZJun 05 06:18:56.623 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3772026-06-05T06:18:58.786ZJun 05 06:18:58.767 INFO cr2: executing eos script show version
3782026-06-05T06:18:58.786ZJun 05 06:18:58.768 DEBG [sc] cr2: starting
3792026-06-05T06:18:58.786ZJun 05 06:18:58.768 DEBG [sc] cr2: connecting to [::1]:35410
3802026-06-05T06:18:58.826ZJun 05 06:18:58.768 DEBG [sc] cr2 waiting for prompt
3812026-06-05T06:18:58.827ZJun 05 06:18:58.779 DEBG [sc] cr2: logging in
3822026-06-05T06:18:58.925ZJun 05 06:18:58.912 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3832026-06-05T06:18:59.035ZJun 05 06:18:59.023 INFO cr1: executing frr script
3842026-06-05T06:18:59.035Z configure
3852026-06-05T06:18:59.035Z ip forwarding
3862026-06-05T06:18:59.035Z ipv6 forwarding
3872026-06-05T06:18:59.035Z ip route 1.2.3.0/24 null0
3882026-06-05T06:18:59.035Z ipv6 route fd99::/64 null0
3892026-06-05T06:18:59.035Z router bgp 44
3902026-06-05T06:18:59.035Z no bgp ebgp-requires-policy
3912026-06-05T06:18:59.035Z timers bgp 2 6
3922026-06-05T06:18:59.035Z neighbor enp0s8 interface remote-as external
3932026-06-05T06:18:59.035Z neighbor enp0s8 timers connect 1
3942026-06-05T06:18:59.035Z address-family ipv4 unicast
3952026-06-05T06:18:59.035Z network 1.2.3.0/24
3962026-06-05T06:18:59.035Z neighbor enp0s8 activate
3972026-06-05T06:18:59.035Z exit-address-family
3982026-06-05T06:18:59.035Z address-family ipv6 unicast
3992026-06-05T06:18:59.035Z network fd99::/64
4002026-06-05T06:18:59.036Z neighbor enp0s8 activate
4012026-06-05T06:18:59.036Z exit-address-family
4022026-06-05T06:18:59.036Z exit
4032026-06-05T06:18:59.036Z
4042026-06-05T06:18:59.036ZJun 05 06:18:59.023 DEBG [sc] cr1: starting
4052026-06-05T06:18:59.036ZJun 05 06:18:59.023 DEBG [sc] cr1: connecting to [::1]:61068
4062026-06-05T06:18:59.060ZJun 05 06:18:59.024 DEBG [sc] cr1 waiting for prompt
4072026-06-05T06:18:59.060ZJun 05 06:18:59.035 DEBG [sc] cr1: logging in
4082026-06-05T06:18:59.209ZJun 05 06:18:59.200 DEBG [sc] cr1: executing command `vtysh -c '' -c ' configure' -c ' ip forwarding' -c ' ipv6 forwarding' -c ' ip route 1.2.3.0/24 null0' -c ' ipv6 route fd99::/64 null0' -c ' router bgp 44' -c ' no bgp ebgp-requires-policy' -c ' timers bgp 2 6' -c ' neighbor enp0s8 interface remote-as external' -c ' neighbor enp0s8 timers connect 1 ' -c ' address-family ipv4 unicast' -c ' network 1.2.3.0/24' -c ' neighbor enp0s8 activate' -c ' exit-address-family' -c ' address-family ipv6 unicast' -c ' network fd99::/64' -c ' neighbor enp0s8 activate' -c ' exit-address-family' -c ' exit' -c ' '`
4092026-06-05T06:19:01.372ZJun 05 06:19:01.362 INFO cr2: executing eos script
4102026-06-05T06:19:01.372Z enable
4112026-06-05T06:19:01.372Z configure
4122026-06-05T06:19:01.372Z ipv6 unicast-routing
4132026-06-05T06:19:01.372Z ip routing ipv6 interfaces
4142026-06-05T06:19:01.373Z ip routing
4152026-06-05T06:19:01.373Z ip route 1.2.3.0/24 null0
4162026-06-05T06:19:01.373Z ipv6 route fd99::/64 null0
4172026-06-05T06:19:01.373Z interface et1
4182026-06-05T06:19:01.373Z no switchport
4192026-06-05T06:19:01.373Z ipv6 enable
4202026-06-05T06:19:01.373Z
4212026-06-05T06:19:01.373Z router bgp 45
4222026-06-05T06:19:01.373Z router-id 1.2.3.1
4232026-06-05T06:19:01.373Z no bgp default ipv4-unicast
4242026-06-05T06:19:01.373Z timers bgp 2 6
4252026-06-05T06:19:01.373Z neighbor ebgp peer group
4262026-06-05T06:19:01.373Z neighbor ebgp remote-as 33
4272026-06-05T06:19:01.373Z neighbor interface Et1 peer-group ebgp
4282026-06-05T06:19:01.373Z address-family ipv4
4292026-06-05T06:19:01.373Z neighbor ebgp activate
4302026-06-05T06:19:01.373Z neighbor ebgp next-hop address-family ipv6 originate
4312026-06-05T06:19:01.374Z network 1.2.3.0/24
4322026-06-05T06:19:01.374Z exit
4332026-06-05T06:19:01.374Z address-family ipv6
4342026-06-05T06:19:01.374Z neighbor ebgp activate
4352026-06-05T06:19:01.374Z neighbor ebgp next-hop address-family ipv6 originate
4362026-06-05T06:19:01.374Z network fd99::/64
4372026-06-05T06:19:01.374Z exit
4382026-06-05T06:19:01.374Z exit
4392026-06-05T06:19:01.374Z
4402026-06-05T06:19:01.374ZJun 05 06:19:01.362 DEBG [sc] cr2: starting
4412026-06-05T06:19:01.374ZJun 05 06:19:01.362 DEBG [sc] cr2: connecting to [::1]:35410
4422026-06-05T06:19:01.401ZJun 05 06:19:01.363 DEBG [sc] cr2 waiting for prompt
4432026-06-05T06:19:01.401ZJun 05 06:19:01.373 DEBG [sc] cr2: logging in
4442026-06-05T06:19:01.538ZJun 05 06:19:01.528 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
4452026-06-05T06:19:01.538Z enable
4462026-06-05T06:19:01.538Z configure
4472026-06-05T06:19:01.538Z ipv6 unicast-routing
4482026-06-05T06:19:01.538Z ip routing ipv6 interfaces
4492026-06-05T06:19:01.538Z ip routing
4502026-06-05T06:19:01.538Z ip route 1.2.3.0/24 null0
4512026-06-05T06:19:01.538Z ipv6 route fd99::/64 null0
4522026-06-05T06:19:01.538Z interface et1
4532026-06-05T06:19:01.538Z no switchport
4542026-06-05T06:19:01.538Z ipv6 enable
4552026-06-05T06:19:01.538Z
4562026-06-05T06:19:01.538Z router bgp 45
4572026-06-05T06:19:01.539Z router-id 1.2.3.1
4582026-06-05T06:19:01.539Z no bgp default ipv4-unicast
4592026-06-05T06:19:01.539Z timers bgp 2 6
4602026-06-05T06:19:01.539Z neighbor ebgp peer group
4612026-06-05T06:19:01.539Z neighbor ebgp remote-as 33
4622026-06-05T06:19:01.539Z neighbor interface Et1 peer-group ebgp
4632026-06-05T06:19:01.539Z address-family ipv4
4642026-06-05T06:19:01.539Z neighbor ebgp activate
4652026-06-05T06:19:01.539Z neighbor ebgp next-hop address-family ipv6 originate
4662026-06-05T06:19:01.539Z network 1.2.3.0/24
4672026-06-05T06:19:01.539Z exit
4682026-06-05T06:19:01.539Z address-family ipv6
4692026-06-05T06:19:01.539Z neighbor ebgp activate
4702026-06-05T06:19:01.539Z neighbor ebgp next-hop address-family ipv6 originate
4712026-06-05T06:19:01.539Z network fd99::/64
4722026-06-05T06:19:01.540Z exit
4732026-06-05T06:19:01.540Z exit
4742026-06-05T06:19:01.540Z '`
4752026-06-05T06:19:04.612ZJun 05 06:19:04.599 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4762026-06-05T06:19:05.609ZJun 05 06:19:05.599 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4772026-06-05T06:19:06.611ZJun 05 06:19:06.601 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4782026-06-05T06:19:07.615ZJun 05 06:19:07.601 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4792026-06-05T06:19:08.615ZJun 05 06:19:08.603 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4802026-06-05T06:19:09.614ZJun 05 06:19:09.605 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4812026-06-05T06:19:10.616ZJun 05 06:19:10.606 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4822026-06-05T06:19:11.618ZJun 05 06:19:11.607 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4832026-06-05T06:19:12.618ZJun 05 06:19:12.608 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4842026-06-05T06:19:13.620ZJun 05 06:19:13.609 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4852026-06-05T06:19:14.620ZJun 05 06:19:14.610 DEBG wait for dpd: Communication Error: error sending request for url (http://10.151.6.166:12224/dpd-uptime): error sending request for url (http://10.151.6.166:12224/dpd-uptime): client error (Connect): tcp connect error: Connection refused (os error 146)
4862026-06-05T06:19:14.644ZJun 05 06:19:14.610 WARN collecting diagnostics for mgtriou
4872026-06-05T06:19:14.644ZJun 05 06:19:14.610 DEBG [sc] ox: starting
4882026-06-05T06:19:14.644ZJun 05 06:19:14.610 DEBG [sc] ox: connecting to [::1]:63242
4892026-06-05T06:19:14.644ZJun 05 06:19:14.611 DEBG [sc] ox waiting for prompt
4902026-06-05T06:19:14.644ZJun 05 06:19:14.611 DEBG [sc] ox: logging in
4912026-06-05T06:19:14.697ZJun 05 06:19:14.688 DEBG [sc] ox: executing command `svcs -xv`
4922026-06-05T06:19:14.846ZJun 05 06:19:14.836 INFO === mgtriou ox-svcs-xv (svcs -xv) ===
4932026-06-05T06:19:14.846Z
4942026-06-05T06:19:14.870ZJun 05 06:19:14.836 DEBG [sc] ox: starting
4952026-06-05T06:19:14.870ZJun 05 06:19:14.836 DEBG [sc] ox: connecting to [::1]:63242
4962026-06-05T06:19:14.870ZJun 05 06:19:14.837 DEBG [sc] ox waiting for prompt
4972026-06-05T06:19:14.870ZJun 05 06:19:14.847 DEBG [sc] ox: logging in
4982026-06-05T06:19:14.933ZJun 05 06:19:14.924 DEBG [sc] ox: executing command `ipadm show-addr`
4992026-06-05T06:19:14.989ZJun 05 06:19:14.979 INFO === mgtriou ox-ipadm (ipadm show-addr) ===
5002026-06-05T06:19:14.990ZADDROBJ TYPE STATE ADDR
5012026-06-05T06:19:14.990Zlo0/v4 static ok 127.0.0.1/8
5022026-06-05T06:19:14.990Zvioif1/dhcp dhcp ok 10.151.6.166/24
5032026-06-05T06:19:14.990Zlo0/v6 static ok ::1/128
5042026-06-05T06:19:14.990ZJun 05 06:19:14.980 DEBG [sc] ox: starting
5052026-06-05T06:19:14.990ZJun 05 06:19:14.980 DEBG [sc] ox: connecting to [::1]:63242
5062026-06-05T06:19:15.014ZJun 05 06:19:14.981 DEBG [sc] ox waiting for prompt
5072026-06-05T06:19:15.014ZJun 05 06:19:14.990 DEBG [sc] ox: logging in
5082026-06-05T06:19:15.078ZJun 05 06:19:15.068 DEBG [sc] ox: executing command `dladm show-link`
5092026-06-05T06:19:15.134ZJun 05 06:19:15.123 INFO === mgtriou ox-dladm (dladm show-link) ===
5102026-06-05T06:19:15.134ZLINK CLASS MTU STATE BRIDGE OVER
5112026-06-05T06:19:15.134Zvioif0 phys 1500 up -- --
5122026-06-05T06:19:15.134Zvioif1 phys 1500 up -- --
5132026-06-05T06:19:15.134ZJun 05 06:19:15.123 DEBG [sc] ox: starting
5142026-06-05T06:19:15.134ZJun 05 06:19:15.123 DEBG [sc] ox: connecting to [::1]:63242
5152026-06-05T06:19:15.158ZJun 05 06:19:15.124 DEBG [sc] ox waiting for prompt
5162026-06-05T06:19:15.158ZJun 05 06:19:15.134 DEBG [sc] ox: logging in
5172026-06-05T06:19:15.220ZJun 05 06:19:15.211 DEBG [sc] ox: executing command `netstat -nr`
5182026-06-05T06:19:15.298ZJun 05 06:19:15.288 INFO === mgtriou ox-netstat (netstat -nr) ===
5192026-06-05T06:19:15.298Z
5202026-06-05T06:19:15.298ZRouting Table: IPv4
5212026-06-05T06:19:15.298Z Destination Gateway Flags Ref Use Interface
5222026-06-05T06:19:15.298Z-------------------- -------------------- ----- ----- ---------- ---------
5232026-06-05T06:19:15.298Zdefault 10.151.6.1 UG 30 24226 vioif1
5242026-06-05T06:19:15.298Z10.151.6.0 10.151.6.166 U 4 11 vioif1
5252026-06-05T06:19:15.298Z127.0.0.1 127.0.0.1 UH 2 81 lo0
5262026-06-05T06:19:15.298Z
5272026-06-05T06:19:15.298ZRouting Table: IPv6
5282026-06-05T06:19:15.298Z Destination/Mask Gateway Flags Ref Use If
5292026-06-05T06:19:15.298Z--------------------------- --------------------------- ----- --- ------- -----
5302026-06-05T06:19:15.298Z::1 ::1 UH 2 40 lo0
5312026-06-05T06:19:15.322ZJun 05 06:19:15.288 DEBG [sc] ox: starting
5322026-06-05T06:19:15.322ZJun 05 06:19:15.288 DEBG [sc] ox: connecting to [::1]:63242
5332026-06-05T06:19:15.322ZJun 05 06:19:15.289 DEBG [sc] ox waiting for prompt
5342026-06-05T06:19:15.322ZJun 05 06:19:15.299 DEBG [sc] ox: logging in
5352026-06-05T06:19:15.385ZJun 05 06:19:15.376 DEBG [sc] ox: executing command `svcs -L dendrite`
5362026-06-05T06:19:15.419ZJun 05 06:19:15.409 DEBG [sc] ox: starting
5372026-06-05T06:19:15.419ZJun 05 06:19:15.409 DEBG [sc] ox: connecting to [::1]:63242
5382026-06-05T06:19:15.445ZJun 05 06:19:15.410 DEBG [sc] ox waiting for prompt
5392026-06-05T06:19:15.469ZJun 05 06:19:15.453 DEBG [sc] ox: logging in
5402026-06-05T06:19:15.541ZJun 05 06:19:15.531 DEBG [sc] ox: executing command `cat svcs: Pattern 'dendrite' doesn't match any instances`
 
5412026-06-05T14:11:17.512Zjob duration 28825 exceeds 28800 seconds; aborting
5422026-06-05T14:11:17.535Zworker failed without completing job
5432026-06-05T14:11:17.536Ztask 2 was incomplete, marked failed