01KPA7CDFPDMKBVP7WADJWFCHJ: falcon

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

Buildomat Job: 01KPA7CXNVBY2QK6981Y7TTBST

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-16T04:18:41.581Zjob dependencies complete; ready to run (waiting for 13 m 56 s)
22026-04-16T04:21:53.548Zjob assigned to worker 01KPA86FJJKWDQ2HADTBYQQKM4 [factory edgar, gimlet/BRM42220010/771] (queued for 3 m 11 s)
32026-04-16T04:21:54.067Zdownloading input: /input/build/work/debug/ddmadm
42026-04-16T04:22:16.545Zdownloaded input: /input/build/work/debug/ddmadm
52026-04-16T04:22:16.545Zdownloading input: /input/build/work/debug/ddmd
62026-04-16T04:22:43.967Zdownloaded input: /input/build/work/debug/ddmd
72026-04-16T04:22:43.991Zdownloading input: /input/build-interop/work/testbed.tar.gz
82026-04-16T04:22:52.250Zdownloaded input: /input/build-interop/work/testbed.tar.gz
92026-04-16T04:22:52.250Zdownloading input: /input/build/work/debug/mgadm
102026-04-16T04:23:18.263Zdownloaded input: /input/build/work/debug/mgadm
112026-04-16T04:23:18.263Zdownloading input: /input/build-interop/work/dhcp-server
122026-04-16T04:23:20.724Zdownloaded input: /input/build-interop/work/dhcp-server
132026-04-16T04:23:20.724Zdownloading input: /input/build/work/debug/mgd
142026-04-16T04:23:53.853Zdownloaded input: /input/build/work/debug/mgd
152026-04-16T04:23:53.877Zdownloading input: /input/build/work/release/ddmadm
162026-04-16T04:23:55.899Zdownloaded input: /input/build/work/release/ddmadm
172026-04-16T04:23:55.899Zdownloading input: /input/build/work/release/ddmd
182026-04-16T04:23:58.247Zdownloaded input: /input/build/work/release/ddmd
192026-04-16T04:23:58.247Zdownloading input: /input/build/work/release/falcon-lab
202026-04-16T04:23:59.798Zdownloaded input: /input/build/work/release/falcon-lab
212026-04-16T04:23:59.798Zdownloading input: /input/build/work/release/mgadm
222026-04-16T04:24:02.313Zdownloaded input: /input/build/work/release/mgadm
232026-04-16T04:24:02.313Zdownloading input: /input/build/work/release/mgd
242026-04-16T04:24:05.599Zdownloaded input: /input/build/work/release/mgd
 
252026-04-16T04:24:05.599Zstarting task 0: "setup"
262026-04-16T04:24:05.623Z++ uname -s
272026-04-16T04:24:05.623Z+ kern=SunOS
282026-04-16T04:24:05.623Z+ build_user=build
292026-04-16T04:24:05.623Z+ build_uid=12345
302026-04-16T04:24:05.623Z+ work_dir=/work
312026-04-16T04:24:05.623Z+ input_dir=/input
322026-04-16T04:24:05.623Z+ [[ 0 == 12345 ]]
332026-04-16T04:24:05.623Z+ case "$kern" in
342026-04-16T04:24:05.623Z+ groupadd -g 12345 build
352026-04-16T04:24:05.648Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-04-16T04:24:07.637Z+ zfs create -o mountpoint=/work rpool/work
372026-04-16T04:24:08.641Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-04-16T04:24:08.665Z+ home_fs=
392026-04-16T04:24:08.665Z+ [[ '' == autofs ]]
402026-04-16T04:24:08.665Z+ mkdir -p /home/build
412026-04-16T04:24:08.665Z+ chown build:build /home/build /work
422026-04-16T04:24:09.654Z+ chmod 0700 /home/build /work
432026-04-16T04:24:09.679Zprocess exited: duration 4058 ms, exit code 0
 
442026-04-16T04:24:09.728Zstarting task 1: "authentication"
452026-04-16T04:24:09.824Zprocess exited: duration 94 ms, exit code 0
 
462026-04-16T04:24:09.876Zstarting task 2: "build"
472026-04-16T04:24:09.901Z+ set -e
482026-04-16T04:24:09.901Z+ banner zpool
492026-04-16T04:24:09.901Z
502026-04-16T04:24:09.902Z ###### ##### #### #### #
512026-04-16T04:24:09.902Z # # # # # # # #
522026-04-16T04:24:09.902Z # # # # # # # #
532026-04-16T04:24:09.902Z # ##### # # # # #
542026-04-16T04:24:09.902Z # # # # # # #
552026-04-16T04:24:09.902Z ###### # #### #### ######
562026-04-16T04:24:09.902Z
572026-04-16T04:24:09.902Z++ pfexec diskinfo -pH
582026-04-16T04:24:09.902Z++ sort -k8 -n -r
592026-04-16T04:24:09.926Z++ head -1
602026-04-16T04:24:09.926Z++ awk '{print $2}'
612026-04-16T04:24:09.950Z+ DISK=c9t0014EE81000BC3B1d0
622026-04-16T04:24:09.951Z+ export DISK
632026-04-16T04:24:09.951Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
642026-04-16T04:24:10.011Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-04-16T04:24:10.040Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-04-16T04:24:10.191Z+ [[ true =~ true ]]
672026-04-16T04:24:10.217Z+ pfexec zpool trim cpool
682026-04-16T04:24:10.217Z++ zpool status -t cpool
692026-04-16T04:24:10.241Z+ [[ ! pool: cpool
702026-04-16T04:24:10.241Z state: ONLINE
712026-04-16T04:24:10.241Z scan: none requested
722026-04-16T04:24:10.241Zconfig:
732026-04-16T04:24:10.241Z
742026-04-16T04:24:10.241Z NAME STATE READ WRITE CKSUM
752026-04-16T04:24:10.241Z cpool ONLINE 0 0 0
762026-04-16T04:24:10.241Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at April 16, 2026 at 04:24:10 AM UTC)
772026-04-16T04:24:10.241Z
782026-04-16T04:24:10.241Zerrors: No known data errors =~ 100% ]]
792026-04-16T04:24:10.241Z+ sleep 10
802026-04-16T04:24:20.383Z++ zpool status -t cpool
812026-04-16T04:24:20.411Z+ [[ ! pool: cpool
822026-04-16T04:24:20.411Z state: ONLINE
832026-04-16T04:24:20.412Z scan: none requested
842026-04-16T04:24:20.412Zconfig:
852026-04-16T04:24:20.412Z
862026-04-16T04:24:20.412Z NAME STATE READ WRITE CKSUM
872026-04-16T04:24:20.412Z cpool ONLINE 0 0 0
882026-04-16T04:24:20.412Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at April 16, 2026 at 04:24:10 AM UTC)
892026-04-16T04:24:20.412Z
902026-04-16T04:24:20.412Zerrors: No known data errors =~ 100% ]]
912026-04-16T04:24:20.412Z+ sleep 10
922026-04-16T04:24:30.243Z++ zpool status -t cpool
932026-04-16T04:24:30.267Z+ [[ ! pool: cpool
942026-04-16T04:24:30.267Z state: ONLINE
952026-04-16T04:24:30.267Z scan: none requested
962026-04-16T04:24:30.267Zconfig:
972026-04-16T04:24:30.267Z
982026-04-16T04:24:30.267Z NAME STATE READ WRITE CKSUM
992026-04-16T04:24:30.267Z cpool ONLINE 0 0 0
1002026-04-16T04:24:30.267Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at April 16, 2026 at 04:24:24 AM UTC)
1012026-04-16T04:24:30.267Z
1022026-04-16T04:24:30.267Zerrors: No known data errors =~ 100% ]]
1032026-04-16T04:24:30.267Z+ pfexec chown 12345 /ci
1042026-04-16T04:24:30.267Z+ cd /ci
1052026-04-16T04:24:30.267Z+ export FALCON_DATASET=cpool/falcon
1062026-04-16T04:24:30.267Z+ FALCON_DATASET=cpool/falcon
1072026-04-16T04:24:30.268Z+ banner setup
1082026-04-16T04:24:30.268Z
1092026-04-16T04:24:30.268Z #### ###### ##### # # #####
1102026-04-16T04:24:30.268Z # # # # # # #
1112026-04-16T04:24:30.268Z #### ##### # # # # #
1122026-04-16T04:24:30.268Z # # # # # #####
1132026-04-16T04:24:30.268Z # # # # # # #
1142026-04-16T04:24:30.268Z #### ###### # #### #
1152026-04-16T04:24:30.268Z
1162026-04-16T04:24:30.268Z+ cp /input/build-interop/work/dhcp-server .
1172026-04-16T04:24:30.469Z+ cp /input/build/work/release/falcon-lab .
1182026-04-16T04:24:30.501Z+ cp /input/build/work/release/mgd .
1192026-04-16T04:24:30.565Z+ cp /input/build/work/release/ddmd .
1202026-04-16T04:24:30.620Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-04-16T04:24:30.647Z+ mkdir -p cargo-bay
1222026-04-16T04:24:30.647Z+ mv mgd cargo-bay/
1232026-04-16T04:24:30.647Z+ mv ddmd cargo-bay/
1242026-04-16T04:24:30.647Z+ export EXT_INTERFACE=igb0
1252026-04-16T04:24:30.647Z+ EXT_INTERFACE=igb0
1262026-04-16T04:24:30.647Z++ bmat address ls -f extra -Ho first
1272026-04-16T04:24:30.672Z+ first=10.151.6.164
1282026-04-16T04:24:30.672Z++ bmat address ls -f extra -Ho last
1292026-04-16T04:24:30.706Z+ last=10.151.6.227
1302026-04-16T04:24:30.706Z++ bmat address ls -f extra -Ho gateway
1312026-04-16T04:24:30.729Z+ gw=10.151.6.1
1322026-04-16T04:24:30.729Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-04-16T04:24:30.730Z++ sed 's#/.*##g'
1342026-04-16T04:24:30.730Z+ server=10.151.6.100
1352026-04-16T04:24:30.730Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1362026-04-16T04:24:30.730Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-04-16T04:24:30.754Z+ error=
1382026-04-16T04:24:30.754Z+ RUST_LOG=debug
1392026-04-16T04:24:30.754Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-04-16T04:24:30.754ZApr 16 04:24:30.720 DEBG using default route interface igb0
1412026-04-16T04:24:30.754ZApr 16 04:24:30.721 DEBG using default route interface igb0
1422026-04-16T04:24:30.754ZApr 16 04:24:30.721 DEBG using default route interface igb0
1432026-04-16T04:24:30.754ZApr 16 04:24:30.721 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-04-16T04:24:30.754ZApr 16 04:24:30.721 INFO starting preflight for deployment mgtriou
1452026-04-16T04:24:30.754ZApr 16 04:24:30.721 INFO propolis-server binary not found
1462026-04-16T04:24:30.754ZApr 16 04:24:30.721 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-04-16T04:24:34.787ZApr 16 04:24:34.755 INFO ovmf fd not found
1482026-04-16T04:24:34.787ZApr 16 04:24:34.755 INFO downloading ovmf
1492026-04-16T04:24:35.225ZApr 16 04:24:35.192 INFO base image for helios-2.9 does not exist, attempting to install
1502026-04-16T04:24:35.225ZApr 16 04:24:35.192 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-04-16T04:25:07.393ZApr 16 04:25:07.354 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-04-16T04:25:41.233ZApr 16 04:25:41.193 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-04-16T04:25:41.283ZApr 16 04:25:41.244 INFO copying image data to zvol
1542026-04-16T04:25:51.738ZApr 16 04:25:51.699 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-04-16T04:25:55.265ZApr 16 04:25:55.226 INFO base image for debian-13.2 does not exist, attempting to install
1562026-04-16T04:25:55.265ZApr 16 04:25:55.226 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-04-16T04:26:19.989ZApr 16 04:26:19.954 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-04-16T04:26:40.951ZApr 16 04:26:40.921 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-04-16T04:26:40.976ZApr 16 04:26:40.947 INFO copying image data to zvol
1602026-04-16T04:26:49.129ZApr 16 04:26:49.103 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-04-16T04:26:52.170ZApr 16 04:26:52.144 INFO base image for eos-4.35 does not exist, attempting to install
1622026-04-16T04:26:52.170ZApr 16 04:26:52.144 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-04-16T04:27:35.995ZApr 16 04:27:35.972 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-04-16T04:28:59.712ZApr 16 04:28:59.696 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-04-16T04:28:59.738ZApr 16 04:28:59.723 INFO copying image data to zvol
1662026-04-16T04:29:48.695ZApr 16 04:29:48.684 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-04-16T04:29:52.172ZApr 16 04:29:52.161 INFO creating links
1682026-04-16T04:29:52.173ZApr 16 04:29:52.161 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-04-16T04:29:52.197ZApr 16 04:29:52.161 DEBG destroying link mgtriou_ox_sn_sim0
1702026-04-16T04:29:52.197ZApr 16 04:29:52.161 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-04-16T04:29:52.197ZApr 16 04:29:52.163 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-04-16T04:29:52.197ZApr 16 04:29:52.171 DEBG link pair created
1732026-04-16T04:29:52.197ZApr 16 04:29:52.171 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-04-16T04:29:52.197ZApr 16 04:29:52.171 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-04-16T04:29:52.197ZApr 16 04:29:52.171 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-04-16T04:29:52.197ZApr 16 04:29:52.172 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-04-16T04:29:52.197ZApr 16 04:29:52.178 DEBG link pair created
1782026-04-16T04:29:52.197ZApr 16 04:29:52.179 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-04-16T04:29:52.197ZApr 16 04:29:52.179 DEBG destroying link mgtriou_ox_sn_sim1
1802026-04-16T04:29:52.197ZApr 16 04:29:52.179 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-04-16T04:29:52.197ZApr 16 04:29:52.181 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-04-16T04:29:52.221ZApr 16 04:29:52.187 DEBG link pair created
1832026-04-16T04:29:52.221ZApr 16 04:29:52.187 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-04-16T04:29:52.221ZApr 16 04:29:52.187 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-04-16T04:29:52.221ZApr 16 04:29:52.187 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-04-16T04:29:52.221ZApr 16 04:29:52.189 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-04-16T04:29:52.221ZApr 16 04:29:52.196 DEBG link pair created
1882026-04-16T04:29:52.221ZApr 16 04:29:52.196 INFO creating external links
1892026-04-16T04:29:52.221ZApr 16 04:29:52.196 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-04-16T04:29:52.221ZApr 16 04:29:52.196 INFO creating external link mgtriou_ox_vn_vnic2
1912026-04-16T04:29:52.221ZApr 16 04:29:52.198 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-04-16T04:29:52.221ZApr 16 04:29:52.198 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-04-16T04:29:52.221ZApr 16 04:29:52.198 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-04-16T04:29:52.222ZApr 16 04:29:52.199 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-04-16T04:29:52.222ZApr 16 04:29:52.199 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-04-16T04:29:52.222ZApr 16 04:29:52.199 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-04-16T04:29:52.222ZApr 16 04:29:52.200 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-04-16T04:29:52.222ZApr 16 04:29:52.200 INFO creating nodes
1992026-04-16T04:29:52.222ZApr 16 04:29:52.201 INFO ox: launching node
2002026-04-16T04:29:52.222ZApr 16 04:29:52.203 INFO cr1: launching node
2012026-04-16T04:29:52.222ZApr 16 04:29:52.205 INFO cr2: launching node
2022026-04-16T04:29:52.246ZApr 16 04:29:52.219 INFO launched instance ox with pid 841 on port 44007
2032026-04-16T04:29:52.246ZApr 16 04:29:52.219 INFO ox: instance ensure
2042026-04-16T04:29:52.246ZApr 16 04:29:52.220 INFO launched instance cr1 with pid 842 on port 63990
2052026-04-16T04:29:52.246ZApr 16 04:29:52.220 INFO cr1: instance ensure
2062026-04-16T04:29:52.246ZApr 16 04:29:52.220 INFO launched instance cr2 with pid 843 on port 59086
2072026-04-16T04:29:52.246ZApr 16 04:29:52.220 INFO cr2: instance ensure
2082026-04-16T04:29:54.688ZApr 16 04:29:54.676 INFO cr2: instance run
2092026-04-16T04:29:54.712ZApr 16 04:29:54.676 DEBG [sc] cr2: starting
2102026-04-16T04:29:54.712ZApr 16 04:29:54.677 DEBG [sc] cr2: connecting to [::1]:59086
2112026-04-16T04:29:54.712ZApr 16 04:29:54.678 DEBG [sc] cr2 waiting for prompt
2122026-04-16T04:29:54.737ZApr 16 04:29:54.706 INFO cr1: instance run
2132026-04-16T04:29:54.737ZApr 16 04:29:54.706 DEBG [sc] cr1: starting
2142026-04-16T04:29:54.737ZApr 16 04:29:54.706 DEBG [sc] cr1: connecting to [::1]:63990
2152026-04-16T04:29:54.737ZApr 16 04:29:54.707 DEBG [sc] cr1 waiting for prompt
2162026-04-16T04:29:54.767ZApr 16 04:29:54.755 INFO ox: instance run
2172026-04-16T04:29:54.791ZApr 16 04:29:54.755 DEBG [sc] ox: starting
2182026-04-16T04:29:54.791ZApr 16 04:29:54.756 DEBG [sc] ox: connecting to [::1]:44007
2192026-04-16T04:29:54.791ZApr 16 04:29:54.756 DEBG [sc] ox waiting for prompt
2202026-04-16T04:30:11.976ZApr 16 04:30:11.963 DEBG [sc] cr1: logging in
2212026-04-16T04:30:12.408ZApr 16 04:30:12.397 INFO cr1: mounting /opt/cargo-bay
2222026-04-16T04:30:12.409ZApr 16 04:30:12.397 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-04-16T04:30:12.435ZApr 16 04:30:12.418 DEBG [sc] cr1: executing command `cd`
2242026-04-16T04:30:12.459ZApr 16 04:30:12.429 INFO cr1: finished mounting /opt/cargo-bay
2252026-04-16T04:30:12.459ZApr 16 04:30:12.429 DEBG [sc] cr1: executing command `hostname cr1`
2262026-04-16T04:30:12.459ZApr 16 04:30:12.440 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-04-16T04:30:12.483ZApr 16 04:30:12.451 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-04-16T04:30:12.483ZApr 16 04:30:12.463 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-04-16T04:30:12.507ZApr 16 04:30:12.474 INFO cr1: logging out
2302026-04-16T04:30:14.551ZApr 16 04:30:14.539 INFO cr1: logged out
2312026-04-16T04:30:15.014ZApr 16 04:30:15.002 DEBG [sc] ox: logging in
2322026-04-16T04:30:15.194ZApr 16 04:30:15.182 INFO ox: mounting /opt/cargo-bay
2332026-04-16T04:30:15.194ZApr 16 04:30:15.183 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-04-16T04:30:16.201ZApr 16 04:30:16.189 DEBG [sc] ox: executing command `cd`
2352026-04-16T04:30:16.225ZApr 16 04:30:16.201 INFO ox: finished mounting /opt/cargo-bay
2362026-04-16T04:30:16.225ZApr 16 04:30:16.201 DEBG [sc] ox: executing command `hostname ox`
2372026-04-16T04:30:16.225ZApr 16 04:30:16.212 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2382026-04-16T04:30:16.249ZApr 16 04:30:16.223 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2392026-04-16T04:30:16.249ZApr 16 04:30:16.234 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2402026-04-16T04:30:16.273ZApr 16 04:30:16.244 INFO ox: logging out
2412026-04-16T04:30:16.273ZApr 16 04:30:16.254 DEBG [sc] cr2: logging in
2422026-04-16T04:30:16.333ZApr 16 04:30:16.322 INFO ox: logged out
2432026-04-16T04:30:16.671ZApr 16 04:30:16.659 INFO cr2: mounting /opt/cargo-bay
2442026-04-16T04:30:16.671ZApr 16 04:30:16.659 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-04-16T04:30:16.695ZApr 16 04:30:16.681 DEBG [sc] cr2: executing command `cd`
2462026-04-16T04:30:16.720ZApr 16 04:30:16.693 INFO cr2: finished mounting /opt/cargo-bay
2472026-04-16T04:30:16.720ZApr 16 04:30:16.693 DEBG [sc] cr2: executing command `hostname cr2`
2482026-04-16T04:30:16.720ZApr 16 04:30:16.704 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-04-16T04:30:16.744ZApr 16 04:30:16.714 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-04-16T04:30:16.744ZApr 16 04:30:16.725 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-04-16T04:30:16.768ZApr 16 04:30:16.736 INFO cr2: logging out
2522026-04-16T04:30:19.803ZApr 16 04:30:19.791 INFO cr2: logged out
2532026-04-16T04:30:19.827ZApr 16 04:30:19.791 DEBG [sc] ox: starting
2542026-04-16T04:30:19.827ZApr 16 04:30:19.791 DEBG [sc] ox: connecting to [::1]:44007
2552026-04-16T04:30:19.827ZApr 16 04:30:19.792 DEBG [sc] ox waiting for prompt
2562026-04-16T04:30:19.827ZApr 16 04:30:19.802 DEBG [sc] ox: logging in
2572026-04-16T04:30:19.904ZApr 16 04:30:19.892 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-04-16T04:30:25.414ZApr 16 04:30:25.402 DEBG [sc] ox: starting
2592026-04-16T04:30:25.414ZApr 16 04:30:25.402 DEBG [sc] ox: connecting to [::1]:44007
2602026-04-16T04:30:25.438ZApr 16 04:30:25.403 DEBG [sc] ox waiting for prompt
2612026-04-16T04:30:25.439ZApr 16 04:30:25.413 DEBG [sc] ox: logging in
2622026-04-16T04:30:25.513ZApr 16 04:30:25.501 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-04-16T04:30:25.601ZApr 16 04:30:25.589 DEBG [sc] ox: starting
2642026-04-16T04:30:25.601ZApr 16 04:30:25.589 DEBG [sc] ox: connecting to [::1]:44007
2652026-04-16T04:30:25.625ZApr 16 04:30:25.590 DEBG [sc] ox waiting for prompt
2662026-04-16T04:30:25.625ZApr 16 04:30:25.600 DEBG [sc] ox: logging in
2672026-04-16T04:30:25.699ZApr 16 04:30:25.688 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-04-16T04:30:25.766ZApr 16 04:30:25.754 INFO cr1: installing frr
2692026-04-16T04:30:25.766ZApr 16 04:30:25.754 INFO waiting for ceos to initialize
2702026-04-16T04:30:25.766ZApr 16 04:30:25.755 INFO ox: setting up npuvm
2712026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] cr1: starting
2722026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] cr1: connecting to [::1]:63990
2732026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] cr2: starting
2742026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] cr2: connecting to [::1]:59086
2752026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] ox: starting
2762026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] ox: connecting to [::1]:44007
2772026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] cr1 waiting for prompt
2782026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] ox waiting for prompt
2792026-04-16T04:30:25.790ZApr 16 04:30:25.755 DEBG [sc] cr2 waiting for prompt
2802026-04-16T04:30:25.790ZApr 16 04:30:25.765 DEBG [sc] cr2: logging in
2812026-04-16T04:30:25.790ZApr 16 04:30:25.766 DEBG [sc] ox: logging in
2822026-04-16T04:30:25.790ZApr 16 04:30:25.766 DEBG [sc] cr1: logging in
2832026-04-16T04:30:25.869ZApr 16 04:30:25.853 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-04-16T04:30:25.899ZApr 16 04:30:25.887 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-04-16T04:30:26.148ZApr 16 04:30:26.136 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-04-16T04:30:28.119ZApr 16 04:30:28.108 INFO cr2: executing eos script show version
2872026-04-16T04:30:28.119ZApr 16 04:30:28.108 DEBG [sc] cr2: starting
2882026-04-16T04:30:28.119ZApr 16 04:30:28.108 DEBG [sc] cr2: connecting to [::1]:59086
2892026-04-16T04:30:28.144ZApr 16 04:30:28.108 DEBG [sc] cr2 waiting for prompt
2902026-04-16T04:30:28.144ZApr 16 04:30:28.118 DEBG [sc] cr2: logging in
2912026-04-16T04:30:28.296ZApr 16 04:30:28.285 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-04-16T04:30:31.240ZApr 16 04:30:31.228 DEBG [sc] ox: starting
2932026-04-16T04:30:31.240ZApr 16 04:30:31.228 DEBG [sc] ox: connecting to [::1]:44007
2942026-04-16T04:30:31.264ZApr 16 04:30:31.228 DEBG [sc] ox waiting for prompt
2952026-04-16T04:30:31.264ZApr 16 04:30:31.239 DEBG [sc] ox: logging in
2962026-04-16T04:30:31.339ZApr 16 04:30:31.328 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-04-16T04:30:31.406ZApr 16 04:30:31.394 DEBG [sc] ox: starting
2982026-04-16T04:30:31.406ZApr 16 04:30:31.394 DEBG [sc] ox: connecting to [::1]:44007
2992026-04-16T04:30:31.430ZApr 16 04:30:31.395 DEBG [sc] ox waiting for prompt
3002026-04-16T04:30:31.430ZApr 16 04:30:31.405 DEBG [sc] ox: logging in
3012026-04-16T04:30:31.494ZApr 16 04:30:31.482 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-04-16T04:30:36.867ZApr 16 04:30:36.855 DEBG [sc] cr2: starting
3032026-04-16T04:30:36.867ZApr 16 04:30:36.855 DEBG [sc] cr2: connecting to [::1]:59086
3042026-04-16T04:30:36.891ZApr 16 04:30:36.855 DEBG [sc] cr2 waiting for prompt
3052026-04-16T04:30:36.891ZApr 16 04:30:36.865 DEBG [sc] cr2: logging in
3062026-04-16T04:30:37.031ZApr 16 04:30:37.020 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-04-16T04:30:39.137ZApr 16 04:30:39.125 INFO cr2: executing eos script show version
3082026-04-16T04:30:39.161ZApr 16 04:30:39.125 DEBG [sc] cr2: starting
3092026-04-16T04:30:39.161ZApr 16 04:30:39.125 DEBG [sc] cr2: connecting to [::1]:59086
3102026-04-16T04:30:39.161ZApr 16 04:30:39.126 DEBG [sc] cr2 waiting for prompt
3112026-04-16T04:30:39.161ZApr 16 04:30:39.136 DEBG [sc] cr2: logging in
3122026-04-16T04:30:39.323ZApr 16 04:30:39.312 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-04-16T04:30:41.710ZApr 16 04:30:41.692 DEBG [sc] cr2: starting
3142026-04-16T04:30:41.710ZApr 16 04:30:41.692 DEBG [sc] cr2: connecting to [::1]:59086
3152026-04-16T04:30:41.739ZApr 16 04:30:41.693 DEBG [sc] cr2 waiting for prompt
3162026-04-16T04:30:41.740ZApr 16 04:30:41.703 DEBG [sc] cr2: logging in
3172026-04-16T04:30:41.858ZApr 16 04:30:41.845 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-04-16T04:30:44.004ZApr 16 04:30:43.990 INFO cr2: executing eos script show version
3192026-04-16T04:30:44.004ZApr 16 04:30:43.990 DEBG [sc] cr2: starting
3202026-04-16T04:30:44.004ZApr 16 04:30:43.991 DEBG [sc] cr2: connecting to [::1]:59086
3212026-04-16T04:30:44.031ZApr 16 04:30:43.991 DEBG [sc] cr2 waiting for prompt
3222026-04-16T04:30:44.032ZApr 16 04:30:44.000 DEBG [sc] cr2: logging in
3232026-04-16T04:30:44.167ZApr 16 04:30:44.156 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-04-16T04:30:46.665ZApr 16 04:30:46.642 INFO cr2: executing eos script
3252026-04-16T04:30:46.665Z enable
3262026-04-16T04:30:46.665Z configure
3272026-04-16T04:30:46.665Z ipv6 unicast-routing
3282026-04-16T04:30:46.665Z ip routing ipv6 interfaces
3292026-04-16T04:30:46.665Z ip routing
3302026-04-16T04:30:46.665Z ip route 1.2.3.0/24 null0
3312026-04-16T04:30:46.665Z ipv6 route fd99::/64 null0
3322026-04-16T04:30:46.665Z interface et1
3332026-04-16T04:30:46.665Z no switchport
3342026-04-16T04:30:46.665Z ipv6 enable
3352026-04-16T04:30:46.665Z
3362026-04-16T04:30:46.665Z router bgp 45
3372026-04-16T04:30:46.665Z router-id 1.2.3.1
3382026-04-16T04:30:46.665Z no bgp default ipv4-unicast
3392026-04-16T04:30:46.665Z timers bgp 2 6
3402026-04-16T04:30:46.665Z neighbor ebgp peer group
3412026-04-16T04:30:46.665Z neighbor ebgp remote-as 33
3422026-04-16T04:30:46.665Z neighbor interface Et1 peer-group ebgp
3432026-04-16T04:30:46.665Z address-family ipv4
3442026-04-16T04:30:46.666Z neighbor ebgp activate
3452026-04-16T04:30:46.666Z neighbor ebgp next-hop address-family ipv6 originate
3462026-04-16T04:30:46.666Z network 1.2.3.0/24
3472026-04-16T04:30:46.666Z exit
3482026-04-16T04:30:46.666Z address-family ipv6
3492026-04-16T04:30:46.666Z neighbor ebgp activate
3502026-04-16T04:30:46.666Z neighbor ebgp next-hop address-family ipv6 originate
3512026-04-16T04:30:46.666Z network fd99::/64
3522026-04-16T04:30:46.666Z exit
3532026-04-16T04:30:46.697Z exit
3542026-04-16T04:30:46.697Z
3552026-04-16T04:30:46.698ZApr 16 04:30:46.642 DEBG [sc] cr2: starting
3562026-04-16T04:30:46.698ZApr 16 04:30:46.642 DEBG [sc] cr2: connecting to [::1]:59086
3572026-04-16T04:30:46.698ZApr 16 04:30:46.642 DEBG [sc] cr2 waiting for prompt
3582026-04-16T04:30:46.698ZApr 16 04:30:46.652 DEBG [sc] cr2: logging in
3592026-04-16T04:30:46.796ZApr 16 04:30:46.784 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-04-16T04:30:46.796Z enable
3612026-04-16T04:30:46.796Z configure
3622026-04-16T04:30:46.796Z ipv6 unicast-routing
3632026-04-16T04:30:46.796Z ip routing ipv6 interfaces
3642026-04-16T04:30:46.796Z ip routing
3652026-04-16T04:30:46.796Z ip route 1.2.3.0/24 null0
3662026-04-16T04:30:46.796Z ipv6 route fd99::/64 null0
3672026-04-16T04:30:46.796Z interface et1
3682026-04-16T04:30:46.796Z no switchport
3692026-04-16T04:30:46.796Z ipv6 enable
3702026-04-16T04:30:46.796Z
3712026-04-16T04:30:46.796Z router bgp 45
3722026-04-16T04:30:46.796Z router-id 1.2.3.1
3732026-04-16T04:30:46.796Z no bgp default ipv4-unicast
3742026-04-16T04:30:46.796Z timers bgp 2 6
3752026-04-16T04:30:46.796Z neighbor ebgp peer group
3762026-04-16T04:30:46.796Z neighbor ebgp remote-as 33
3772026-04-16T04:30:46.796Z neighbor interface Et1 peer-group ebgp
3782026-04-16T04:30:46.796Z address-family ipv4
3792026-04-16T04:30:46.796Z neighbor ebgp activate
3802026-04-16T04:30:46.796Z neighbor ebgp next-hop address-family ipv6 originate
3812026-04-16T04:30:46.796Z network 1.2.3.0/24
3822026-04-16T04:30:46.796Z exit
3832026-04-16T04:30:46.796Z address-family ipv6
3842026-04-16T04:30:46.796Z neighbor ebgp activate
3852026-04-16T04:30:46.796Z neighbor ebgp next-hop address-family ipv6 originate
3862026-04-16T04:30:46.796Z network fd99::/64
3872026-04-16T04:30:46.796Z exit
3882026-04-16T04:30:46.796Z exit
3892026-04-16T04:30:46.796Z '`
3902026-04-16T04:31:03.835ZApr 16 04:31:03.823 INFO cr1: enabling frr daemon bgpd
3912026-04-16T04:31:03.835ZApr 16 04:31:03.823 DEBG [sc] cr1: starting
3922026-04-16T04:31:03.835ZApr 16 04:31:03.823 DEBG [sc] cr1: connecting to [::1]:63990
3932026-04-16T04:31:03.957ZApr 16 04:31:03.824 DEBG [sc] cr1 waiting for prompt
3942026-04-16T04:31:03.957ZApr 16 04:31:03.834 DEBG [sc] cr1: logging in
3952026-04-16T04:31:03.986ZApr 16 04:31:03.966 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-04-16T04:31:09.993ZApr 16 04:31:06.034 DEBG [sc] cr1: starting
3972026-04-16T04:31:09.993ZApr 16 04:31:06.034 DEBG [sc] cr1: connecting to [::1]:63990
3982026-04-16T04:31:10.034ZApr 16 04:31:06.035 DEBG [sc] cr1 waiting for prompt
3992026-04-16T04:31:10.034ZApr 16 04:31:06.045 DEBG [sc] cr1: logging in
4002026-04-16T04:31:10.034ZApr 16 04:31:06.189 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-04-16T04:31:13.818ZApr 16 04:31:13.806 INFO cr1: executing frr script
4022026-04-16T04:31:13.818Z configure
4032026-04-16T04:31:13.818Z ip forwarding
4042026-04-16T04:31:13.818Z ipv6 forwarding
4052026-04-16T04:31:13.818Z ip route 1.2.3.0/24 null0
4062026-04-16T04:31:13.818Z ipv6 route fd99::/64 null0
4072026-04-16T04:31:13.818Z route-map PERMIT-ALL permit 10
4082026-04-16T04:31:13.818Z router bgp 44
4092026-04-16T04:31:13.818Z timers bgp 2 6
4102026-04-16T04:31:13.818Z neighbor enp0s8 interface remote-as external
4112026-04-16T04:31:13.818Z neighbor enp0s8 timers connect 1
4122026-04-16T04:31:13.818Z address-family ipv4 unicast
4132026-04-16T04:31:13.818Z network 1.2.3.0/24
4142026-04-16T04:31:13.819Z neighbor enp0s8 activate
4152026-04-16T04:31:13.819Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-04-16T04:31:13.819Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-04-16T04:31:13.819Z exit-address-family
4182026-04-16T04:31:13.819Z address-family ipv6 unicast
4192026-04-16T04:31:13.819Z network fd99::/64
4202026-04-16T04:31:13.819Z neighbor enp0s8 activate
4212026-04-16T04:31:13.819Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-04-16T04:31:13.819Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-04-16T04:31:13.819Z exit-address-family
4242026-04-16T04:31:13.819Z exit
4252026-04-16T04:31:13.819Z
4262026-04-16T04:31:13.819ZApr 16 04:31:13.806 DEBG [sc] cr1: starting
4272026-04-16T04:31:13.819ZApr 16 04:31:13.806 DEBG [sc] cr1: connecting to [::1]:63990
4282026-04-16T04:31:13.843ZApr 16 04:31:13.807 DEBG [sc] cr1 waiting for prompt
4292026-04-16T04:31:13.843ZApr 16 04:31:13.817 DEBG [sc] cr1: logging in
4302026-04-16T04:31:13.961ZApr 16 04:31:13.949 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 ' route-map PERMIT-ALL permit 10' -c ' router bgp 44' -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 ' neighbor enp0s8 route-map PERMIT-ALL out' -c ' neighbor enp0s8 route-map PERMIT-ALL in' -c ' exit-address-family' -c ' address-family ipv6 unicast' -c ' network fd99::/64' -c ' neighbor enp0s8 activate' -c ' neighbor enp0s8 route-map PERMIT-ALL out' -c ' neighbor enp0s8 route-map PERMIT-ALL in' -c ' exit-address-family' -c ' exit' -c ' '`
4312026-04-16T04:32:15.167ZApr 16 04:32:15.156 DEBG [sc] ox: starting
4322026-04-16T04:32:15.167ZApr 16 04:32:15.156 DEBG [sc] ox: connecting to [::1]:44007
4332026-04-16T04:32:15.192ZApr 16 04:32:15.156 DEBG [sc] ox waiting for prompt
4342026-04-16T04:32:15.192ZApr 16 04:32:15.167 DEBG [sc] ox: logging in
4352026-04-16T04:32:15.255ZApr 16 04:32:15.244 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-04-16T04:32:15.545ZApr 16 04:32:15.534 DEBG [sc] ox: starting
4372026-04-16T04:32:15.545ZApr 16 04:32:15.534 DEBG [sc] ox: connecting to [::1]:44007
4382026-04-16T04:32:15.570ZApr 16 04:32:15.534 DEBG [sc] ox waiting for prompt
4392026-04-16T04:32:15.570ZApr 16 04:32:15.546 DEBG [sc] ox: logging in
4402026-04-16T04:32:15.635ZApr 16 04:32:15.623 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-04-16T04:32:16.659ZApr 16 04:32:16.647 DEBG [sc] ox: starting
4422026-04-16T04:32:16.659ZApr 16 04:32:16.647 DEBG [sc] ox: connecting to [::1]:44007
4432026-04-16T04:32:16.683ZApr 16 04:32:16.648 DEBG [sc] ox waiting for prompt
4442026-04-16T04:32:16.683ZApr 16 04:32:16.648 DEBG [sc] ox: logging in
4452026-04-16T04:32:16.737ZApr 16 04:32:16.726 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4462026-04-16T04:32:16.793ZApr 16 04:32:16.782 DEBG [sc] ox: starting
4472026-04-16T04:32:16.793ZApr 16 04:32:16.782 DEBG [sc] ox: connecting to [::1]:44007
4482026-04-16T04:32:16.817ZApr 16 04:32:16.783 DEBG [sc] ox waiting for prompt
4492026-04-16T04:32:16.817ZApr 16 04:32:16.793 DEBG [sc] ox: logging in
4502026-04-16T04:32:16.881ZApr 16 04:32:16.870 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4512026-04-16T04:32:16.936ZApr 16 04:32:16.925 DEBG [sc] ox: starting
4522026-04-16T04:32:16.936ZApr 16 04:32:16.925 DEBG [sc] ox: connecting to [::1]:44007
4532026-04-16T04:32:16.960ZApr 16 04:32:16.925 DEBG [sc] ox waiting for prompt
4542026-04-16T04:32:16.960ZApr 16 04:32:16.935 DEBG [sc] ox: logging in
4552026-04-16T04:32:17.025ZApr 16 04:32:17.013 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4562026-04-16T04:32:17.080ZApr 16 04:32:17.068 DEBG [sc] ox: starting
4572026-04-16T04:32:17.080ZApr 16 04:32:17.068 DEBG [sc] ox: connecting to [::1]:44007
4582026-04-16T04:32:17.106ZApr 16 04:32:17.069 DEBG [sc] ox waiting for prompt
4592026-04-16T04:32:17.106ZApr 16 04:32:17.079 DEBG [sc] ox: logging in
4602026-04-16T04:32:17.167ZApr 16 04:32:17.155 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-04-16T04:32:17.233ZApr 16 04:32:17.222 DEBG [sc] ox: starting
4622026-04-16T04:32:17.233ZApr 16 04:32:17.222 DEBG [sc] ox: connecting to [::1]:44007
4632026-04-16T04:32:17.259ZApr 16 04:32:17.223 DEBG [sc] ox waiting for prompt
4642026-04-16T04:32:17.259ZApr 16 04:32:17.233 DEBG [sc] ox: logging in
4652026-04-16T04:32:17.321ZApr 16 04:32:17.310 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-04-16T04:32:17.388ZApr 16 04:32:17.376 DEBG [sc] ox: starting
4672026-04-16T04:32:17.388ZApr 16 04:32:17.376 DEBG [sc] ox: connecting to [::1]:44007
4682026-04-16T04:32:17.412ZApr 16 04:32:17.377 DEBG [sc] ox waiting for prompt
4692026-04-16T04:32:17.412ZApr 16 04:32:17.387 DEBG [sc] ox: logging in
4702026-04-16T04:32:17.487ZApr 16 04:32:17.475 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-04-16T04:32:17.553ZApr 16 04:32:17.541 DEBG [sc] ox: starting
4722026-04-16T04:32:17.553ZApr 16 04:32:17.541 DEBG [sc] ox: connecting to [::1]:44007
4732026-04-16T04:32:17.577ZApr 16 04:32:17.542 DEBG [sc] ox waiting for prompt
4742026-04-16T04:32:17.577ZApr 16 04:32:17.552 DEBG [sc] ox: logging in
4752026-04-16T04:32:17.641ZApr 16 04:32:17.630 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-04-16T04:32:17.708ZApr 16 04:32:17.697 DEBG [sc] ox: starting
4772026-04-16T04:32:17.708ZApr 16 04:32:17.697 DEBG [sc] ox: connecting to [::1]:44007
4782026-04-16T04:32:17.733ZApr 16 04:32:17.697 DEBG [sc] ox waiting for prompt
4792026-04-16T04:32:17.733ZApr 16 04:32:17.708 DEBG [sc] ox: logging in
4802026-04-16T04:32:17.796ZApr 16 04:32:17.785 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-04-16T04:32:17.865ZApr 16 04:32:17.854 INFO adding BGP router to mgd
4822026-04-16T04:32:19.888ZApr 16 04:32:19.876 INFO cr1: executing frr script show ip bgp json
4832026-04-16T04:32:19.888ZApr 16 04:32:19.877 DEBG [sc] cr1: starting
4842026-04-16T04:32:19.888ZApr 16 04:32:19.877 DEBG [sc] cr1: connecting to [::1]:63990
4852026-04-16T04:32:19.912ZApr 16 04:32:19.877 DEBG [sc] cr1 waiting for prompt
4862026-04-16T04:32:19.912ZApr 16 04:32:19.888 DEBG [sc] cr1: logging in
4872026-04-16T04:32:20.201ZApr 16 04:32:20.189 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-04-16T04:32:22.327ZApr 16 04:32:22.316 INFO cr1: executing frr script show bgp json
4892026-04-16T04:32:22.327ZApr 16 04:32:22.316 DEBG [sc] cr1: starting
4902026-04-16T04:32:22.328ZApr 16 04:32:22.316 DEBG [sc] cr1: connecting to [::1]:63990
4912026-04-16T04:32:22.352ZApr 16 04:32:22.317 DEBG [sc] cr1 waiting for prompt
4922026-04-16T04:32:22.352ZApr 16 04:32:22.327 DEBG [sc] cr1: logging in
4932026-04-16T04:32:22.481ZApr 16 04:32:22.470 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-04-16T04:32:24.623ZApr 16 04:32:24.611 INFO cr2: executing eos script show ip bgp | json
4952026-04-16T04:32:24.647ZApr 16 04:32:24.611 DEBG [sc] cr2: starting
4962026-04-16T04:32:24.647ZApr 16 04:32:24.611 DEBG [sc] cr2: connecting to [::1]:59086
4972026-04-16T04:32:24.647ZApr 16 04:32:24.612 DEBG [sc] cr2 waiting for prompt
4982026-04-16T04:32:24.647ZApr 16 04:32:24.623 DEBG [sc] cr2: logging in
4992026-04-16T04:32:24.959ZApr 16 04:32:24.947 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-04-16T04:32:27.335ZApr 16 04:32:27.324 INFO cr2: executing eos script show ipv6 bgp | json
5012026-04-16T04:32:27.359ZApr 16 04:32:27.324 DEBG [sc] cr2: starting
5022026-04-16T04:32:27.359ZApr 16 04:32:27.324 DEBG [sc] cr2: connecting to [::1]:59086
5032026-04-16T04:32:27.359ZApr 16 04:32:27.325 DEBG [sc] cr2 waiting for prompt
5042026-04-16T04:32:27.359ZApr 16 04:32:27.334 DEBG [sc] cr2: logging in
5052026-04-16T04:32:27.479ZApr 16 04:32:27.467 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-04-16T04:32:29.827ZApr 16 04:32:29.815 INFO trio bgp unnumbered test passed 🎉
5072026-04-16T04:32:29.827ZApr 16 04:32:29.815 INFO destroying runner for deployment mgtriou
5082026-04-16T04:32:29.827ZApr 16 04:32:29.815 INFO destroying deployment mgtriou
5092026-04-16T04:32:29.827ZApr 16 04:32:29.815 INFO destroying nodes
5102026-04-16T04:32:29.918ZApr 16 04:32:29.906 INFO destroying links
5112026-04-16T04:32:29.918ZApr 16 04:32:29.906 INFO destroying link mgtriou_ox_sn_vnic0
5122026-04-16T04:32:29.942ZApr 16 04:32:29.909 INFO destroying link mgtriou_ox_sn_sim0
5132026-04-16T04:32:29.942ZApr 16 04:32:29.910 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-04-16T04:32:30.925ZApr 16 04:32:30.913 INFO destroying link mgtriou_cr1_vn_sim0
5152026-04-16T04:32:30.949ZApr 16 04:32:30.914 INFO destroying link mgtriou_ox_sn_vnic1
5162026-04-16T04:32:30.949ZApr 16 04:32:30.916 INFO destroying link mgtriou_ox_sn_sim1
5172026-04-16T04:32:30.949ZApr 16 04:32:30.917 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-04-16T04:32:30.949ZApr 16 04:32:30.918 INFO destroying link mgtriou_cr2_vn_sim0
5192026-04-16T04:32:30.949ZApr 16 04:32:30.919 INFO destroying external links
5202026-04-16T04:32:30.949ZApr 16 04:32:30.919 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-04-16T04:32:30.949ZApr 16 04:32:30.920 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-04-16T04:32:30.949ZApr 16 04:32:30.922 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-04-16T04:32:30.949ZApr 16 04:32:30.923 INFO destroying images
5242026-04-16T04:32:31.401ZApr 16 04:32:31.390 INFO destroying workspace at .falcon
5252026-04-16T04:32:31.425Zprocess exited: duration 501524 ms, exit code 0
 
5262026-04-16T04:32:31.476Zfound 0 output files