01KMZY623PD1Q46QC5TDS6AD4Z: falcon

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

Buildomat Job: 01KMZY6TD4GBRDS0XC7HEPDF6V

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-03-30T17:56:02.092Zjob dependencies complete; ready to run (waiting for 0 s)
22026-03-30T17:59:20.443Zjob assigned to worker 01KMZY6VQRHH21TW134K82TBQY [factory edgar, gimlet/BRM42220010/673] (queued for 3 m 18 s)
32026-03-30T17:59:20.740Zdownloading input: /input/build/work/debug/ddmadm
42026-03-30T17:59:45.090Zdownloaded input: /input/build/work/debug/ddmadm
52026-03-30T17:59:45.090Zdownloading input: /input/build/work/debug/ddmd
62026-03-30T18:00:11.632Zdownloaded input: /input/build/work/debug/ddmd
72026-03-30T18:00:11.633Zdownloading input: /input/build/work/debug/mgadm
82026-03-30T18:00:38.013Zdownloaded input: /input/build/work/debug/mgadm
92026-03-30T18:00:38.038Zdownloading input: /input/build-interop/work/testbed.tar.gz
102026-03-30T18:00:46.627Zdownloaded input: /input/build-interop/work/testbed.tar.gz
112026-03-30T18:00:46.651Zdownloading input: /input/build-interop/work/dhcp-server
122026-03-30T18:00:48.987Zdownloaded input: /input/build-interop/work/dhcp-server
132026-03-30T18:00:48.987Zdownloading input: /input/build/work/debug/mgd
142026-03-30T18:01:19.522Zdownloaded input: /input/build/work/debug/mgd
152026-03-30T18:01:19.547Zdownloading input: /input/build/work/release/ddmadm
162026-03-30T18:01:21.757Zdownloaded input: /input/build/work/release/ddmadm
172026-03-30T18:01:21.757Zdownloading input: /input/build/work/release/ddmd
182026-03-30T18:01:24.558Zdownloaded input: /input/build/work/release/ddmd
192026-03-30T18:01:24.558Zdownloading input: /input/build/work/release/falcon-lab
202026-03-30T18:01:26.188Zdownloaded input: /input/build/work/release/falcon-lab
212026-03-30T18:01:26.188Zdownloading input: /input/build/work/release/mgadm
222026-03-30T18:01:28.913Zdownloaded input: /input/build/work/release/mgadm
232026-03-30T18:01:28.913Zdownloading input: /input/build/work/release/mgd
242026-03-30T18:01:32.382Zdownloaded input: /input/build/work/release/mgd
 
252026-03-30T18:01:32.382Zstarting task 0: "setup"
262026-03-30T18:01:32.406Z++ uname -s
272026-03-30T18:01:32.406Z+ kern=SunOS
282026-03-30T18:01:32.406Z+ build_user=build
292026-03-30T18:01:32.406Z+ build_uid=12345
302026-03-30T18:01:32.406Z+ work_dir=/work
312026-03-30T18:01:32.406Z+ input_dir=/input
322026-03-30T18:01:32.406Z+ [[ 0 == 12345 ]]
332026-03-30T18:01:32.406Z+ case "$kern" in
342026-03-30T18:01:32.406Z+ groupadd -g 12345 build
352026-03-30T18:01:32.431Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-03-30T18:01:34.420Z+ zfs create -o mountpoint=/work rpool/work
372026-03-30T18:01:35.493Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-03-30T18:01:35.518Z+ home_fs=
392026-03-30T18:01:35.518Z+ [[ '' == autofs ]]
402026-03-30T18:01:35.519Z+ mkdir -p /home/build
412026-03-30T18:01:35.519Z+ chown build:build /home/build /work
422026-03-30T18:01:36.506Z+ chmod 0700 /home/build /work
432026-03-30T18:01:36.530Zprocess exited: duration 4128 ms, exit code 0
 
442026-03-30T18:01:36.579Zstarting task 1: "authentication"
452026-03-30T18:01:36.672Zprocess exited: duration 93 ms, exit code 0
 
462026-03-30T18:01:36.720Zstarting task 2: "build"
472026-03-30T18:01:36.745Z+ set -e
482026-03-30T18:01:36.745Z+ banner zpool
492026-03-30T18:01:36.745Z
502026-03-30T18:01:36.745Z ###### ##### #### #### #
512026-03-30T18:01:36.745Z # # # # # # # #
522026-03-30T18:01:36.745Z # # # # # # # #
532026-03-30T18:01:36.745Z # ##### # # # # #
542026-03-30T18:01:36.745Z # # # # # # #
552026-03-30T18:01:36.745Z ###### # #### #### ######
562026-03-30T18:01:36.745Z
572026-03-30T18:01:36.745Z++ pfexec diskinfo -pH
582026-03-30T18:01:36.769Z++ sort -k8 -n -r
592026-03-30T18:01:36.769Z++ head -1
602026-03-30T18:01:36.769Z++ awk '{print $2}'
612026-03-30T18:01:36.795Z+ DISK=c9t0014EE81000BC3B1d0
622026-03-30T18:01:36.795Z+ export DISK
632026-03-30T18:01:36.795Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
642026-03-30T18:01:36.862Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-03-30T18:01:36.888Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-03-30T18:01:37.035Z+ [[ true =~ true ]]
672026-03-30T18:01:37.035Z+ pfexec zpool trim cpool
682026-03-30T18:01:37.060Z++ zpool status -t cpool
692026-03-30T18:01:37.060Z+ [[ ! pool: cpool
702026-03-30T18:01:37.060Z state: ONLINE
712026-03-30T18:01:37.060Z scan: none requested
722026-03-30T18:01:37.060Zconfig:
732026-03-30T18:01:37.060Z
742026-03-30T18:01:37.060Z NAME STATE READ WRITE CKSUM
752026-03-30T18:01:37.060Z cpool ONLINE 0 0 0
762026-03-30T18:01:37.060Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at March 30, 2026 at 06:01:37 PM UTC)
772026-03-30T18:01:37.060Z
782026-03-30T18:01:37.060Zerrors: No known data errors =~ 100% ]]
792026-03-30T18:01:37.060Z+ sleep 10
802026-03-30T18:01:47.068Z++ zpool status -t cpool
812026-03-30T18:01:47.092Z+ [[ ! pool: cpool
822026-03-30T18:01:47.092Z state: ONLINE
832026-03-30T18:01:47.092Z scan: none requested
842026-03-30T18:01:47.092Zconfig:
852026-03-30T18:01:47.092Z
862026-03-30T18:01:47.092Z NAME STATE READ WRITE CKSUM
872026-03-30T18:01:47.092Z cpool ONLINE 0 0 0
882026-03-30T18:01:47.092Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (70% trimmed, started at March 30, 2026 at 06:01:37 PM UTC)
892026-03-30T18:01:47.092Z
902026-03-30T18:01:47.092Zerrors: No known data errors =~ 100% ]]
912026-03-30T18:01:47.092Z+ sleep 10
922026-03-30T18:01:57.082Z++ zpool status -t cpool
932026-03-30T18:01:57.106Z+ [[ ! pool: cpool
942026-03-30T18:01:57.106Z state: ONLINE
952026-03-30T18:01:57.106Z scan: none requested
962026-03-30T18:01:57.106Zconfig:
972026-03-30T18:01:57.106Z
982026-03-30T18:01:57.106Z NAME STATE READ WRITE CKSUM
992026-03-30T18:01:57.106Z cpool ONLINE 0 0 0
1002026-03-30T18:01:57.106Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at March 30, 2026 at 06:01:51 PM UTC)
1012026-03-30T18:01:57.106Z
1022026-03-30T18:01:57.106Zerrors: No known data errors =~ 100% ]]
1032026-03-30T18:01:57.106Z+ pfexec chown 12345 /ci
1042026-03-30T18:01:57.106Z+ cd /ci
1052026-03-30T18:01:57.106Z+ export FALCON_DATASET=cpool/falcon
1062026-03-30T18:01:57.106Z+ FALCON_DATASET=cpool/falcon
1072026-03-30T18:01:57.106Z+ banner setup
1082026-03-30T18:01:57.106Z
1092026-03-30T18:01:57.106Z #### ###### ##### # # #####
1102026-03-30T18:01:57.106Z # # # # # # #
1112026-03-30T18:01:57.106Z #### ##### # # # # #
1122026-03-30T18:01:57.106Z # # # # # #####
1132026-03-30T18:01:57.106Z # # # # # # #
1142026-03-30T18:01:57.107Z #### ###### # #### #
1152026-03-30T18:01:57.107Z
1162026-03-30T18:01:57.107Z+ cp /input/build-interop/work/dhcp-server .
1172026-03-30T18:01:57.303Z+ cp /input/build/work/release/falcon-lab .
1182026-03-30T18:01:57.332Z+ cp /input/build/work/release/mgd .
1192026-03-30T18:01:57.395Z+ cp /input/build/work/release/ddmd .
1202026-03-30T18:01:57.450Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-03-30T18:01:57.474Z+ mkdir -p cargo-bay
1222026-03-30T18:01:57.474Z+ mv mgd cargo-bay/
1232026-03-30T18:01:57.474Z+ mv ddmd cargo-bay/
1242026-03-30T18:01:57.474Z+ export EXT_INTERFACE=igb0
1252026-03-30T18:01:57.474Z+ EXT_INTERFACE=igb0
1262026-03-30T18:01:57.474Z++ bmat address ls -f extra -Ho first
1272026-03-30T18:01:57.499Z+ first=10.151.6.164
1282026-03-30T18:01:57.499Z++ bmat address ls -f extra -Ho last
1292026-03-30T18:01:57.523Z+ last=10.151.6.227
1302026-03-30T18:01:57.523Z++ bmat address ls -f extra -Ho gateway
1312026-03-30T18:01:57.548Z+ gw=10.151.6.1
1322026-03-30T18:01:57.548Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-03-30T18:01:57.548Z++ sed 's#/.*##g'
1342026-03-30T18:01:57.548Z+ server=10.151.6.100
1352026-03-30T18:01:57.572Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1362026-03-30T18:01:57.572Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-03-30T18:01:57.572Z+ error=
1382026-03-30T18:01:57.572Z+ RUST_LOG=debug
1392026-03-30T18:01:57.572Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-03-30T18:01:57.596ZMar 30 18:01:57.566 DEBG using default route interface igb0
1412026-03-30T18:01:57.596ZMar 30 18:01:57.567 DEBG using default route interface igb0
1422026-03-30T18:01:57.596ZMar 30 18:01:57.567 DEBG using default route interface igb0
1432026-03-30T18:01:57.596ZMar 30 18:01:57.567 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-03-30T18:01:57.596ZMar 30 18:01:57.567 INFO starting preflight for deployment mgtriou
1452026-03-30T18:01:57.596ZMar 30 18:01:57.567 INFO propolis-server binary not found
1462026-03-30T18:01:57.596ZMar 30 18:01:57.567 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-03-30T18:02:01.410ZMar 30 18:02:01.395 INFO ovmf fd not found
1482026-03-30T18:02:01.410ZMar 30 18:02:01.395 INFO downloading ovmf
1492026-03-30T18:02:01.777ZMar 30 18:02:01.762 INFO base image for helios-2.9 does not exist, attempting to install
1502026-03-30T18:02:01.777ZMar 30 18:02:01.762 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-03-30T18:02:32.162ZMar 30 18:02:32.147 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-03-30T18:03:05.886ZMar 30 18:03:05.860 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-03-30T18:03:05.934ZMar 30 18:03:05.913 INFO copying image data to zvol
1542026-03-30T18:03:16.430ZMar 30 18:03:16.359 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-03-30T18:03:19.709ZMar 30 18:03:19.685 INFO base image for debian-13.2 does not exist, attempting to install
1562026-03-30T18:03:19.709ZMar 30 18:03:19.685 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-03-30T18:03:39.959ZMar 30 18:03:39.932 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-03-30T18:04:01.547ZMar 30 18:04:00.856 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-03-30T18:04:01.547ZMar 30 18:04:00.883 INFO copying image data to zvol
1602026-03-30T18:04:07.064ZMar 30 18:04:07.048 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-03-30T18:04:10.978ZMar 30 18:04:10.962 INFO base image for eos-4.35 does not exist, attempting to install
1622026-03-30T18:04:10.978ZMar 30 18:04:10.962 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-03-30T18:04:54.949ZMar 30 18:04:54.934 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-03-30T18:06:18.238ZMar 30 18:06:18.218 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-03-30T18:06:18.265ZMar 30 18:06:18.245 INFO copying image data to zvol
1662026-03-30T18:07:17.098ZMar 30 18:07:17.084 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-03-30T18:07:20.222ZMar 30 18:07:20.208 INFO creating links
1682026-03-30T18:07:20.222ZMar 30 18:07:20.208 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-03-30T18:07:20.258ZMar 30 18:07:20.208 DEBG destroying link mgtriou_ox_sn_sim0
1702026-03-30T18:07:20.258ZMar 30 18:07:20.208 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-03-30T18:07:20.258ZMar 30 18:07:20.210 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-03-30T18:07:20.258ZMar 30 18:07:20.218 DEBG link pair created
1732026-03-30T18:07:20.259ZMar 30 18:07:20.218 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-03-30T18:07:20.259ZMar 30 18:07:20.218 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-03-30T18:07:20.259ZMar 30 18:07:20.218 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-03-30T18:07:20.259ZMar 30 18:07:20.219 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-03-30T18:07:20.259ZMar 30 18:07:20.226 DEBG link pair created
1782026-03-30T18:07:20.259ZMar 30 18:07:20.226 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-03-30T18:07:20.259ZMar 30 18:07:20.226 DEBG destroying link mgtriou_ox_sn_sim1
1802026-03-30T18:07:20.259ZMar 30 18:07:20.226 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-03-30T18:07:20.260ZMar 30 18:07:20.228 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-03-30T18:07:20.260ZMar 30 18:07:20.234 DEBG link pair created
1832026-03-30T18:07:20.260ZMar 30 18:07:20.234 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-03-30T18:07:20.260ZMar 30 18:07:20.234 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-03-30T18:07:20.260ZMar 30 18:07:20.234 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-03-30T18:07:20.260ZMar 30 18:07:20.235 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-03-30T18:07:20.295ZMar 30 18:07:20.242 DEBG link pair created
1882026-03-30T18:07:20.295ZMar 30 18:07:20.242 INFO creating external links
1892026-03-30T18:07:20.295ZMar 30 18:07:20.242 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-03-30T18:07:20.295ZMar 30 18:07:20.242 INFO creating external link mgtriou_ox_vn_vnic2
1912026-03-30T18:07:20.295ZMar 30 18:07:20.244 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-03-30T18:07:20.295ZMar 30 18:07:20.244 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-03-30T18:07:20.295ZMar 30 18:07:20.244 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-03-30T18:07:20.295ZMar 30 18:07:20.245 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-03-30T18:07:20.296ZMar 30 18:07:20.245 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-03-30T18:07:20.296ZMar 30 18:07:20.245 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-03-30T18:07:20.296ZMar 30 18:07:20.246 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-03-30T18:07:20.296ZMar 30 18:07:20.246 INFO creating nodes
1992026-03-30T18:07:20.296ZMar 30 18:07:20.246 INFO ox: launching node
2002026-03-30T18:07:20.296ZMar 30 18:07:20.251 INFO cr1: launching node
2012026-03-30T18:07:20.296ZMar 30 18:07:20.251 INFO cr2: launching node
2022026-03-30T18:07:20.296ZMar 30 18:07:20.265 INFO launched instance ox with pid 852 on port 33884
2032026-03-30T18:07:20.296ZMar 30 18:07:20.265 INFO ox: instance ensure
2042026-03-30T18:07:20.296ZMar 30 18:07:20.266 INFO launched instance cr1 with pid 853 on port 45504
2052026-03-30T18:07:20.297ZMar 30 18:07:20.266 INFO cr1: instance ensure
2062026-03-30T18:07:20.297ZMar 30 18:07:20.266 INFO launched instance cr2 with pid 854 on port 63386
2072026-03-30T18:07:20.297ZMar 30 18:07:20.266 INFO cr2: instance ensure
2082026-03-30T18:07:22.643ZMar 30 18:07:22.629 INFO ox: instance run
2092026-03-30T18:07:22.667ZMar 30 18:07:22.630 DEBG [sc] ox: starting
2102026-03-30T18:07:22.667ZMar 30 18:07:22.630 DEBG [sc] ox: connecting to [::1]:33884
2112026-03-30T18:07:22.667ZMar 30 18:07:22.631 DEBG [sc] ox waiting for prompt
2122026-03-30T18:07:22.709ZMar 30 18:07:22.695 INFO cr2: instance run
2132026-03-30T18:07:22.733ZMar 30 18:07:22.696 DEBG [sc] cr2: starting
2142026-03-30T18:07:22.733ZMar 30 18:07:22.696 DEBG [sc] cr2: connecting to [::1]:63386
2152026-03-30T18:07:22.733ZMar 30 18:07:22.696 DEBG [sc] cr2 waiting for prompt
2162026-03-30T18:07:22.733ZMar 30 18:07:22.714 INFO cr1: instance run
2172026-03-30T18:07:22.733ZMar 30 18:07:22.714 DEBG [sc] cr1: starting
2182026-03-30T18:07:22.733ZMar 30 18:07:22.714 DEBG [sc] cr1: connecting to [::1]:45504
2192026-03-30T18:07:22.733ZMar 30 18:07:22.715 DEBG [sc] cr1 waiting for prompt
2202026-03-30T18:07:39.781ZMar 30 18:07:39.768 DEBG [sc] cr1: logging in
2212026-03-30T18:07:40.181ZMar 30 18:07:40.167 INFO cr1: mounting /opt/cargo-bay
2222026-03-30T18:07:40.181ZMar 30 18:07:40.167 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-03-30T18:07:40.206ZMar 30 18:07:40.189 DEBG [sc] cr1: executing command `cd`
2242026-03-30T18:07:40.230ZMar 30 18:07:40.200 INFO cr1: finished mounting /opt/cargo-bay
2252026-03-30T18:07:40.230ZMar 30 18:07:40.200 DEBG [sc] cr1: executing command `hostname cr1`
2262026-03-30T18:07:40.231ZMar 30 18:07:40.210 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-03-30T18:07:40.255ZMar 30 18:07:40.221 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-03-30T18:07:40.255ZMar 30 18:07:40.232 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-03-30T18:07:40.280ZMar 30 18:07:40.244 INFO cr1: logging out
2302026-03-30T18:07:42.313ZMar 30 18:07:42.299 INFO cr1: logged out
2312026-03-30T18:07:42.998ZMar 30 18:07:42.984 DEBG [sc] ox: logging in
2322026-03-30T18:07:43.182ZMar 30 18:07:43.168 INFO ox: mounting /opt/cargo-bay
2332026-03-30T18:07:43.182ZMar 30 18:07:43.168 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-03-30T18:07:44.184ZMar 30 18:07:44.170 DEBG [sc] cr2: logging in
2352026-03-30T18:07:44.213ZMar 30 18:07:44.199 DEBG [sc] ox: executing command `cd`
2362026-03-30T18:07:44.238ZMar 30 18:07:44.210 INFO ox: finished mounting /opt/cargo-bay
2372026-03-30T18:07:44.238ZMar 30 18:07:44.210 DEBG [sc] ox: executing command `hostname ox`
2382026-03-30T18:07:44.238ZMar 30 18:07:44.221 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2392026-03-30T18:07:44.262ZMar 30 18:07:44.232 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2402026-03-30T18:07:44.262ZMar 30 18:07:44.242 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2412026-03-30T18:07:44.287ZMar 30 18:07:44.254 INFO ox: logging out
2422026-03-30T18:07:44.346ZMar 30 18:07:44.331 INFO ox: logged out
2432026-03-30T18:07:44.602ZMar 30 18:07:44.588 INFO cr2: mounting /opt/cargo-bay
2442026-03-30T18:07:44.602ZMar 30 18:07:44.588 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-03-30T18:07:44.626ZMar 30 18:07:44.610 DEBG [sc] cr2: executing command `cd`
2462026-03-30T18:07:44.650ZMar 30 18:07:44.620 INFO cr2: finished mounting /opt/cargo-bay
2472026-03-30T18:07:44.650ZMar 30 18:07:44.620 DEBG [sc] cr2: executing command `hostname cr2`
2482026-03-30T18:07:44.650ZMar 30 18:07:44.631 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-03-30T18:07:44.674ZMar 30 18:07:44.643 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-03-30T18:07:44.674ZMar 30 18:07:44.654 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-03-30T18:07:44.701ZMar 30 18:07:44.665 INFO cr2: logging out
2522026-03-30T18:07:47.539ZMar 30 18:07:47.525 INFO cr2: logged out
2532026-03-30T18:07:47.564ZMar 30 18:07:47.525 DEBG [sc] ox: starting
2542026-03-30T18:07:47.564ZMar 30 18:07:47.525 DEBG [sc] ox: connecting to [::1]:33884
2552026-03-30T18:07:47.564ZMar 30 18:07:47.526 DEBG [sc] ox waiting for prompt
2562026-03-30T18:07:47.564ZMar 30 18:07:47.537 DEBG [sc] ox: logging in
2572026-03-30T18:07:47.639ZMar 30 18:07:47.625 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-03-30T18:07:52.624ZMar 30 18:07:52.612 DEBG [sc] ox: starting
2592026-03-30T18:07:52.624ZMar 30 18:07:52.612 DEBG [sc] ox: connecting to [::1]:33884
2602026-03-30T18:07:52.649ZMar 30 18:07:52.612 DEBG [sc] ox waiting for prompt
2612026-03-30T18:07:52.649ZMar 30 18:07:52.623 DEBG [sc] ox: logging in
2622026-03-30T18:07:52.712ZMar 30 18:07:52.699 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-03-30T18:07:52.801ZMar 30 18:07:52.788 DEBG [sc] ox: starting
2642026-03-30T18:07:52.801ZMar 30 18:07:52.788 DEBG [sc] ox: connecting to [::1]:33884
2652026-03-30T18:07:52.826ZMar 30 18:07:52.788 DEBG [sc] ox waiting for prompt
2662026-03-30T18:07:52.826ZMar 30 18:07:52.799 DEBG [sc] ox: logging in
2672026-03-30T18:07:52.900ZMar 30 18:07:52.887 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-03-30T18:07:52.965ZMar 30 18:07:52.953 INFO cr1: installing frr
2692026-03-30T18:07:52.965ZMar 30 18:07:52.953 INFO waiting for ceos to initialize
2702026-03-30T18:07:52.965ZMar 30 18:07:52.953 INFO ox: setting up npuvm
2712026-03-30T18:07:52.989ZMar 30 18:07:52.953 DEBG [sc] cr1: starting
2722026-03-30T18:07:52.989ZMar 30 18:07:52.953 DEBG [sc] cr2: starting
2732026-03-30T18:07:52.989ZMar 30 18:07:52.953 DEBG [sc] cr1: connecting to [::1]:45504
2742026-03-30T18:07:52.989ZMar 30 18:07:52.953 DEBG [sc] cr2: connecting to [::1]:63386
2752026-03-30T18:07:52.990ZMar 30 18:07:52.953 DEBG [sc] ox: starting
2762026-03-30T18:07:52.990ZMar 30 18:07:52.953 DEBG [sc] ox: connecting to [::1]:33884
2772026-03-30T18:07:52.990ZMar 30 18:07:52.953 DEBG [sc] ox waiting for prompt
2782026-03-30T18:07:52.990ZMar 30 18:07:52.953 DEBG [sc] cr2 waiting for prompt
2792026-03-30T18:07:52.990ZMar 30 18:07:52.953 DEBG [sc] cr1 waiting for prompt
2802026-03-30T18:07:52.990ZMar 30 18:07:52.963 DEBG [sc] ox: logging in
2812026-03-30T18:07:52.990ZMar 30 18:07:52.965 DEBG [sc] cr2: logging in
2822026-03-30T18:07:52.990ZMar 30 18:07:52.965 DEBG [sc] cr1: logging in
2832026-03-30T18:07:53.065ZMar 30 18:07:53.053 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-03-30T18:07:53.098ZMar 30 18:07:53.086 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-03-30T18:07:53.348ZMar 30 18:07:53.334 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-03-30T18:07:55.323ZMar 30 18:07:55.308 INFO cr2: executing eos script show version
2872026-03-30T18:07:55.323ZMar 30 18:07:55.309 DEBG [sc] cr2: starting
2882026-03-30T18:07:55.323ZMar 30 18:07:55.309 DEBG [sc] cr2: connecting to [::1]:63386
2892026-03-30T18:07:55.350ZMar 30 18:07:55.309 DEBG [sc] cr2 waiting for prompt
2902026-03-30T18:07:55.350ZMar 30 18:07:55.320 DEBG [sc] cr2: logging in
2912026-03-30T18:07:55.475ZMar 30 18:07:55.463 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-03-30T18:07:58.708ZMar 30 18:07:58.691 DEBG [sc] ox: starting
2932026-03-30T18:07:58.708ZMar 30 18:07:58.691 DEBG [sc] ox: connecting to [::1]:33884
2942026-03-30T18:07:58.734ZMar 30 18:07:58.691 DEBG [sc] ox waiting for prompt
2952026-03-30T18:07:58.734ZMar 30 18:07:58.703 DEBG [sc] ox: logging in
2962026-03-30T18:07:58.807ZMar 30 18:07:58.790 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-03-30T18:07:58.869ZMar 30 18:07:58.856 DEBG [sc] ox: starting
2982026-03-30T18:07:58.869ZMar 30 18:07:58.857 DEBG [sc] ox: connecting to [::1]:33884
2992026-03-30T18:07:58.895ZMar 30 18:07:58.857 DEBG [sc] ox waiting for prompt
3002026-03-30T18:07:58.895ZMar 30 18:07:58.867 DEBG [sc] ox: logging in
3012026-03-30T18:07:58.957ZMar 30 18:07:58.944 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-03-30T18:08:05.139ZMar 30 18:08:05.127 DEBG [sc] cr2: starting
3032026-03-30T18:08:05.140ZMar 30 18:08:05.127 DEBG [sc] cr2: connecting to [::1]:63386
3042026-03-30T18:08:05.165ZMar 30 18:08:05.128 DEBG [sc] cr2 waiting for prompt
3052026-03-30T18:08:05.165ZMar 30 18:08:05.138 DEBG [sc] cr2: logging in
3062026-03-30T18:08:05.282ZMar 30 18:08:05.269 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-03-30T18:08:07.386ZMar 30 18:08:07.373 INFO cr2: executing eos script show version
3082026-03-30T18:08:07.386ZMar 30 18:08:07.374 DEBG [sc] cr2: starting
3092026-03-30T18:08:07.386ZMar 30 18:08:07.374 DEBG [sc] cr2: connecting to [::1]:63386
3102026-03-30T18:08:07.411ZMar 30 18:08:07.374 DEBG [sc] cr2 waiting for prompt
3112026-03-30T18:08:07.411ZMar 30 18:08:07.384 DEBG [sc] cr2: logging in
3122026-03-30T18:08:07.552ZMar 30 18:08:07.539 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-03-30T18:08:09.931ZMar 30 18:08:09.919 DEBG [sc] cr2: starting
3142026-03-30T18:08:09.931ZMar 30 18:08:09.919 DEBG [sc] cr2: connecting to [::1]:63386
3152026-03-30T18:08:09.955ZMar 30 18:08:09.919 DEBG [sc] cr2 waiting for prompt
3162026-03-30T18:08:09.955ZMar 30 18:08:09.931 DEBG [sc] cr2: logging in
3172026-03-30T18:08:10.089ZMar 30 18:08:10.073 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-03-30T18:08:12.230ZMar 30 18:08:12.219 INFO cr2: executing eos script show version
3192026-03-30T18:08:12.230ZMar 30 18:08:12.219 DEBG [sc] cr2: starting
3202026-03-30T18:08:12.230ZMar 30 18:08:12.219 DEBG [sc] cr2: connecting to [::1]:63386
3212026-03-30T18:08:12.255ZMar 30 18:08:12.219 DEBG [sc] cr2 waiting for prompt
3222026-03-30T18:08:12.255ZMar 30 18:08:12.230 DEBG [sc] cr2: logging in
3232026-03-30T18:08:12.397ZMar 30 18:08:12.385 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-03-30T18:08:14.849ZMar 30 18:08:14.825 INFO cr2: executing eos script
3252026-03-30T18:08:14.850Z enable
3262026-03-30T18:08:14.850Z configure
3272026-03-30T18:08:14.850Z ipv6 unicast-routing
3282026-03-30T18:08:14.850Z ip routing ipv6 interfaces
3292026-03-30T18:08:14.850Z ip routing
3302026-03-30T18:08:14.850Z ip route 1.2.3.0/24 null0
3312026-03-30T18:08:14.850Z ipv6 route fd99::/64 null0
3322026-03-30T18:08:14.850Z interface et1
3332026-03-30T18:08:14.850Z no switchport
3342026-03-30T18:08:14.850Z ipv6 enable
3352026-03-30T18:08:14.851Z
3362026-03-30T18:08:14.851Z router bgp 45
3372026-03-30T18:08:14.851Z router-id 1.2.3.1
3382026-03-30T18:08:14.851Z no bgp default ipv4-unicast
3392026-03-30T18:08:14.851Z timers bgp 2 6
3402026-03-30T18:08:14.851Z neighbor ebgp peer group
3412026-03-30T18:08:14.851Z neighbor ebgp remote-as 33
3422026-03-30T18:08:14.851Z neighbor interface Et1 peer-group ebgp
3432026-03-30T18:08:14.851Z address-family ipv4
3442026-03-30T18:08:14.851Z neighbor ebgp activate
3452026-03-30T18:08:14.851Z neighbor ebgp next-hop address-family ipv6 originate
3462026-03-30T18:08:14.851Z network 1.2.3.0/24
3472026-03-30T18:08:14.851Z exit
3482026-03-30T18:08:14.851Z address-family ipv6
3492026-03-30T18:08:14.851Z neighbor ebgp activate
3502026-03-30T18:08:14.852Z neighbor ebgp next-hop address-family ipv6 originate
3512026-03-30T18:08:14.852Z network fd99::/64
3522026-03-30T18:08:14.852Z exit
3532026-03-30T18:08:14.852Z exit
3542026-03-30T18:08:14.852Z
3552026-03-30T18:08:14.852ZMar 30 18:08:14.825 DEBG [sc] cr2: starting
3562026-03-30T18:08:14.852ZMar 30 18:08:14.825 DEBG [sc] cr2: connecting to [::1]:63386
3572026-03-30T18:08:14.887ZMar 30 18:08:14.825 DEBG [sc] cr2 waiting for prompt
3582026-03-30T18:08:14.887ZMar 30 18:08:14.836 DEBG [sc] cr2: logging in
3592026-03-30T18:08:14.986ZMar 30 18:08:14.969 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-03-30T18:08:14.987Z enable
3612026-03-30T18:08:14.987Z configure
3622026-03-30T18:08:14.987Z ipv6 unicast-routing
3632026-03-30T18:08:14.987Z ip routing ipv6 interfaces
3642026-03-30T18:08:14.987Z ip routing
3652026-03-30T18:08:14.987Z ip route 1.2.3.0/24 null0
3662026-03-30T18:08:14.987Z ipv6 route fd99::/64 null0
3672026-03-30T18:08:14.987Z interface et1
3682026-03-30T18:08:14.988Z no switchport
3692026-03-30T18:08:14.988Z ipv6 enable
3702026-03-30T18:08:14.988Z
3712026-03-30T18:08:14.988Z router bgp 45
3722026-03-30T18:08:14.988Z router-id 1.2.3.1
3732026-03-30T18:08:14.988Z no bgp default ipv4-unicast
3742026-03-30T18:08:14.988Z timers bgp 2 6
3752026-03-30T18:08:14.988Z neighbor ebgp peer group
3762026-03-30T18:08:14.988Z neighbor ebgp remote-as 33
3772026-03-30T18:08:14.988Z neighbor interface Et1 peer-group ebgp
3782026-03-30T18:08:14.989Z address-family ipv4
3792026-03-30T18:08:14.989Z neighbor ebgp activate
3802026-03-30T18:08:14.989Z neighbor ebgp next-hop address-family ipv6 originate
3812026-03-30T18:08:14.989Z network 1.2.3.0/24
3822026-03-30T18:08:14.989Z exit
3832026-03-30T18:08:14.989Z address-family ipv6
3842026-03-30T18:08:14.989Z neighbor ebgp activate
3852026-03-30T18:08:14.990Z neighbor ebgp next-hop address-family ipv6 originate
3862026-03-30T18:08:14.990Z network fd99::/64
3872026-03-30T18:08:14.990Z exit
3882026-03-30T18:08:14.990Z exit
3892026-03-30T18:08:14.990Z '`
3902026-03-30T18:08:30.732ZMar 30 18:08:30.711 INFO cr1: enabling frr daemon bgpd
3912026-03-30T18:08:30.732ZMar 30 18:08:30.711 DEBG [sc] cr1: starting
3922026-03-30T18:08:30.732ZMar 30 18:08:30.711 DEBG [sc] cr1: connecting to [::1]:45504
3932026-03-30T18:08:30.732ZMar 30 18:08:30.711 DEBG [sc] cr1 waiting for prompt
3942026-03-30T18:08:30.756ZMar 30 18:08:30.723 DEBG [sc] cr1: logging in
3952026-03-30T18:08:30.877ZMar 30 18:08:30.866 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-03-30T18:08:32.952ZMar 30 18:08:32.940 DEBG [sc] cr1: starting
3972026-03-30T18:08:32.952ZMar 30 18:08:32.940 DEBG [sc] cr1: connecting to [::1]:45504
3982026-03-30T18:08:32.952ZMar 30 18:08:32.940 DEBG [sc] cr1 waiting for prompt
3992026-03-30T18:08:32.976ZMar 30 18:08:32.950 DEBG [sc] cr1: logging in
4002026-03-30T18:08:33.094ZMar 30 18:08:33.082 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-03-30T18:08:40.678ZMar 30 18:08:40.666 INFO cr1: executing frr script
4022026-03-30T18:08:40.678Z configure
4032026-03-30T18:08:40.678Z ip forwarding
4042026-03-30T18:08:40.678Z ipv6 forwarding
4052026-03-30T18:08:40.679Z ip route 1.2.3.0/24 null0
4062026-03-30T18:08:40.679Z ipv6 route fd99::/64 null0
4072026-03-30T18:08:40.679Z route-map PERMIT-ALL permit 10
4082026-03-30T18:08:40.679Z router bgp 44
4092026-03-30T18:08:40.679Z timers bgp 2 6
4102026-03-30T18:08:40.679Z neighbor enp0s8 interface remote-as external
4112026-03-30T18:08:40.679Z neighbor enp0s8 timers connect 1
4122026-03-30T18:08:40.679Z address-family ipv4 unicast
4132026-03-30T18:08:40.679Z network 1.2.3.0/24
4142026-03-30T18:08:40.679Z neighbor enp0s8 activate
4152026-03-30T18:08:40.679Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-03-30T18:08:40.679Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-03-30T18:08:40.679Z exit-address-family
4182026-03-30T18:08:40.679Z address-family ipv6 unicast
4192026-03-30T18:08:40.679Z network fd99::/64
4202026-03-30T18:08:40.679Z neighbor enp0s8 activate
4212026-03-30T18:08:40.679Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-03-30T18:08:40.679Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-03-30T18:08:40.679Z exit-address-family
4242026-03-30T18:08:40.679Z exit
4252026-03-30T18:08:40.679Z
4262026-03-30T18:08:40.679ZMar 30 18:08:40.666 DEBG [sc] cr1: starting
4272026-03-30T18:08:40.679ZMar 30 18:08:40.666 DEBG [sc] cr1: connecting to [::1]:45504
4282026-03-30T18:08:40.704ZMar 30 18:08:40.667 DEBG [sc] cr1 waiting for prompt
4292026-03-30T18:08:40.704ZMar 30 18:08:40.678 DEBG [sc] cr1: logging in
4302026-03-30T18:08:40.823ZMar 30 18:08:40.811 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-30T18:09:37.392ZMar 30 18:09:37.380 DEBG [sc] ox: starting
4322026-03-30T18:09:37.392ZMar 30 18:09:37.380 DEBG [sc] ox: connecting to [::1]:33884
4332026-03-30T18:09:37.417ZMar 30 18:09:37.380 DEBG [sc] ox waiting for prompt
4342026-03-30T18:09:37.417ZMar 30 18:09:37.391 DEBG [sc] ox: logging in
4352026-03-30T18:09:37.496ZMar 30 18:09:37.482 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-03-30T18:09:38.129ZMar 30 18:09:38.116 DEBG [sc] ox: starting
4372026-03-30T18:09:38.129ZMar 30 18:09:38.116 DEBG [sc] ox: connecting to [::1]:33884
4382026-03-30T18:09:38.154ZMar 30 18:09:38.117 DEBG [sc] ox waiting for prompt
4392026-03-30T18:09:38.154ZMar 30 18:09:38.128 DEBG [sc] ox: logging in
4402026-03-30T18:09:38.218ZMar 30 18:09:38.206 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-03-30T18:09:39.241ZMar 30 18:09:39.229 DEBG [sc] ox: starting
4422026-03-30T18:09:39.241ZMar 30 18:09:39.229 DEBG [sc] ox: connecting to [::1]:33884
4432026-03-30T18:09:39.266ZMar 30 18:09:39.230 DEBG [sc] ox waiting for prompt
4442026-03-30T18:09:39.266ZMar 30 18:09:39.230 DEBG [sc] ox: logging in
4452026-03-30T18:09:39.319ZMar 30 18:09:39.307 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4462026-03-30T18:09:39.374ZMar 30 18:09:39.363 DEBG [sc] ox: starting
4472026-03-30T18:09:39.375ZMar 30 18:09:39.363 DEBG [sc] ox: connecting to [::1]:33884
4482026-03-30T18:09:39.399ZMar 30 18:09:39.363 DEBG [sc] ox waiting for prompt
4492026-03-30T18:09:39.399ZMar 30 18:09:39.373 DEBG [sc] ox: logging in
4502026-03-30T18:09:39.463ZMar 30 18:09:39.451 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4512026-03-30T18:09:39.518ZMar 30 18:09:39.507 DEBG [sc] ox: starting
4522026-03-30T18:09:39.518ZMar 30 18:09:39.507 DEBG [sc] ox: connecting to [::1]:33884
4532026-03-30T18:09:39.543ZMar 30 18:09:39.507 DEBG [sc] ox waiting for prompt
4542026-03-30T18:09:39.543ZMar 30 18:09:39.518 DEBG [sc] ox: logging in
4552026-03-30T18:09:39.606ZMar 30 18:09:39.594 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4562026-03-30T18:09:39.673ZMar 30 18:09:39.660 DEBG [sc] ox: starting
4572026-03-30T18:09:39.673ZMar 30 18:09:39.660 DEBG [sc] ox: connecting to [::1]:33884
4582026-03-30T18:09:39.697ZMar 30 18:09:39.661 DEBG [sc] ox waiting for prompt
4592026-03-30T18:09:39.697ZMar 30 18:09:39.671 DEBG [sc] ox: logging in
4602026-03-30T18:09:39.759ZMar 30 18:09:39.748 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-03-30T18:09:39.815ZMar 30 18:09:39.804 DEBG [sc] ox: starting
4622026-03-30T18:09:39.815ZMar 30 18:09:39.804 DEBG [sc] ox: connecting to [::1]:33884
4632026-03-30T18:09:39.840ZMar 30 18:09:39.804 DEBG [sc] ox waiting for prompt
4642026-03-30T18:09:39.840ZMar 30 18:09:39.814 DEBG [sc] ox: logging in
4652026-03-30T18:09:39.904ZMar 30 18:09:39.892 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-03-30T18:09:39.960ZMar 30 18:09:39.948 DEBG [sc] ox: starting
4672026-03-30T18:09:39.960ZMar 30 18:09:39.948 DEBG [sc] ox: connecting to [::1]:33884
4682026-03-30T18:09:39.984ZMar 30 18:09:39.948 DEBG [sc] ox waiting for prompt
4692026-03-30T18:09:39.984ZMar 30 18:09:39.959 DEBG [sc] ox: logging in
4702026-03-30T18:09:40.048ZMar 30 18:09:40.036 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-03-30T18:09:40.103ZMar 30 18:09:40.091 DEBG [sc] ox: starting
4722026-03-30T18:09:40.103ZMar 30 18:09:40.092 DEBG [sc] ox: connecting to [::1]:33884
4732026-03-30T18:09:40.128ZMar 30 18:09:40.092 DEBG [sc] ox waiting for prompt
4742026-03-30T18:09:40.128ZMar 30 18:09:40.103 DEBG [sc] ox: logging in
4752026-03-30T18:09:40.192ZMar 30 18:09:40.179 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-03-30T18:09:40.258ZMar 30 18:09:40.246 DEBG [sc] ox: starting
4772026-03-30T18:09:40.258ZMar 30 18:09:40.246 DEBG [sc] ox: connecting to [::1]:33884
4782026-03-30T18:09:40.282ZMar 30 18:09:40.247 DEBG [sc] ox waiting for prompt
4792026-03-30T18:09:40.282ZMar 30 18:09:40.257 DEBG [sc] ox: logging in
4802026-03-30T18:09:40.357ZMar 30 18:09:40.345 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-03-30T18:09:40.427ZMar 30 18:09:40.415 INFO adding BGP router to mgd
4822026-03-30T18:09:42.447ZMar 30 18:09:42.435 INFO cr1: executing frr script show ip bgp json
4832026-03-30T18:09:42.447ZMar 30 18:09:42.435 DEBG [sc] cr1: starting
4842026-03-30T18:09:42.447ZMar 30 18:09:42.435 DEBG [sc] cr1: connecting to [::1]:45504
4852026-03-30T18:09:42.471ZMar 30 18:09:42.436 DEBG [sc] cr1 waiting for prompt
4862026-03-30T18:09:42.471ZMar 30 18:09:42.447 DEBG [sc] cr1: logging in
4872026-03-30T18:09:42.801ZMar 30 18:09:42.789 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-03-30T18:09:44.947ZMar 30 18:09:44.936 INFO cr1: executing frr script show bgp json
4892026-03-30T18:09:44.947ZMar 30 18:09:44.936 DEBG [sc] cr1: starting
4902026-03-30T18:09:44.947ZMar 30 18:09:44.936 DEBG [sc] cr1: connecting to [::1]:45504
4912026-03-30T18:09:44.972ZMar 30 18:09:44.937 DEBG [sc] cr1 waiting for prompt
4922026-03-30T18:09:44.972ZMar 30 18:09:44.946 DEBG [sc] cr1: logging in
4932026-03-30T18:09:45.091ZMar 30 18:09:45.079 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-03-30T18:09:47.211ZMar 30 18:09:47.199 INFO cr2: executing eos script show ip bgp | json
4952026-03-30T18:09:47.211ZMar 30 18:09:47.199 DEBG [sc] cr2: starting
4962026-03-30T18:09:47.211ZMar 30 18:09:47.199 DEBG [sc] cr2: connecting to [::1]:63386
4972026-03-30T18:09:47.235ZMar 30 18:09:47.200 DEBG [sc] cr2 waiting for prompt
4982026-03-30T18:09:47.235ZMar 30 18:09:47.211 DEBG [sc] cr2: logging in
4992026-03-30T18:09:47.570ZMar 30 18:09:47.558 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-03-30T18:09:49.936ZMar 30 18:09:49.923 INFO cr2: executing eos script show ipv6 bgp | json
5012026-03-30T18:09:49.936ZMar 30 18:09:49.923 DEBG [sc] cr2: starting
5022026-03-30T18:09:49.936ZMar 30 18:09:49.923 DEBG [sc] cr2: connecting to [::1]:63386
5032026-03-30T18:09:49.961ZMar 30 18:09:49.924 DEBG [sc] cr2 waiting for prompt
5042026-03-30T18:09:49.961ZMar 30 18:09:49.934 DEBG [sc] cr2: logging in
5052026-03-30T18:09:50.090ZMar 30 18:09:50.078 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-03-30T18:09:52.469ZMar 30 18:09:52.457 INFO trio bgp unnumbered test passed 🎉
5072026-03-30T18:09:52.493ZMar 30 18:09:52.457 INFO destroying runner for deployment mgtriou
5082026-03-30T18:09:52.493ZMar 30 18:09:52.457 INFO destroying deployment mgtriou
5092026-03-30T18:09:52.493ZMar 30 18:09:52.457 INFO destroying nodes
5102026-03-30T18:09:52.567ZMar 30 18:09:52.556 INFO destroying links
5112026-03-30T18:09:52.567ZMar 30 18:09:52.556 INFO destroying link mgtriou_ox_sn_vnic0
5122026-03-30T18:09:52.592ZMar 30 18:09:52.558 INFO destroying link mgtriou_ox_sn_sim0
5132026-03-30T18:09:52.592ZMar 30 18:09:52.560 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-03-30T18:09:53.575ZMar 30 18:09:53.563 INFO destroying link mgtriou_cr1_vn_sim0
5152026-03-30T18:09:53.600ZMar 30 18:09:53.564 INFO destroying link mgtriou_ox_sn_vnic1
5162026-03-30T18:09:53.600ZMar 30 18:09:53.565 INFO destroying link mgtriou_ox_sn_sim1
5172026-03-30T18:09:53.600ZMar 30 18:09:53.567 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-03-30T18:09:53.600ZMar 30 18:09:53.568 INFO destroying link mgtriou_cr2_vn_sim0
5192026-03-30T18:09:53.600ZMar 30 18:09:53.569 INFO destroying external links
5202026-03-30T18:09:53.600ZMar 30 18:09:53.569 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-03-30T18:09:53.600ZMar 30 18:09:53.570 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-03-30T18:09:53.600ZMar 30 18:09:53.571 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-03-30T18:09:53.600ZMar 30 18:09:53.573 INFO destroying images
5242026-03-30T18:09:54.059ZMar 30 18:09:54.048 INFO destroying workspace at .falcon
5252026-03-30T18:09:54.086Zprocess exited: duration 497346 ms, exit code 0
 
5262026-03-30T18:09:54.137Zfound 0 output files