01KMZVA2QRDXT45CVC0E491BNH: falcon

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

Buildomat Job: 01KMZVAWZ6WFGHJF9S28A14JD9

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-03-30T17:19:41.592Zjob dependencies complete; ready to run (waiting for 13 m 52 s)
22026-03-30T17:22:55.568Zjob assigned to worker 01KMZW49ZPKVXRDRGVX9CXGPQ2 [factory edgar, gimlet/BRM42220010/671] (queued for 3 m 13 s)
32026-03-30T17:22:56.585Zdownloading input: /input/build/work/debug/ddmadm
42026-03-30T17:23:18.944Zdownloaded input: /input/build/work/debug/ddmadm
52026-03-30T17:23:18.975Zdownloading input: /input/build-interop/work/testbed.tar.gz
62026-03-30T17:23:31.161Zdownloaded input: /input/build-interop/work/testbed.tar.gz
72026-03-30T17:23:31.161Zdownloading input: /input/build-interop/work/dhcp-server
82026-03-30T17:23:33.665Zdownloaded input: /input/build-interop/work/dhcp-server
92026-03-30T17:23:33.665Zdownloading input: /input/build/work/debug/ddmd
102026-03-30T17:24:03.698Zdownloaded input: /input/build/work/debug/ddmd
112026-03-30T17:24:03.699Zdownloading input: /input/build/work/debug/mgadm
122026-03-30T17:24:30.491Zdownloaded input: /input/build/work/debug/mgadm
132026-03-30T17:24:30.491Zdownloading input: /input/build/work/debug/mgd
142026-03-30T17:25:01.364Zdownloaded input: /input/build/work/debug/mgd
152026-03-30T17:25:01.388Zdownloading input: /input/build/work/release/ddmadm
162026-03-30T17:25:03.433Zdownloaded input: /input/build/work/release/ddmadm
172026-03-30T17:25:03.433Zdownloading input: /input/build/work/release/ddmd
182026-03-30T17:25:05.678Zdownloaded input: /input/build/work/release/ddmd
192026-03-30T17:25:05.678Zdownloading input: /input/build/work/release/falcon-lab
202026-03-30T17:25:07.444Zdownloaded input: /input/build/work/release/falcon-lab
212026-03-30T17:25:07.444Zdownloading input: /input/build/work/release/mgadm
222026-03-30T17:25:09.891Zdownloaded input: /input/build/work/release/mgadm
232026-03-30T17:25:09.891Zdownloading input: /input/build/work/release/mgd
242026-03-30T17:25:13.217Zdownloaded input: /input/build/work/release/mgd
 
252026-03-30T17:25:13.217Zstarting task 0: "setup"
262026-03-30T17:25:13.242Z++ uname -s
272026-03-30T17:25:13.242Z+ kern=SunOS
282026-03-30T17:25:13.242Z+ build_user=build
292026-03-30T17:25:13.242Z+ build_uid=12345
302026-03-30T17:25:13.242Z+ work_dir=/work
312026-03-30T17:25:13.242Z+ input_dir=/input
322026-03-30T17:25:13.242Z+ [[ 0 == 12345 ]]
332026-03-30T17:25:13.242Z+ case "$kern" in
342026-03-30T17:25:13.242Z+ groupadd -g 12345 build
352026-03-30T17:25:13.266Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-03-30T17:25:15.254Z+ zfs create -o mountpoint=/work rpool/work
372026-03-30T17:25:16.323Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-03-30T17:25:16.348Z+ home_fs=
392026-03-30T17:25:16.348Z+ [[ '' == autofs ]]
402026-03-30T17:25:16.348Z+ mkdir -p /home/build
412026-03-30T17:25:16.348Z+ chown build:build /home/build /work
422026-03-30T17:25:17.336Z+ chmod 0700 /home/build /work
432026-03-30T17:25:17.360Zprocess exited: duration 4121 ms, exit code 0
 
442026-03-30T17:25:17.408Zstarting task 1: "authentication"
452026-03-30T17:25:17.502Zprocess exited: duration 93 ms, exit code 0
 
462026-03-30T17:25:17.551Zstarting task 2: "build"
472026-03-30T17:25:17.575Z+ set -e
482026-03-30T17:25:17.575Z+ banner zpool
492026-03-30T17:25:17.575Z
502026-03-30T17:25:17.575Z ###### ##### #### #### #
512026-03-30T17:25:17.575Z # # # # # # # #
522026-03-30T17:25:17.575Z # # # # # # # #
532026-03-30T17:25:17.575Z # ##### # # # # #
542026-03-30T17:25:17.575Z # # # # # # #
552026-03-30T17:25:17.575Z ###### # #### #### ######
562026-03-30T17:25:17.575Z
572026-03-30T17:25:17.575Z++ pfexec diskinfo -pH
582026-03-30T17:25:17.599Z++ sort -k8 -n -r
592026-03-30T17:25:17.599Z++ head -1
602026-03-30T17:25:17.599Z++ awk '{print $2}'
612026-03-30T17:25:17.624Z+ DISK=c9t0014EE81000BC3B1d0
622026-03-30T17:25:17.624Z+ export DISK
632026-03-30T17:25:17.624Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
642026-03-30T17:25:17.688Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-03-30T17:25:17.716Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-03-30T17:25:17.861Z+ [[ true =~ true ]]
672026-03-30T17:25:17.886Z+ pfexec zpool trim cpool
682026-03-30T17:25:17.886Z++ zpool status -t cpool
692026-03-30T17:25:17.910Z+ [[ ! pool: cpool
702026-03-30T17:25:17.911Z state: ONLINE
712026-03-30T17:25:17.911Z scan: none requested
722026-03-30T17:25:17.911Zconfig:
732026-03-30T17:25:17.911Z
742026-03-30T17:25:17.911Z NAME STATE READ WRITE CKSUM
752026-03-30T17:25:17.911Z cpool ONLINE 0 0 0
762026-03-30T17:25:17.911Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at March 30, 2026 at 05:25:17 PM UTC)
772026-03-30T17:25:17.911Z
782026-03-30T17:25:17.911Zerrors: No known data errors =~ 100% ]]
792026-03-30T17:25:17.911Z+ sleep 10
802026-03-30T17:25:27.893Z++ zpool status -t cpool
812026-03-30T17:25:27.918Z+ [[ ! pool: cpool
822026-03-30T17:25:27.919Z state: ONLINE
832026-03-30T17:25:27.919Z scan: none requested
842026-03-30T17:25:27.919Zconfig:
852026-03-30T17:25:27.919Z
862026-03-30T17:25:27.919Z NAME STATE READ WRITE CKSUM
872026-03-30T17:25:27.919Z cpool ONLINE 0 0 0
882026-03-30T17:25:27.919Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at March 30, 2026 at 05:25:17 PM UTC)
892026-03-30T17:25:27.919Z
902026-03-30T17:25:27.919Zerrors: No known data errors =~ 100% ]]
912026-03-30T17:25:27.919Z+ sleep 10
922026-03-30T17:25:37.907Z++ zpool status -t cpool
932026-03-30T17:25:37.934Z+ [[ ! pool: cpool
942026-03-30T17:25:37.934Z state: ONLINE
952026-03-30T17:25:37.934Z scan: none requested
962026-03-30T17:25:37.934Zconfig:
972026-03-30T17:25:37.934Z
982026-03-30T17:25:37.934Z NAME STATE READ WRITE CKSUM
992026-03-30T17:25:37.934Z cpool ONLINE 0 0 0
1002026-03-30T17:25:37.934Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at March 30, 2026 at 05:25:32 PM UTC)
1012026-03-30T17:25:37.934Z
1022026-03-30T17:25:37.934Zerrors: No known data errors =~ 100% ]]
1032026-03-30T17:25:37.934Z+ pfexec chown 12345 /ci
1042026-03-30T17:25:37.934Z+ cd /ci
1052026-03-30T17:25:37.934Z+ export FALCON_DATASET=cpool/falcon
1062026-03-30T17:25:37.934Z+ FALCON_DATASET=cpool/falcon
1072026-03-30T17:25:37.934Z+ banner setup
1082026-03-30T17:25:37.934Z
1092026-03-30T17:25:37.934Z #### ###### ##### # # #####
1102026-03-30T17:25:37.934Z # # # # # # #
1112026-03-30T17:25:37.934Z #### ##### # # # # #
1122026-03-30T17:25:37.934Z # # # # # #####
1132026-03-30T17:25:37.934Z # # # # # # #
1142026-03-30T17:25:37.934Z #### ###### # #### #
1152026-03-30T17:25:37.934Z
1162026-03-30T17:25:37.934Z+ cp /input/build-interop/work/dhcp-server .
1172026-03-30T17:25:38.241Z+ cp /input/build/work/release/falcon-lab .
1182026-03-30T17:25:38.274Z+ cp /input/build/work/release/mgd .
1192026-03-30T17:25:38.342Z+ cp /input/build/work/release/ddmd .
1202026-03-30T17:25:38.393Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-03-30T17:25:38.417Z+ mkdir -p cargo-bay
1222026-03-30T17:25:38.417Z+ mv mgd cargo-bay/
1232026-03-30T17:25:38.418Z+ mv ddmd cargo-bay/
1242026-03-30T17:25:38.418Z+ export EXT_INTERFACE=igb0
1252026-03-30T17:25:38.418Z+ EXT_INTERFACE=igb0
1262026-03-30T17:25:38.418Z++ bmat address ls -f extra -Ho first
1272026-03-30T17:25:38.442Z+ first=10.151.6.164
1282026-03-30T17:25:38.442Z++ bmat address ls -f extra -Ho last
1292026-03-30T17:25:38.465Z+ last=10.151.6.227
1302026-03-30T17:25:38.465Z++ bmat address ls -f extra -Ho gateway
1312026-03-30T17:25:38.490Z+ gw=10.151.6.1
1322026-03-30T17:25:38.490Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-03-30T17:25:38.514Z++ sed 's#/.*##g'
1342026-03-30T17:25:38.514Z+ server=10.151.6.100
1352026-03-30T17:25:38.514Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1362026-03-30T17:25:38.514Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-03-30T17:25:38.514Z+ error=
1382026-03-30T17:25:38.514Z+ RUST_LOG=debug
1392026-03-30T17:25:38.514Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-03-30T17:25:38.539ZMar 30 17:25:38.523 DEBG using default route interface igb0
1412026-03-30T17:25:38.539ZMar 30 17:25:38.523 DEBG using default route interface igb0
1422026-03-30T17:25:38.539ZMar 30 17:25:38.523 DEBG using default route interface igb0
1432026-03-30T17:25:38.539ZMar 30 17:25:38.523 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-03-30T17:25:38.539ZMar 30 17:25:38.523 INFO starting preflight for deployment mgtriou
1452026-03-30T17:25:38.539ZMar 30 17:25:38.523 INFO propolis-server binary not found
1462026-03-30T17:25:38.539ZMar 30 17:25:38.523 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-03-30T17:25:42.319ZMar 30 17:25:42.313 INFO ovmf fd not found
1482026-03-30T17:25:42.319ZMar 30 17:25:42.313 INFO downloading ovmf
1492026-03-30T17:25:42.697ZMar 30 17:25:42.691 INFO base image for helios-2.9 does not exist, attempting to install
1502026-03-30T17:25:42.697ZMar 30 17:25:42.691 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-03-30T17:26:11.274ZMar 30 17:26:11.274 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-03-30T17:26:44.928ZMar 30 17:26:44.932 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-03-30T17:26:44.978ZMar 30 17:26:44.984 INFO copying image data to zvol
1542026-03-30T17:26:55.579ZMar 30 17:26:55.586 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-03-30T17:26:58.869ZMar 30 17:26:58.876 INFO base image for debian-13.2 does not exist, attempting to install
1562026-03-30T17:26:58.869ZMar 30 17:26:58.876 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-03-30T17:27:19.319ZMar 30 17:27:19.327 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-03-30T17:27:40.432ZMar 30 17:27:40.438 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-03-30T17:27:40.458ZMar 30 17:27:40.465 INFO copying image data to zvol
1602026-03-30T17:27:48.087ZMar 30 17:27:48.092 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-03-30T17:27:51.307ZMar 30 17:27:51.313 INFO base image for eos-4.35 does not exist, attempting to install
1622026-03-30T17:27:51.307ZMar 30 17:27:51.313 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-03-30T17:28:34.913ZMar 30 17:28:34.915 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-03-30T17:29:58.510ZMar 30 17:29:58.496 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-03-30T17:29:58.536ZMar 30 17:29:58.523 INFO copying image data to zvol
1662026-03-30T17:30:47.326ZMar 30 17:30:47.312 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-03-30T17:30:50.766ZMar 30 17:30:50.752 INFO creating links
1682026-03-30T17:30:50.766ZMar 30 17:30:50.752 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-03-30T17:30:50.791ZMar 30 17:30:50.752 DEBG destroying link mgtriou_ox_sn_sim0
1702026-03-30T17:30:50.791ZMar 30 17:30:50.752 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-03-30T17:30:50.791ZMar 30 17:30:50.754 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-03-30T17:30:50.791ZMar 30 17:30:50.762 DEBG link pair created
1732026-03-30T17:30:50.791ZMar 30 17:30:50.762 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-03-30T17:30:50.791ZMar 30 17:30:50.762 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-03-30T17:30:50.791ZMar 30 17:30:50.762 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-03-30T17:30:50.791ZMar 30 17:30:50.763 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-03-30T17:30:50.791ZMar 30 17:30:50.770 DEBG link pair created
1782026-03-30T17:30:50.791ZMar 30 17:30:50.770 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-03-30T17:30:50.791ZMar 30 17:30:50.770 DEBG destroying link mgtriou_ox_sn_sim1
1802026-03-30T17:30:50.791ZMar 30 17:30:50.770 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-03-30T17:30:50.791ZMar 30 17:30:50.771 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-03-30T17:30:50.816ZMar 30 17:30:50.778 DEBG link pair created
1832026-03-30T17:30:50.816ZMar 30 17:30:50.778 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-03-30T17:30:50.816ZMar 30 17:30:50.778 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-03-30T17:30:50.816ZMar 30 17:30:50.778 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-03-30T17:30:50.816ZMar 30 17:30:50.779 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-03-30T17:30:50.816ZMar 30 17:30:50.786 DEBG link pair created
1882026-03-30T17:30:50.816ZMar 30 17:30:50.786 INFO creating external links
1892026-03-30T17:30:50.816ZMar 30 17:30:50.786 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-03-30T17:30:50.816ZMar 30 17:30:50.786 INFO creating external link mgtriou_ox_vn_vnic2
1912026-03-30T17:30:50.816ZMar 30 17:30:50.787 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-03-30T17:30:50.816ZMar 30 17:30:50.787 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-03-30T17:30:50.816ZMar 30 17:30:50.787 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-03-30T17:30:50.817ZMar 30 17:30:50.789 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-03-30T17:30:50.817ZMar 30 17:30:50.789 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-03-30T17:30:50.817ZMar 30 17:30:50.789 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-03-30T17:30:50.817ZMar 30 17:30:50.790 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-03-30T17:30:50.817ZMar 30 17:30:50.790 INFO creating nodes
1992026-03-30T17:30:50.817ZMar 30 17:30:50.790 INFO ox: launching node
2002026-03-30T17:30:50.817ZMar 30 17:30:50.792 INFO cr1: launching node
2012026-03-30T17:30:50.817ZMar 30 17:30:50.794 INFO cr2: launching node
2022026-03-30T17:30:50.841ZMar 30 17:30:50.808 INFO launched instance ox with pid 851 on port 48156
2032026-03-30T17:30:50.841ZMar 30 17:30:50.808 INFO ox: instance ensure
2042026-03-30T17:30:50.841ZMar 30 17:30:50.809 INFO launched instance cr1 with pid 852 on port 50234
2052026-03-30T17:30:50.841ZMar 30 17:30:50.809 INFO cr1: instance ensure
2062026-03-30T17:30:50.841ZMar 30 17:30:50.809 INFO launched instance cr2 with pid 853 on port 58859
2072026-03-30T17:30:50.841ZMar 30 17:30:50.809 INFO cr2: instance ensure
2082026-03-30T17:30:53.143ZMar 30 17:30:53.129 INFO ox: instance run
2092026-03-30T17:30:53.167ZMar 30 17:30:53.130 DEBG [sc] ox: starting
2102026-03-30T17:30:53.167ZMar 30 17:30:53.130 DEBG [sc] ox: connecting to [::1]:48156
2112026-03-30T17:30:53.167ZMar 30 17:30:53.131 DEBG [sc] ox waiting for prompt
2122026-03-30T17:30:53.345ZMar 30 17:30:53.332 INFO cr1: instance run
2132026-03-30T17:30:53.370ZMar 30 17:30:53.332 DEBG [sc] cr1: starting
2142026-03-30T17:30:53.370ZMar 30 17:30:53.332 DEBG [sc] cr1: connecting to [::1]:50234
2152026-03-30T17:30:53.370ZMar 30 17:30:53.333 DEBG [sc] cr1 waiting for prompt
2162026-03-30T17:30:53.399ZMar 30 17:30:53.385 INFO cr2: instance run
2172026-03-30T17:30:53.423ZMar 30 17:30:53.386 DEBG [sc] cr2: starting
2182026-03-30T17:30:53.423ZMar 30 17:30:53.386 DEBG [sc] cr2: connecting to [::1]:58859
2192026-03-30T17:30:53.423ZMar 30 17:30:53.386 DEBG [sc] cr2 waiting for prompt
2202026-03-30T17:31:10.497ZMar 30 17:31:10.483 DEBG [sc] cr1: logging in
2212026-03-30T17:31:10.889ZMar 30 17:31:10.876 INFO cr1: mounting /opt/cargo-bay
2222026-03-30T17:31:10.890ZMar 30 17:31:10.876 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-03-30T17:31:10.915ZMar 30 17:31:10.887 DEBG [sc] cr1: executing command `cd`
2242026-03-30T17:31:10.915ZMar 30 17:31:10.898 INFO cr1: finished mounting /opt/cargo-bay
2252026-03-30T17:31:10.915ZMar 30 17:31:10.898 DEBG [sc] cr1: executing command `hostname cr1`
2262026-03-30T17:31:10.940ZMar 30 17:31:10.908 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-03-30T17:31:10.940ZMar 30 17:31:10.919 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-03-30T17:31:10.965ZMar 30 17:31:10.930 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-03-30T17:31:10.965ZMar 30 17:31:10.942 INFO cr1: logging out
2302026-03-30T17:31:13.022ZMar 30 17:31:13.008 INFO cr1: logged out
2312026-03-30T17:31:13.710ZMar 30 17:31:13.697 DEBG [sc] ox: logging in
2322026-03-30T17:31:13.901ZMar 30 17:31:13.887 INFO ox: mounting /opt/cargo-bay
2332026-03-30T17:31:13.901ZMar 30 17:31:13.887 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-03-30T17:31:14.978ZMar 30 17:31:14.963 DEBG [sc] ox: executing command `cd`
2352026-03-30T17:31:15.003ZMar 30 17:31:14.974 INFO ox: finished mounting /opt/cargo-bay
2362026-03-30T17:31:15.003ZMar 30 17:31:14.974 DEBG [sc] ox: executing command `hostname ox`
2372026-03-30T17:31:15.003ZMar 30 17:31:14.985 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2382026-03-30T17:31:15.028ZMar 30 17:31:14.996 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2392026-03-30T17:31:15.028ZMar 30 17:31:15.008 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2402026-03-30T17:31:15.053ZMar 30 17:31:15.019 INFO ox: logging out
2412026-03-30T17:31:15.078ZMar 30 17:31:15.049 DEBG [sc] cr2: logging in
2422026-03-30T17:31:15.145ZMar 30 17:31:15.131 INFO ox: logged out
2432026-03-30T17:31:15.539ZMar 30 17:31:15.525 INFO cr2: mounting /opt/cargo-bay
2442026-03-30T17:31:15.539ZMar 30 17:31:15.525 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-03-30T17:31:15.565ZMar 30 17:31:15.536 DEBG [sc] cr2: executing command `cd`
2462026-03-30T17:31:15.566ZMar 30 17:31:15.547 INFO cr2: finished mounting /opt/cargo-bay
2472026-03-30T17:31:15.566ZMar 30 17:31:15.548 DEBG [sc] cr2: executing command `hostname cr2`
2482026-03-30T17:31:15.590ZMar 30 17:31:15.559 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-03-30T17:31:15.590ZMar 30 17:31:15.569 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-03-30T17:31:15.614ZMar 30 17:31:15.580 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-03-30T17:31:15.614ZMar 30 17:31:15.591 INFO cr2: logging out
2522026-03-30T17:31:18.524ZMar 30 17:31:18.510 INFO cr2: logged out
2532026-03-30T17:31:18.549ZMar 30 17:31:18.511 DEBG [sc] ox: starting
2542026-03-30T17:31:18.549ZMar 30 17:31:18.511 DEBG [sc] ox: connecting to [::1]:48156
2552026-03-30T17:31:18.549ZMar 30 17:31:18.511 DEBG [sc] ox waiting for prompt
2562026-03-30T17:31:18.549ZMar 30 17:31:18.522 DEBG [sc] ox: logging in
2572026-03-30T17:31:18.626ZMar 30 17:31:18.611 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-03-30T17:31:24.359ZMar 30 17:31:24.346 DEBG [sc] ox: starting
2592026-03-30T17:31:24.360ZMar 30 17:31:24.346 DEBG [sc] ox: connecting to [::1]:48156
2602026-03-30T17:31:24.385ZMar 30 17:31:24.346 DEBG [sc] ox waiting for prompt
2612026-03-30T17:31:24.385ZMar 30 17:31:24.357 DEBG [sc] ox: logging in
2622026-03-30T17:31:24.458ZMar 30 17:31:24.445 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-03-30T17:31:24.547ZMar 30 17:31:24.533 DEBG [sc] ox: starting
2642026-03-30T17:31:24.547ZMar 30 17:31:24.533 DEBG [sc] ox: connecting to [::1]:48156
2652026-03-30T17:31:24.572ZMar 30 17:31:24.534 DEBG [sc] ox waiting for prompt
2662026-03-30T17:31:24.572ZMar 30 17:31:24.545 DEBG [sc] ox: logging in
2672026-03-30T17:31:24.649ZMar 30 17:31:24.633 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-03-30T17:31:24.712ZMar 30 17:31:24.699 INFO cr1: installing frr
2692026-03-30T17:31:24.712ZMar 30 17:31:24.699 INFO waiting for ceos to initialize
2702026-03-30T17:31:24.712ZMar 30 17:31:24.699 INFO ox: setting up npuvm
2712026-03-30T17:31:24.737ZMar 30 17:31:24.699 DEBG [sc] cr1: starting
2722026-03-30T17:31:24.737ZMar 30 17:31:24.699 DEBG [sc] cr2: starting
2732026-03-30T17:31:24.737ZMar 30 17:31:24.699 DEBG [sc] cr1: connecting to [::1]:50234
2742026-03-30T17:31:24.737ZMar 30 17:31:24.699 DEBG [sc] cr2: connecting to [::1]:58859
2752026-03-30T17:31:24.737ZMar 30 17:31:24.699 DEBG [sc] ox: starting
2762026-03-30T17:31:24.738ZMar 30 17:31:24.699 DEBG [sc] ox: connecting to [::1]:48156
2772026-03-30T17:31:24.738ZMar 30 17:31:24.699 DEBG [sc] ox waiting for prompt
2782026-03-30T17:31:24.738ZMar 30 17:31:24.699 DEBG [sc] cr1 waiting for prompt
2792026-03-30T17:31:24.738ZMar 30 17:31:24.700 DEBG [sc] cr2 waiting for prompt
2802026-03-30T17:31:24.738ZMar 30 17:31:24.710 DEBG [sc] ox: logging in
2812026-03-30T17:31:24.738ZMar 30 17:31:24.711 DEBG [sc] cr1: logging in
2822026-03-30T17:31:24.738ZMar 30 17:31:24.711 DEBG [sc] cr2: logging in
2832026-03-30T17:31:24.800ZMar 30 17:31:24.787 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-03-30T17:31:24.858ZMar 30 17:31:24.844 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-03-30T17:31:25.061ZMar 30 17:31:25.047 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-03-30T17:31:27.082ZMar 30 17:31:27.068 INFO cr2: executing eos script show version
2872026-03-30T17:31:27.106ZMar 30 17:31:27.068 DEBG [sc] cr2: starting
2882026-03-30T17:31:27.106ZMar 30 17:31:27.068 DEBG [sc] cr2: connecting to [::1]:58859
2892026-03-30T17:31:27.106ZMar 30 17:31:27.069 DEBG [sc] cr2 waiting for prompt
2902026-03-30T17:31:27.106ZMar 30 17:31:27.079 DEBG [sc] cr2: logging in
2912026-03-30T17:31:27.247ZMar 30 17:31:27.234 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-03-30T17:31:30.115ZMar 30 17:31:30.101 DEBG [sc] ox: starting
2932026-03-30T17:31:30.115ZMar 30 17:31:30.102 DEBG [sc] ox: connecting to [::1]:48156
2942026-03-30T17:31:30.142ZMar 30 17:31:30.102 DEBG [sc] ox waiting for prompt
2952026-03-30T17:31:30.142ZMar 30 17:31:30.113 DEBG [sc] ox: logging in
2962026-03-30T17:31:30.203ZMar 30 17:31:30.189 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-03-30T17:31:30.268ZMar 30 17:31:30.255 DEBG [sc] ox: starting
2982026-03-30T17:31:30.268ZMar 30 17:31:30.255 DEBG [sc] ox: connecting to [::1]:48156
2992026-03-30T17:31:30.293ZMar 30 17:31:30.255 DEBG [sc] ox waiting for prompt
3002026-03-30T17:31:30.293ZMar 30 17:31:30.266 DEBG [sc] ox: logging in
3012026-03-30T17:31:30.358ZMar 30 17:31:30.344 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-03-30T17:31:35.502ZMar 30 17:31:35.487 DEBG [sc] cr2: starting
3032026-03-30T17:31:35.502ZMar 30 17:31:35.487 DEBG [sc] cr2: connecting to [::1]:58859
3042026-03-30T17:31:35.526ZMar 30 17:31:35.488 DEBG [sc] cr2 waiting for prompt
3052026-03-30T17:31:35.526ZMar 30 17:31:35.498 DEBG [sc] cr2: logging in
3062026-03-30T17:31:35.679ZMar 30 17:31:35.665 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-03-30T17:31:37.784ZMar 30 17:31:37.770 INFO cr2: executing eos script show version
3082026-03-30T17:31:37.784ZMar 30 17:31:37.770 DEBG [sc] cr2: starting
3092026-03-30T17:31:37.784ZMar 30 17:31:37.770 DEBG [sc] cr2: connecting to [::1]:58859
3102026-03-30T17:31:37.809ZMar 30 17:31:37.771 DEBG [sc] cr2 waiting for prompt
3112026-03-30T17:31:37.809ZMar 30 17:31:37.782 DEBG [sc] cr2: logging in
3122026-03-30T17:31:37.971ZMar 30 17:31:37.957 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-03-30T17:31:40.319ZMar 30 17:31:40.305 DEBG [sc] cr2: starting
3142026-03-30T17:31:40.319ZMar 30 17:31:40.305 DEBG [sc] cr2: connecting to [::1]:58859
3152026-03-30T17:31:40.343ZMar 30 17:31:40.305 DEBG [sc] cr2 waiting for prompt
3162026-03-30T17:31:40.343ZMar 30 17:31:40.315 DEBG [sc] cr2: logging in
3172026-03-30T17:31:40.484ZMar 30 17:31:40.471 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-03-30T17:31:42.617ZMar 30 17:31:42.603 INFO cr2: executing eos script show version
3192026-03-30T17:31:42.617ZMar 30 17:31:42.603 DEBG [sc] cr2: starting
3202026-03-30T17:31:42.617ZMar 30 17:31:42.603 DEBG [sc] cr2: connecting to [::1]:58859
3212026-03-30T17:31:42.641ZMar 30 17:31:42.603 DEBG [sc] cr2 waiting for prompt
3222026-03-30T17:31:42.641ZMar 30 17:31:42.614 DEBG [sc] cr2: logging in
3232026-03-30T17:31:42.782ZMar 30 17:31:42.769 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-03-30T17:31:45.278ZMar 30 17:31:45.264 INFO cr2: executing eos script
3252026-03-30T17:31:45.278Z enable
3262026-03-30T17:31:45.278Z configure
3272026-03-30T17:31:45.278Z ipv6 unicast-routing
3282026-03-30T17:31:45.278Z ip routing ipv6 interfaces
3292026-03-30T17:31:45.278Z ip routing
3302026-03-30T17:31:45.278Z ip route 1.2.3.0/24 null0
3312026-03-30T17:31:45.278Z ipv6 route fd99::/64 null0
3322026-03-30T17:31:45.278Z interface et1
3332026-03-30T17:31:45.278Z no switchport
3342026-03-30T17:31:45.279Z ipv6 enable
3352026-03-30T17:31:45.279Z
3362026-03-30T17:31:45.279Z router bgp 45
3372026-03-30T17:31:45.279Z router-id 1.2.3.1
3382026-03-30T17:31:45.279Z no bgp default ipv4-unicast
3392026-03-30T17:31:45.279Z timers bgp 2 6
3402026-03-30T17:31:45.279Z neighbor ebgp peer group
3412026-03-30T17:31:45.279Z neighbor ebgp remote-as 33
3422026-03-30T17:31:45.279Z neighbor interface Et1 peer-group ebgp
3432026-03-30T17:31:45.279Z address-family ipv4
3442026-03-30T17:31:45.279Z neighbor ebgp activate
3452026-03-30T17:31:45.279Z neighbor ebgp next-hop address-family ipv6 originate
3462026-03-30T17:31:45.279Z network 1.2.3.0/24
3472026-03-30T17:31:45.279Z exit
3482026-03-30T17:31:45.279Z address-family ipv6
3492026-03-30T17:31:45.279Z neighbor ebgp activate
3502026-03-30T17:31:45.279Z neighbor ebgp next-hop address-family ipv6 originate
3512026-03-30T17:31:45.280Z network fd99::/64
3522026-03-30T17:31:45.280Z exit
3532026-03-30T17:31:45.280Z exit
3542026-03-30T17:31:45.280Z
3552026-03-30T17:31:45.305ZMar 30 17:31:45.264 DEBG [sc] cr2: starting
3562026-03-30T17:31:45.305ZMar 30 17:31:45.265 DEBG [sc] cr2: connecting to [::1]:58859
3572026-03-30T17:31:45.305ZMar 30 17:31:45.265 DEBG [sc] cr2 waiting for prompt
3582026-03-30T17:31:45.305ZMar 30 17:31:45.275 DEBG [sc] cr2: logging in
3592026-03-30T17:31:45.432ZMar 30 17:31:45.418 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-03-30T17:31:45.432Z enable
3612026-03-30T17:31:45.432Z configure
3622026-03-30T17:31:45.432Z ipv6 unicast-routing
3632026-03-30T17:31:45.432Z ip routing ipv6 interfaces
3642026-03-30T17:31:45.432Z ip routing
3652026-03-30T17:31:45.432Z ip route 1.2.3.0/24 null0
3662026-03-30T17:31:45.432Z ipv6 route fd99::/64 null0
3672026-03-30T17:31:45.432Z interface et1
3682026-03-30T17:31:45.432Z no switchport
3692026-03-30T17:31:45.432Z ipv6 enable
3702026-03-30T17:31:45.432Z
3712026-03-30T17:31:45.432Z router bgp 45
3722026-03-30T17:31:45.432Z router-id 1.2.3.1
3732026-03-30T17:31:45.433Z no bgp default ipv4-unicast
3742026-03-30T17:31:45.433Z timers bgp 2 6
3752026-03-30T17:31:45.433Z neighbor ebgp peer group
3762026-03-30T17:31:45.433Z neighbor ebgp remote-as 33
3772026-03-30T17:31:45.433Z neighbor interface Et1 peer-group ebgp
3782026-03-30T17:31:45.433Z address-family ipv4
3792026-03-30T17:31:45.433Z neighbor ebgp activate
3802026-03-30T17:31:45.433Z neighbor ebgp next-hop address-family ipv6 originate
3812026-03-30T17:31:45.433Z network 1.2.3.0/24
3822026-03-30T17:31:45.433Z exit
3832026-03-30T17:31:45.433Z address-family ipv6
3842026-03-30T17:31:45.433Z neighbor ebgp activate
3852026-03-30T17:31:45.433Z neighbor ebgp next-hop address-family ipv6 originate
3862026-03-30T17:31:45.433Z network fd99::/64
3872026-03-30T17:31:45.433Z exit
3882026-03-30T17:31:45.433Z exit
3892026-03-30T17:31:45.433Z '`
3902026-03-30T17:32:02.706ZMar 30 17:32:02.692 INFO cr1: enabling frr daemon bgpd
3912026-03-30T17:32:02.706ZMar 30 17:32:02.693 DEBG [sc] cr1: starting
3922026-03-30T17:32:02.706ZMar 30 17:32:02.693 DEBG [sc] cr1: connecting to [::1]:50234
3932026-03-30T17:32:02.731ZMar 30 17:32:02.693 DEBG [sc] cr1 waiting for prompt
3942026-03-30T17:32:02.731ZMar 30 17:32:02.703 DEBG [sc] cr1: logging in
3952026-03-30T17:32:02.871ZMar 30 17:32:02.857 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-03-30T17:32:04.950ZMar 30 17:32:04.935 DEBG [sc] cr1: starting
3972026-03-30T17:32:04.950ZMar 30 17:32:04.935 DEBG [sc] cr1: connecting to [::1]:50234
3982026-03-30T17:32:04.975ZMar 30 17:32:04.936 DEBG [sc] cr1 waiting for prompt
3992026-03-30T17:32:04.975ZMar 30 17:32:04.946 DEBG [sc] cr1: logging in
4002026-03-30T17:32:05.093ZMar 30 17:32:05.080 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-03-30T17:32:12.703ZMar 30 17:32:12.688 INFO cr1: executing frr script
4022026-03-30T17:32:12.703Z configure
4032026-03-30T17:32:12.703Z ip forwarding
4042026-03-30T17:32:12.703Z ipv6 forwarding
4052026-03-30T17:32:12.703Z ip route 1.2.3.0/24 null0
4062026-03-30T17:32:12.703Z ipv6 route fd99::/64 null0
4072026-03-30T17:32:12.703Z route-map PERMIT-ALL permit 10
4082026-03-30T17:32:12.703Z router bgp 44
4092026-03-30T17:32:12.703Z timers bgp 2 6
4102026-03-30T17:32:12.703Z neighbor enp0s8 interface remote-as external
4112026-03-30T17:32:12.703Z neighbor enp0s8 timers connect 1
4122026-03-30T17:32:12.703Z address-family ipv4 unicast
4132026-03-30T17:32:12.703Z network 1.2.3.0/24
4142026-03-30T17:32:12.703Z neighbor enp0s8 activate
4152026-03-30T17:32:12.703Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-03-30T17:32:12.703Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-03-30T17:32:12.703Z exit-address-family
4182026-03-30T17:32:12.703Z address-family ipv6 unicast
4192026-03-30T17:32:12.703Z network fd99::/64
4202026-03-30T17:32:12.703Z neighbor enp0s8 activate
4212026-03-30T17:32:12.704Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-03-30T17:32:12.704Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-03-30T17:32:12.704Z exit-address-family
4242026-03-30T17:32:12.728Z exit
4252026-03-30T17:32:12.728Z
4262026-03-30T17:32:12.728ZMar 30 17:32:12.689 DEBG [sc] cr1: starting
4272026-03-30T17:32:12.728ZMar 30 17:32:12.689 DEBG [sc] cr1: connecting to [::1]:50234
4282026-03-30T17:32:12.728ZMar 30 17:32:12.689 DEBG [sc] cr1 waiting for prompt
4292026-03-30T17:32:12.728ZMar 30 17:32:12.700 DEBG [sc] cr1: logging in
4302026-03-30T17:32:12.869ZMar 30 17:32:12.855 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-03-30T17:33:08.663ZMar 30 17:33:08.650 DEBG [sc] ox: starting
4322026-03-30T17:33:08.663ZMar 30 17:33:08.650 DEBG [sc] ox: connecting to [::1]:48156
4332026-03-30T17:33:08.687ZMar 30 17:33:08.650 DEBG [sc] ox waiting for prompt
4342026-03-30T17:33:08.687ZMar 30 17:33:08.661 DEBG [sc] ox: logging in
4352026-03-30T17:33:08.752ZMar 30 17:33:08.738 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-03-30T17:33:09.018ZMar 30 17:33:09.004 DEBG [sc] ox: starting
4372026-03-30T17:33:09.018ZMar 30 17:33:09.004 DEBG [sc] ox: connecting to [::1]:48156
4382026-03-30T17:33:09.043ZMar 30 17:33:09.005 DEBG [sc] ox waiting for prompt
4392026-03-30T17:33:09.043ZMar 30 17:33:09.015 DEBG [sc] ox: logging in
4402026-03-30T17:33:09.107ZMar 30 17:33:09.093 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-03-30T17:33:10.131ZMar 30 17:33:10.116 DEBG [sc] ox: starting
4422026-03-30T17:33:10.131ZMar 30 17:33:10.116 DEBG [sc] ox: connecting to [::1]:48156
4432026-03-30T17:33:10.156ZMar 30 17:33:10.116 DEBG [sc] ox waiting for prompt
4442026-03-30T17:33:10.156ZMar 30 17:33:10.116 DEBG [sc] ox: logging in
4452026-03-30T17:33:10.207ZMar 30 17:33:10.193 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4462026-03-30T17:33:10.263ZMar 30 17:33:10.249 DEBG [sc] ox: starting
4472026-03-30T17:33:10.263ZMar 30 17:33:10.249 DEBG [sc] ox: connecting to [::1]:48156
4482026-03-30T17:33:10.287ZMar 30 17:33:10.250 DEBG [sc] ox waiting for prompt
4492026-03-30T17:33:10.287ZMar 30 17:33:10.259 DEBG [sc] ox: logging in
4502026-03-30T17:33:10.351ZMar 30 17:33:10.337 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4512026-03-30T17:33:10.407ZMar 30 17:33:10.393 DEBG [sc] ox: starting
4522026-03-30T17:33:10.407ZMar 30 17:33:10.393 DEBG [sc] ox: connecting to [::1]:48156
4532026-03-30T17:33:10.431ZMar 30 17:33:10.393 DEBG [sc] ox waiting for prompt
4542026-03-30T17:33:10.431ZMar 30 17:33:10.404 DEBG [sc] ox: logging in
4552026-03-30T17:33:10.496ZMar 30 17:33:10.481 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4562026-03-30T17:33:10.551ZMar 30 17:33:10.537 DEBG [sc] ox: starting
4572026-03-30T17:33:10.551ZMar 30 17:33:10.537 DEBG [sc] ox: connecting to [::1]:48156
4582026-03-30T17:33:10.575ZMar 30 17:33:10.538 DEBG [sc] ox waiting for prompt
4592026-03-30T17:33:10.575ZMar 30 17:33:10.548 DEBG [sc] ox: logging in
4602026-03-30T17:33:10.640ZMar 30 17:33:10.626 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-03-30T17:33:10.696ZMar 30 17:33:10.682 DEBG [sc] ox: starting
4622026-03-30T17:33:10.696ZMar 30 17:33:10.682 DEBG [sc] ox: connecting to [::1]:48156
4632026-03-30T17:33:10.740ZMar 30 17:33:10.683 DEBG [sc] ox waiting for prompt
4642026-03-30T17:33:10.740ZMar 30 17:33:10.692 DEBG [sc] ox: logging in
4652026-03-30T17:33:10.783ZMar 30 17:33:10.770 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-03-30T17:33:10.849ZMar 30 17:33:10.835 DEBG [sc] ox: starting
4672026-03-30T17:33:10.849ZMar 30 17:33:10.835 DEBG [sc] ox: connecting to [::1]:48156
4682026-03-30T17:33:10.873ZMar 30 17:33:10.836 DEBG [sc] ox waiting for prompt
4692026-03-30T17:33:10.873ZMar 30 17:33:10.847 DEBG [sc] ox: logging in
4702026-03-30T17:33:10.949ZMar 30 17:33:10.936 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-03-30T17:33:11.015ZMar 30 17:33:11.001 DEBG [sc] ox: starting
4722026-03-30T17:33:11.015ZMar 30 17:33:11.001 DEBG [sc] ox: connecting to [::1]:48156
4732026-03-30T17:33:11.040ZMar 30 17:33:11.002 DEBG [sc] ox waiting for prompt
4742026-03-30T17:33:11.040ZMar 30 17:33:11.012 DEBG [sc] ox: logging in
4752026-03-30T17:33:11.114ZMar 30 17:33:11.100 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-03-30T17:33:11.181ZMar 30 17:33:11.167 DEBG [sc] ox: starting
4772026-03-30T17:33:11.181ZMar 30 17:33:11.167 DEBG [sc] ox: connecting to [::1]:48156
4782026-03-30T17:33:11.206ZMar 30 17:33:11.168 DEBG [sc] ox waiting for prompt
4792026-03-30T17:33:11.206ZMar 30 17:33:11.178 DEBG [sc] ox: logging in
4802026-03-30T17:33:11.269ZMar 30 17:33:11.255 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-03-30T17:33:11.343ZMar 30 17:33:11.329 INFO adding BGP router to mgd
4822026-03-30T17:33:13.363ZMar 30 17:33:13.348 INFO cr1: executing frr script show ip bgp json
4832026-03-30T17:33:13.363ZMar 30 17:33:13.348 DEBG [sc] cr1: starting
4842026-03-30T17:33:13.363ZMar 30 17:33:13.348 DEBG [sc] cr1: connecting to [::1]:50234
4852026-03-30T17:33:13.387ZMar 30 17:33:13.349 DEBG [sc] cr1 waiting for prompt
4862026-03-30T17:33:13.387ZMar 30 17:33:13.359 DEBG [sc] cr1: logging in
4872026-03-30T17:33:13.687ZMar 30 17:33:13.673 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-03-30T17:33:15.811ZMar 30 17:33:15.798 INFO cr1: executing frr script show bgp json
4892026-03-30T17:33:15.836ZMar 30 17:33:15.798 DEBG [sc] cr1: starting
4902026-03-30T17:33:15.836ZMar 30 17:33:15.798 DEBG [sc] cr1: connecting to [::1]:50234
4912026-03-30T17:33:15.836ZMar 30 17:33:15.798 DEBG [sc] cr1 waiting for prompt
4922026-03-30T17:33:15.836ZMar 30 17:33:15.809 DEBG [sc] cr1: logging in
4932026-03-30T17:33:15.958ZMar 30 17:33:15.940 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-03-30T17:33:18.092ZMar 30 17:33:18.078 INFO cr2: executing eos script show ip bgp | json
4952026-03-30T17:33:18.092ZMar 30 17:33:18.078 DEBG [sc] cr2: starting
4962026-03-30T17:33:18.092ZMar 30 17:33:18.078 DEBG [sc] cr2: connecting to [::1]:58859
4972026-03-30T17:33:18.116ZMar 30 17:33:18.079 DEBG [sc] cr2 waiting for prompt
4982026-03-30T17:33:18.116ZMar 30 17:33:18.090 DEBG [sc] cr2: logging in
4992026-03-30T17:33:18.440ZMar 30 17:33:18.426 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-03-30T17:33:20.796ZMar 30 17:33:20.782 INFO cr2: executing eos script show ipv6 bgp | json
5012026-03-30T17:33:20.796ZMar 30 17:33:20.782 DEBG [sc] cr2: starting
5022026-03-30T17:33:20.796ZMar 30 17:33:20.782 DEBG [sc] cr2: connecting to [::1]:58859
5032026-03-30T17:33:20.820ZMar 30 17:33:20.783 DEBG [sc] cr2 waiting for prompt
5042026-03-30T17:33:20.820ZMar 30 17:33:20.793 DEBG [sc] cr2: logging in
5052026-03-30T17:33:20.929ZMar 30 17:33:20.915 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-03-30T17:33:23.314ZMar 30 17:33:23.298 INFO trio bgp unnumbered test passed 🎉
5072026-03-30T17:33:23.314ZMar 30 17:33:23.299 INFO destroying runner for deployment mgtriou
5082026-03-30T17:33:23.314ZMar 30 17:33:23.299 INFO destroying deployment mgtriou
5092026-03-30T17:33:23.314ZMar 30 17:33:23.299 INFO destroying nodes
5102026-03-30T17:33:23.415ZMar 30 17:33:23.401 INFO destroying links
5112026-03-30T17:33:23.415ZMar 30 17:33:23.401 INFO destroying link mgtriou_ox_sn_vnic0
5122026-03-30T17:33:23.441ZMar 30 17:33:23.404 INFO destroying link mgtriou_ox_sn_sim0
5132026-03-30T17:33:23.441ZMar 30 17:33:23.405 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-03-30T17:33:24.422ZMar 30 17:33:24.408 INFO destroying link mgtriou_cr1_vn_sim0
5152026-03-30T17:33:24.448ZMar 30 17:33:24.409 INFO destroying link mgtriou_ox_sn_vnic1
5162026-03-30T17:33:24.448ZMar 30 17:33:24.411 INFO destroying link mgtriou_ox_sn_sim1
5172026-03-30T17:33:24.448ZMar 30 17:33:24.412 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-03-30T17:33:24.448ZMar 30 17:33:24.413 INFO destroying link mgtriou_cr2_vn_sim0
5192026-03-30T17:33:24.448ZMar 30 17:33:24.415 INFO destroying external links
5202026-03-30T17:33:24.448ZMar 30 17:33:24.415 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-03-30T17:33:24.448ZMar 30 17:33:24.416 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-03-30T17:33:24.448ZMar 30 17:33:24.418 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-03-30T17:33:24.448ZMar 30 17:33:24.419 INFO destroying images
5242026-03-30T17:33:24.917ZMar 30 17:33:24.895 INFO destroying workspace at .falcon
5252026-03-30T17:33:24.946Zprocess exited: duration 487360 ms, exit code 0
 
5262026-03-30T17:33:25.005Zfound 0 output files