01KG5GSGKFG8DRQJ0S65CEVN07: falcon

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

Buildomat Job: 01KG5GT4HBTE6WHHDFYDDWRJ9V

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-01-29T19:07:43.372Zjob dependencies complete; ready to run (waiting for 28 m 51 s)
22026-01-29T19:10:11.043Zjob assigned to worker 01KG5JDTGCD9NEMPZSW1AA5Q66 [factory edgar, gimlet/BRM42220060/53] (queued for 2 m 27 s)
32026-01-29T19:10:12.257Zdownloading input: /input/build-interop/work/testbed.tar.gz
42026-01-29T19:10:24.343Zdownloaded input: /input/build-interop/work/testbed.tar.gz
52026-01-29T19:10:24.343Zdownloading input: /input/build-interop/work/dhcp-server
62026-01-29T19:10:27.809Zdownloaded input: /input/build-interop/work/dhcp-server
72026-01-29T19:10:27.832Zdownloading input: /input/build/work/debug/ddmadm
82026-01-29T19:10:49.056Zdownloaded input: /input/build/work/debug/ddmadm
92026-01-29T19:10:49.056Zdownloading input: /input/build/work/debug/ddmd
102026-01-29T19:11:14.357Zdownloaded input: /input/build/work/debug/ddmd
112026-01-29T19:11:14.357Zdownloading input: /input/build/work/debug/mgadm
122026-01-29T19:11:39.533Zdownloaded input: /input/build/work/debug/mgadm
132026-01-29T19:11:39.533Zdownloading input: /input/build/work/debug/mgd
142026-01-29T19:12:08.427Zdownloaded input: /input/build/work/debug/mgd
152026-01-29T19:12:08.451Zdownloading input: /input/build/work/release/ddmadm
162026-01-29T19:12:09.870Zdownloaded input: /input/build/work/release/ddmadm
172026-01-29T19:12:09.870Zdownloading input: /input/build/work/release/ddmd
182026-01-29T19:12:12.385Zdownloaded input: /input/build/work/release/ddmd
192026-01-29T19:12:12.385Zdownloading input: /input/build/work/release/falcon-lab
202026-01-29T19:12:13.942Zdownloaded input: /input/build/work/release/falcon-lab
212026-01-29T19:12:13.942Zdownloading input: /input/build/work/release/mgadm
222026-01-29T19:12:16.348Zdownloaded input: /input/build/work/release/mgadm
232026-01-29T19:12:16.349Zdownloading input: /input/build/work/release/mgd
242026-01-29T19:12:19.397Zdownloaded input: /input/build/work/release/mgd
 
252026-01-29T19:12:19.398Zstarting task 0: "setup"
262026-01-29T19:12:19.421Z++ uname -s
272026-01-29T19:12:19.421Z+ kern=SunOS
282026-01-29T19:12:19.421Z+ build_user=build
292026-01-29T19:12:19.421Z+ build_uid=12345
302026-01-29T19:12:19.422Z+ work_dir=/work
312026-01-29T19:12:19.422Z+ input_dir=/input
322026-01-29T19:12:19.422Z+ [[ 0 == 12345 ]]
332026-01-29T19:12:19.422Z+ case "$kern" in
342026-01-29T19:12:19.422Z+ groupadd -g 12345 build
352026-01-29T19:12:19.445Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-01-29T19:12:21.435Z+ zfs create -o mountpoint=/work rpool/work
372026-01-29T19:12:22.404Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-01-29T19:12:22.428Z+ home_fs=
392026-01-29T19:12:22.428Z+ [[ '' == autofs ]]
402026-01-29T19:12:22.428Z+ mkdir -p /home/build
412026-01-29T19:12:22.428Z+ chown build:build /home/build /work
422026-01-29T19:12:23.418Z+ chmod 0700 /home/build /work
432026-01-29T19:12:23.442Zprocess exited: duration 4024 ms, exit code 0
 
442026-01-29T19:12:23.489Zstarting task 1: "authentication"
452026-01-29T19:12:23.587Zprocess exited: duration 97 ms, exit code 0
 
462026-01-29T19:12:23.635Zstarting task 2: "build"
472026-01-29T19:12:23.658Z+ set -e
482026-01-29T19:12:23.658Z+ banner zpool
492026-01-29T19:12:23.658Z
502026-01-29T19:12:23.658Z ###### ##### #### #### #
512026-01-29T19:12:23.659Z # # # # # # # #
522026-01-29T19:12:23.659Z # # # # # # # #
532026-01-29T19:12:23.659Z # ##### # # # # #
542026-01-29T19:12:23.659Z # # # # # # #
552026-01-29T19:12:23.659Z ###### # #### #### ######
562026-01-29T19:12:23.659Z
572026-01-29T19:12:23.659Z++ pfexec diskinfo -pH
582026-01-29T19:12:23.682Z++ sort -k8 -n -r
592026-01-29T19:12:23.682Z++ head -1
602026-01-29T19:12:23.682Z++ awk '{print $2}'
612026-01-29T19:12:23.710Z+ DISK=c9t0014EE81000BC57Cd0
622026-01-29T19:12:23.710Z+ export DISK
632026-01-29T19:12:23.734Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-01-29T19:12:23.777Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-01-29T19:12:23.803Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-01-29T19:15:38.597Z+ [[ '' =~ true ]]
672026-01-29T19:15:38.621Z+ pfexec chown 12345 /ci
682026-01-29T19:15:38.622Z+ cd /ci
692026-01-29T19:15:38.622Z+ export FALCON_DATASET=cpool/falcon
702026-01-29T19:15:38.622Z+ FALCON_DATASET=cpool/falcon
712026-01-29T19:15:38.622Z+ banner setup
722026-01-29T19:15:38.624Z
732026-01-29T19:15:38.625Z #### ###### ##### # # #####
742026-01-29T19:15:38.625Z # # # # # # #
752026-01-29T19:15:38.625Z #### ##### # # # # #
762026-01-29T19:15:38.625Z # # # # # #####
772026-01-29T19:15:38.625Z # # # # # # #
782026-01-29T19:15:38.625Z #### ###### # #### #
792026-01-29T19:15:38.626Z
802026-01-29T19:15:38.626Z+ cp /input/build-interop/work/dhcp-server .
812026-01-29T19:15:38.964Z+ cp /input/build/work/release/falcon-lab .
822026-01-29T19:15:38.995Z+ cp /input/build/work/release/mgd .
832026-01-29T19:15:39.055Z+ cp /input/build/work/release/ddmd .
842026-01-29T19:15:39.114Z+ chmod +x dhcp-server falcon-lab mgd ddmd
852026-01-29T19:15:39.137Z+ mkdir -p cargo-bay
862026-01-29T19:15:39.137Z+ mv mgd cargo-bay/
872026-01-29T19:15:39.137Z+ mv ddmd cargo-bay/
882026-01-29T19:15:39.137Z+ export EXT_INTERFACE=igb0
892026-01-29T19:15:39.138Z+ EXT_INTERFACE=igb0
902026-01-29T19:15:39.138Z++ bmat address ls -f extra -Ho first
912026-01-29T19:15:39.165Z+ first=10.151.7.164
922026-01-29T19:15:39.166Z++ bmat address ls -f extra -Ho last
932026-01-29T19:15:39.189Z+ last=10.151.7.227
942026-01-29T19:15:39.189Z++ bmat address ls -f extra -Ho gateway
952026-01-29T19:15:39.212Z+ gw=10.151.7.1
962026-01-29T19:15:39.212Z++ ipadm show-addr igb0/dhcp -po ADDR
972026-01-29T19:15:39.212Z++ sed 's#/.*##g'
982026-01-29T19:15:39.236Z+ server=10.151.7.100
992026-01-29T19:15:39.236Z+ RUST_LOG=debug
1002026-01-29T19:15:39.236Z+ pfexec ./falcon-lab run --dendrite-commit 0c2ab6c341bf9e3802c688961b3bc687b941a144 trio-unnumbered
1012026-01-29T19:15:39.236Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1022026-01-29T19:15:39.259ZJan 29 19:15:39.235 DEBG using default route interface igb0
1032026-01-29T19:15:39.259ZJan 29 19:15:39.235 DEBG using default route interface igb0
1042026-01-29T19:15:39.259ZJan 29 19:15:39.235 DEBG using default route interface igb0
1052026-01-29T19:15:39.260ZJan 29 19:15:39.235 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1062026-01-29T19:15:39.260ZJan 29 19:15:39.235 INFO starting preflight for deployment mgtriou
1072026-01-29T19:15:39.260ZJan 29 19:15:39.235 INFO propolis-server binary not found
1082026-01-29T19:15:39.260ZJan 29 19:15:39.235 INFO downloading propolis server rev 2dc643742f82d2e072a1281dab23ba2bfdcee440, writing to .falcon/bin/propolis-server
1092026-01-29T19:15:43.052ZJan 29 19:15:43.049 INFO ovmf fd not found
1102026-01-29T19:15:43.052ZJan 29 19:15:43.049 INFO downloading ovmf
1112026-01-29T19:15:43.398ZJan 29 19:15:43.394 INFO base image for helios-2.9 does not exist, attempting to install
1122026-01-29T19:15:43.398ZJan 29 19:15:43.394 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1132026-01-29T19:16:12.849ZJan 29 19:16:12.844 INFO extracting image to /tmp/helios-2.9_0.raw
1142026-01-29T19:16:46.253ZJan 29 19:16:46.244 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1152026-01-29T19:16:46.299ZJan 29 19:16:46.291 INFO copying image data to zvol
1162026-01-29T19:16:56.931ZJan 29 19:16:56.923 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1172026-01-29T19:17:01.368ZJan 29 19:17:01.360 INFO base image for debian-13.2 does not exist, attempting to install
1182026-01-29T19:17:01.368ZJan 29 19:17:01.360 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1192026-01-29T19:17:28.737ZJan 29 19:17:28.728 INFO extracting image to /tmp/debian-13.2_0.raw
1202026-01-29T19:17:49.559ZJan 29 19:17:49.550 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1212026-01-29T19:17:49.584ZJan 29 19:17:49.575 INFO copying image data to zvol
1222026-01-29T19:17:56.657ZJan 29 19:17:56.648 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1232026-01-29T19:18:00.516ZJan 29 19:18:00.507 INFO base image for eos-4.35 does not exist, attempting to install
1242026-01-29T19:18:00.516ZJan 29 19:18:00.507 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1252026-01-29T19:18:49.626ZJan 29 19:18:49.616 INFO extracting image to /tmp/eos-4.35_0.raw
1262026-01-29T19:20:12.512ZJan 29 19:20:12.502 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1272026-01-29T19:20:12.536ZJan 29 19:20:12.526 INFO copying image data to zvol
1282026-01-29T19:21:02.167ZJan 29 19:21:02.157 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1292026-01-29T19:21:07.502ZJan 29 19:21:05.555 INFO creating links
1302026-01-29T19:21:07.562ZJan 29 19:21:05.555 DEBG destroying link mgtriou_ox_sn_vnic0
1312026-01-29T19:21:07.562ZJan 29 19:21:05.556 DEBG destroying link mgtriou_ox_sn_sim0
1322026-01-29T19:21:07.562ZJan 29 19:21:05.556 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1332026-01-29T19:21:07.562ZJan 29 19:21:05.562 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1342026-01-29T19:21:07.562ZJan 29 19:21:05.569 DEBG link pair created
1352026-01-29T19:21:07.562ZJan 29 19:21:05.569 DEBG destroying link mgtriou_cr1_vn_vnic0
1362026-01-29T19:21:07.562ZJan 29 19:21:05.569 DEBG destroying link mgtriou_cr1_vn_sim0
1372026-01-29T19:21:07.562ZJan 29 19:21:05.569 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1382026-01-29T19:21:07.562ZJan 29 19:21:05.571 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1392026-01-29T19:21:07.562ZJan 29 19:21:05.577 DEBG link pair created
1402026-01-29T19:21:07.562ZJan 29 19:21:05.577 DEBG destroying link mgtriou_ox_sn_vnic1
1412026-01-29T19:21:07.562ZJan 29 19:21:05.577 DEBG destroying link mgtriou_ox_sn_sim1
1422026-01-29T19:21:07.562ZJan 29 19:21:05.577 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1432026-01-29T19:21:07.562ZJan 29 19:21:05.579 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1442026-01-29T19:21:07.562ZJan 29 19:21:05.586 DEBG link pair created
1452026-01-29T19:21:07.562ZJan 29 19:21:05.586 DEBG destroying link mgtriou_cr2_vn_vnic0
1462026-01-29T19:21:07.562ZJan 29 19:21:05.586 DEBG destroying link mgtriou_cr2_vn_sim0
1472026-01-29T19:21:07.562ZJan 29 19:21:05.586 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1482026-01-29T19:21:07.562ZJan 29 19:21:05.587 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1492026-01-29T19:21:07.562ZJan 29 19:21:05.594 DEBG link pair created
1502026-01-29T19:21:07.562ZJan 29 19:21:05.594 INFO creating external links
1512026-01-29T19:21:07.563ZJan 29 19:21:05.594 DEBG destroying external link mgtriou_ox_vn_vnic2
1522026-01-29T19:21:07.563ZJan 29 19:21:05.594 INFO creating external link mgtriou_ox_vn_vnic2
1532026-01-29T19:21:07.563ZJan 29 19:21:05.596 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1542026-01-29T19:21:07.563ZJan 29 19:21:05.596 DEBG destroying external link mgtriou_cr1_vn_vnic1
1552026-01-29T19:21:07.563ZJan 29 19:21:05.596 INFO creating external link mgtriou_cr1_vn_vnic1
1562026-01-29T19:21:07.563ZJan 29 19:21:05.597 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1572026-01-29T19:21:07.563ZJan 29 19:21:05.597 DEBG destroying external link mgtriou_cr2_vn_vnic1
1582026-01-29T19:21:07.563ZJan 29 19:21:05.597 INFO creating external link mgtriou_cr2_vn_vnic1
1592026-01-29T19:21:07.563ZJan 29 19:21:05.599 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1602026-01-29T19:21:07.563ZJan 29 19:21:05.599 INFO creating nodes
1612026-01-29T19:21:07.563ZJan 29 19:21:05.599 INFO ox: launching node
1622026-01-29T19:21:07.563ZJan 29 19:21:05.602 INFO cr1: launching node
1632026-01-29T19:21:07.563ZJan 29 19:21:05.604 INFO cr2: launching node
1642026-01-29T19:21:07.563ZJan 29 19:21:05.618 INFO launched instance cr1 with pid 847 on port 33737
1652026-01-29T19:21:07.563ZJan 29 19:21:05.618 INFO cr1: instance ensure
1662026-01-29T19:21:07.563ZJan 29 19:21:05.618 INFO launched instance ox with pid 846 on port 38597
1672026-01-29T19:21:07.563ZJan 29 19:21:05.618 INFO ox: instance ensure
1682026-01-29T19:21:07.563ZJan 29 19:21:05.619 INFO launched instance cr2 with pid 848 on port 42311
1692026-01-29T19:21:07.563ZJan 29 19:21:05.619 INFO cr2: instance ensure
1702026-01-29T19:21:07.839ZJan 29 19:21:07.830 INFO ox: instance run
1712026-01-29T19:21:07.863ZJan 29 19:21:07.830 DEBG [sc] ox: starting
1722026-01-29T19:21:07.863ZJan 29 19:21:07.831 DEBG [sc] ox: connecting to [::1]:38597
1732026-01-29T19:21:07.863ZJan 29 19:21:07.831 DEBG [sc] ox waiting for prompt
1742026-01-29T19:21:07.899ZJan 29 19:21:07.889 INFO cr1: instance run
1752026-01-29T19:21:07.923ZJan 29 19:21:07.889 DEBG [sc] cr1: starting
1762026-01-29T19:21:07.923ZJan 29 19:21:07.889 DEBG [sc] cr1: connecting to [::1]:33737
1772026-01-29T19:21:07.923ZJan 29 19:21:07.890 DEBG [sc] cr1 waiting for prompt
1782026-01-29T19:21:07.923ZJan 29 19:21:07.905 INFO cr2: instance run
1792026-01-29T19:21:07.923ZJan 29 19:21:07.906 DEBG [sc] cr2: starting
1802026-01-29T19:21:07.923ZJan 29 19:21:07.906 DEBG [sc] cr2: connecting to [::1]:42311
1812026-01-29T19:21:07.923ZJan 29 19:21:07.907 DEBG [sc] cr2 waiting for prompt
1822026-01-29T19:21:25.023ZJan 29 19:21:25.007 DEBG [sc] cr1: logging in
1832026-01-29T19:21:25.395ZJan 29 19:21:25.384 INFO cr1: mounting /opt/cargo-bay
1842026-01-29T19:21:25.395ZJan 29 19:21:25.384 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
1852026-01-29T19:21:25.426ZJan 29 19:21:25.407 DEBG [sc] cr1: executing command `cd`
1862026-01-29T19:21:25.467ZJan 29 19:21:25.418 INFO cr1: finished mounting /opt/cargo-bay
1872026-01-29T19:21:25.468ZJan 29 19:21:25.418 DEBG [sc] cr1: executing command `hostname cr1`
1882026-01-29T19:21:25.468ZJan 29 19:21:25.429 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
1892026-01-29T19:21:25.468ZJan 29 19:21:25.440 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
1902026-01-29T19:21:25.468ZJan 29 19:21:25.451 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
1912026-01-29T19:21:25.494ZJan 29 19:21:25.463 INFO cr1: logging out
1922026-01-29T19:21:26.139ZJan 29 19:21:26.129 DEBG [sc] ox: logging in
1932026-01-29T19:21:26.369ZJan 29 19:21:26.359 INFO ox: mounting /opt/cargo-bay
1942026-01-29T19:21:26.369ZJan 29 19:21:26.359 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
1952026-01-29T19:21:27.526ZJan 29 19:21:27.516 DEBG [sc] ox: executing command `cd`
1962026-01-29T19:21:27.549ZJan 29 19:21:27.526 INFO cr1: logged out
1972026-01-29T19:21:27.550ZJan 29 19:21:27.527 INFO ox: finished mounting /opt/cargo-bay
1982026-01-29T19:21:27.550ZJan 29 19:21:27.527 DEBG [sc] ox: executing command `hostname ox`
1992026-01-29T19:21:27.550ZJan 29 19:21:27.538 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2002026-01-29T19:21:27.573ZJan 29 19:21:27.549 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2012026-01-29T19:21:27.574ZJan 29 19:21:27.561 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2022026-01-29T19:21:27.597ZJan 29 19:21:27.572 INFO ox: logging out
2032026-01-29T19:21:27.691ZJan 29 19:21:27.681 INFO ox: logged out
2042026-01-29T19:21:29.716ZJan 29 19:21:29.705 DEBG [sc] cr2: logging in
2052026-01-29T19:21:30.096ZJan 29 19:21:30.085 INFO cr2: mounting /opt/cargo-bay
2062026-01-29T19:21:30.096ZJan 29 19:21:30.085 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2072026-01-29T19:21:30.119ZJan 29 19:21:30.108 DEBG [sc] cr2: executing command `cd`
2082026-01-29T19:21:30.143ZJan 29 19:21:30.119 INFO cr2: finished mounting /opt/cargo-bay
2092026-01-29T19:21:30.143ZJan 29 19:21:30.119 DEBG [sc] cr2: executing command `hostname cr2`
2102026-01-29T19:21:30.143ZJan 29 19:21:30.130 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2112026-01-29T19:21:30.170ZJan 29 19:21:30.141 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2122026-01-29T19:21:30.170ZJan 29 19:21:30.152 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2132026-01-29T19:21:30.194ZJan 29 19:21:30.163 INFO cr2: logging out
2142026-01-29T19:21:33.075ZJan 29 19:21:33.065 INFO cr2: logged out
2152026-01-29T19:21:33.098ZJan 29 19:21:33.065 DEBG [sc] ox: starting
2162026-01-29T19:21:33.099ZJan 29 19:21:33.065 DEBG [sc] ox: connecting to [::1]:38597
2172026-01-29T19:21:33.099ZJan 29 19:21:33.066 DEBG [sc] ox waiting for prompt
2182026-01-29T19:21:33.099ZJan 29 19:21:33.076 DEBG [sc] ox: logging in
2192026-01-29T19:21:33.175ZJan 29 19:21:33.165 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2202026-01-29T19:21:37.947ZJan 29 19:21:37.885 DEBG [sc] ox: starting
2212026-01-29T19:21:37.947ZJan 29 19:21:37.885 DEBG [sc] ox: connecting to [::1]:38597
2222026-01-29T19:21:37.980ZJan 29 19:21:37.886 DEBG [sc] ox waiting for prompt
2232026-01-29T19:21:37.980ZJan 29 19:21:37.896 DEBG [sc] ox: logging in
2242026-01-29T19:21:38.005ZJan 29 19:21:37.984 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2252026-01-29T19:21:38.083ZJan 29 19:21:38.072 DEBG [sc] ox: starting
2262026-01-29T19:21:38.083ZJan 29 19:21:38.072 DEBG [sc] ox: connecting to [::1]:38597
2272026-01-29T19:21:38.110ZJan 29 19:21:38.073 DEBG [sc] ox waiting for prompt
2282026-01-29T19:21:38.110ZJan 29 19:21:38.083 DEBG [sc] ox: logging in
2292026-01-29T19:21:38.184ZJan 29 19:21:38.171 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2302026-01-29T19:21:38.247ZJan 29 19:21:38.237 INFO cr1: installing frr
2312026-01-29T19:21:38.247ZJan 29 19:21:38.237 INFO waiting for ceos to initialize
2322026-01-29T19:21:38.247ZJan 29 19:21:38.237 INFO ox: setting up npuvm
2332026-01-29T19:21:38.272ZJan 29 19:21:38.237 DEBG [sc] cr2: starting
2342026-01-29T19:21:38.273ZJan 29 19:21:38.237 DEBG [sc] cr2: connecting to [::1]:42311
2352026-01-29T19:21:38.273ZJan 29 19:21:38.237 DEBG [sc] cr1: starting
2362026-01-29T19:21:38.273ZJan 29 19:21:38.237 DEBG [sc] cr1: connecting to [::1]:33737
2372026-01-29T19:21:38.273ZJan 29 19:21:38.237 DEBG [sc] ox: starting
2382026-01-29T19:21:38.273ZJan 29 19:21:38.237 DEBG [sc] ox: connecting to [::1]:38597
2392026-01-29T19:21:38.273ZJan 29 19:21:38.238 DEBG [sc] cr2 waiting for prompt
2402026-01-29T19:21:38.273ZJan 29 19:21:38.238 DEBG [sc] cr1 waiting for prompt
2412026-01-29T19:21:38.273ZJan 29 19:21:38.238 DEBG [sc] ox waiting for prompt
2422026-01-29T19:21:38.273ZJan 29 19:21:38.248 DEBG [sc] cr2: logging in
2432026-01-29T19:21:38.273ZJan 29 19:21:38.248 DEBG [sc] ox: logging in
2442026-01-29T19:21:38.273ZJan 29 19:21:38.249 DEBG [sc] cr1: logging in
2452026-01-29T19:21:38.336ZJan 29 19:21:38.326 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2462026-01-29T19:21:38.393ZJan 29 19:21:38.380 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2472026-01-29T19:21:38.606ZJan 29 19:21:38.596 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2482026-01-29T19:21:40.623ZJan 29 19:21:40.613 INFO cr2: executing eos script show version
2492026-01-29T19:21:40.624ZJan 29 19:21:40.613 DEBG [sc] cr2: starting
2502026-01-29T19:21:40.624ZJan 29 19:21:40.613 DEBG [sc] cr2: connecting to [::1]:42311
2512026-01-29T19:21:40.655ZJan 29 19:21:40.613 DEBG [sc] cr2 waiting for prompt
2522026-01-29T19:21:40.655ZJan 29 19:21:40.623 DEBG [sc] cr2: logging in
2532026-01-29T19:21:40.778ZJan 29 19:21:40.766 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2542026-01-29T19:21:43.818ZJan 29 19:21:43.806 DEBG [sc] ox: starting
2552026-01-29T19:21:43.818ZJan 29 19:21:43.806 DEBG [sc] ox: connecting to [::1]:38597
2562026-01-29T19:21:43.843ZJan 29 19:21:43.807 DEBG [sc] ox waiting for prompt
2572026-01-29T19:21:43.843ZJan 29 19:21:43.817 DEBG [sc] ox: logging in
2582026-01-29T19:21:43.905ZJan 29 19:21:43.894 DEBG [sc] ox: executing command `chmod +x npuvm`
2592026-01-29T19:21:43.972ZJan 29 19:21:43.961 DEBG [sc] ox: starting
2602026-01-29T19:21:43.972ZJan 29 19:21:43.961 DEBG [sc] ox: connecting to [::1]:38597
2612026-01-29T19:21:44.001ZJan 29 19:21:43.962 DEBG [sc] ox waiting for prompt
2622026-01-29T19:21:44.001ZJan 29 19:21:43.972 DEBG [sc] ox: logging in
2632026-01-29T19:21:44.059ZJan 29 19:21:44.050 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 --dendrite-commit 0c2ab6c341bf9e3802c688961b3bc687b941a144 `
2642026-01-29T19:21:50.427ZJan 29 19:21:50.416 DEBG [sc] cr2: starting
2652026-01-29T19:21:50.427ZJan 29 19:21:50.417 DEBG [sc] cr2: connecting to [::1]:42311
2662026-01-29T19:21:50.450ZJan 29 19:21:50.417 DEBG [sc] cr2 waiting for prompt
2672026-01-29T19:21:50.450ZJan 29 19:21:50.427 DEBG [sc] cr2: logging in
2682026-01-29T19:21:50.591ZJan 29 19:21:50.581 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2692026-01-29T19:21:52.701ZJan 29 19:21:52.691 INFO cr2: executing eos script show version
2702026-01-29T19:21:52.701ZJan 29 19:21:52.691 DEBG [sc] cr2: starting
2712026-01-29T19:21:52.701ZJan 29 19:21:52.691 DEBG [sc] cr2: connecting to [::1]:42311
2722026-01-29T19:21:52.724ZJan 29 19:21:52.691 DEBG [sc] cr2 waiting for prompt
2732026-01-29T19:21:52.724ZJan 29 19:21:52.702 DEBG [sc] cr2: logging in
2742026-01-29T19:21:52.899ZJan 29 19:21:52.889 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2752026-01-29T19:21:55.269ZJan 29 19:21:55.257 DEBG [sc] cr2: starting
2762026-01-29T19:21:55.269ZJan 29 19:21:55.257 DEBG [sc] cr2: connecting to [::1]:42311
2772026-01-29T19:21:55.302ZJan 29 19:21:55.258 DEBG [sc] cr2 waiting for prompt
2782026-01-29T19:21:55.302ZJan 29 19:21:55.268 DEBG [sc] cr2: logging in
2792026-01-29T19:21:55.455ZJan 29 19:21:55.445 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2802026-01-29T19:21:57.598ZJan 29 19:21:57.587 INFO cr2: executing eos script show version
2812026-01-29T19:21:57.598ZJan 29 19:21:57.587 DEBG [sc] cr2: starting
2822026-01-29T19:21:57.598ZJan 29 19:21:57.587 DEBG [sc] cr2: connecting to [::1]:42311
2832026-01-29T19:21:57.622ZJan 29 19:21:57.587 DEBG [sc] cr2 waiting for prompt
2842026-01-29T19:21:57.622ZJan 29 19:21:57.598 DEBG [sc] cr2: logging in
2852026-01-29T19:21:57.761ZJan 29 19:21:57.751 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2862026-01-29T19:22:00.177ZJan 29 19:22:00.163 INFO cr2: executing eos script
2872026-01-29T19:22:00.177Z enable
2882026-01-29T19:22:00.177Z configure
2892026-01-29T19:22:00.177Z ipv6 unicast-routing
2902026-01-29T19:22:00.177Z ip routing ipv6 interfaces
2912026-01-29T19:22:00.177Z ip routing
2922026-01-29T19:22:00.177Z ip route 1.2.3.0/24 null0
2932026-01-29T19:22:00.177Z ipv6 route fd99::/64 null0
2942026-01-29T19:22:00.177Z interface et1
2952026-01-29T19:22:00.177Z no switchport
2962026-01-29T19:22:00.177Z ipv6 enable
2972026-01-29T19:22:00.177Z
2982026-01-29T19:22:00.177Z router bgp 45
2992026-01-29T19:22:00.177Z router-id 1.2.3.1
3002026-01-29T19:22:00.177Z no bgp default ipv4-unicast
3012026-01-29T19:22:00.178Z timers bgp 2 6
3022026-01-29T19:22:00.178Z neighbor ebgp peer group
3032026-01-29T19:22:00.178Z neighbor ebgp remote-as 33
3042026-01-29T19:22:00.178Z neighbor interface Et1 peer-group ebgp
3052026-01-29T19:22:00.178Z address-family ipv4
3062026-01-29T19:22:00.178Z neighbor ebgp activate
3072026-01-29T19:22:00.178Z neighbor ebgp next-hop address-family ipv6 originate
3082026-01-29T19:22:00.178Z network 1.2.3.0/24
3092026-01-29T19:22:00.178Z exit
3102026-01-29T19:22:00.178Z address-family ipv6
3112026-01-29T19:22:00.178Z neighbor ebgp activate
3122026-01-29T19:22:00.178Z neighbor ebgp next-hop address-family ipv6 originate
3132026-01-29T19:22:00.178Z network fd99::/64
3142026-01-29T19:22:00.178Z exit
3152026-01-29T19:22:00.178Z exit
3162026-01-29T19:22:00.179Z
3172026-01-29T19:22:00.179ZJan 29 19:22:00.164 DEBG [sc] cr2: starting
3182026-01-29T19:22:00.179ZJan 29 19:22:00.164 DEBG [sc] cr2: connecting to [::1]:42311
3192026-01-29T19:22:00.212ZJan 29 19:22:00.164 DEBG [sc] cr2 waiting for prompt
3202026-01-29T19:22:00.212ZJan 29 19:22:00.174 DEBG [sc] cr2: logging in
3212026-01-29T19:22:00.335ZJan 29 19:22:00.318 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3222026-01-29T19:22:00.335Z enable
3232026-01-29T19:22:00.335Z configure
3242026-01-29T19:22:00.335Z ipv6 unicast-routing
3252026-01-29T19:22:00.335Z ip routing ipv6 interfaces
3262026-01-29T19:22:00.335Z ip routing
3272026-01-29T19:22:00.335Z ip route 1.2.3.0/24 null0
3282026-01-29T19:22:00.335Z ipv6 route fd99::/64 null0
3292026-01-29T19:22:00.335Z interface et1
3302026-01-29T19:22:00.335Z no switchport
3312026-01-29T19:22:00.335Z ipv6 enable
3322026-01-29T19:22:00.335Z
3332026-01-29T19:22:00.335Z router bgp 45
3342026-01-29T19:22:00.335Z router-id 1.2.3.1
3352026-01-29T19:22:00.335Z no bgp default ipv4-unicast
3362026-01-29T19:22:00.335Z timers bgp 2 6
3372026-01-29T19:22:00.335Z neighbor ebgp peer group
3382026-01-29T19:22:00.335Z neighbor ebgp remote-as 33
3392026-01-29T19:22:00.336Z neighbor interface Et1 peer-group ebgp
3402026-01-29T19:22:00.336Z address-family ipv4
3412026-01-29T19:22:00.336Z neighbor ebgp activate
3422026-01-29T19:22:00.336Z neighbor ebgp next-hop address-family ipv6 originate
3432026-01-29T19:22:00.336Z network 1.2.3.0/24
3442026-01-29T19:22:00.336Z exit
3452026-01-29T19:22:00.336Z address-family ipv6
3462026-01-29T19:22:00.336Z neighbor ebgp activate
3472026-01-29T19:22:00.336Z neighbor ebgp next-hop address-family ipv6 originate
3482026-01-29T19:22:00.336Z network fd99::/64
3492026-01-29T19:22:00.336Z exit
3502026-01-29T19:22:00.336Z exit
3512026-01-29T19:22:00.336Z '`
3522026-01-29T19:22:16.177ZJan 29 19:22:16.163 INFO cr1: enabling frr daemon bgpd
3532026-01-29T19:22:16.200ZJan 29 19:22:16.163 DEBG [sc] cr1: starting
3542026-01-29T19:22:16.200ZJan 29 19:22:16.163 DEBG [sc] cr1: connecting to [::1]:33737
3552026-01-29T19:22:16.200ZJan 29 19:22:16.164 DEBG [sc] cr1 waiting for prompt
3562026-01-29T19:22:16.201ZJan 29 19:22:16.175 DEBG [sc] cr1: logging in
3572026-01-29T19:22:16.318ZJan 29 19:22:16.307 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3582026-01-29T19:22:18.404ZJan 29 19:22:18.393 DEBG [sc] cr1: starting
3592026-01-29T19:22:18.404ZJan 29 19:22:18.394 DEBG [sc] cr1: connecting to [::1]:33737
3602026-01-29T19:22:18.429ZJan 29 19:22:18.394 DEBG [sc] cr1 waiting for prompt
3612026-01-29T19:22:18.429ZJan 29 19:22:18.404 DEBG [sc] cr1: logging in
3622026-01-29T19:22:18.561ZJan 29 19:22:18.536 DEBG [sc] cr1: executing command `systemctl restart frr`
3632026-01-29T19:22:26.137ZJan 29 19:22:26.127 INFO cr1: executing frr script
3642026-01-29T19:22:26.137Z configure
3652026-01-29T19:22:26.137Z ip forwarding
3662026-01-29T19:22:26.138Z ipv6 forwarding
3672026-01-29T19:22:26.138Z ip route 1.2.3.0/24 null0
3682026-01-29T19:22:26.138Z ipv6 route fd99::/64 null0
3692026-01-29T19:22:26.138Z route-map PERMIT-ALL permit 10
3702026-01-29T19:22:26.138Z router bgp 44
3712026-01-29T19:22:26.138Z timers bgp 2 6
3722026-01-29T19:22:26.138Z neighbor enp0s8 interface remote-as external
3732026-01-29T19:22:26.138Z neighbor enp0s8 timers connect 1
3742026-01-29T19:22:26.138Z address-family ipv4 unicast
3752026-01-29T19:22:26.138Z network 1.2.3.0/24
3762026-01-29T19:22:26.138Z neighbor enp0s8 activate
3772026-01-29T19:22:26.138Z neighbor enp0s8 route-map PERMIT-ALL out
3782026-01-29T19:22:26.138Z neighbor enp0s8 route-map PERMIT-ALL in
3792026-01-29T19:22:26.138Z exit-address-family
3802026-01-29T19:22:26.138Z address-family ipv6 unicast
3812026-01-29T19:22:26.138Z network fd99::/64
3822026-01-29T19:22:26.138Z neighbor enp0s8 activate
3832026-01-29T19:22:26.138Z neighbor enp0s8 route-map PERMIT-ALL out
3842026-01-29T19:22:26.138Z neighbor enp0s8 route-map PERMIT-ALL in
3852026-01-29T19:22:26.138Z exit-address-family
3862026-01-29T19:22:26.138Z exit
3872026-01-29T19:22:26.138Z
3882026-01-29T19:22:26.138ZJan 29 19:22:26.127 DEBG [sc] cr1: starting
3892026-01-29T19:22:26.138ZJan 29 19:22:26.127 DEBG [sc] cr1: connecting to [::1]:33737
3902026-01-29T19:22:26.162ZJan 29 19:22:26.128 DEBG [sc] cr1 waiting for prompt
3912026-01-29T19:22:26.162ZJan 29 19:22:26.139 DEBG [sc] cr1: logging in
3922026-01-29T19:22:26.262ZJan 29 19:22:26.251 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 ' '`
3932026-01-29T19:23:09.872ZJan 29 19:23:09.862 DEBG [sc] ox: starting
3942026-01-29T19:23:09.872ZJan 29 19:23:09.862 DEBG [sc] ox: connecting to [::1]:38597
3952026-01-29T19:23:09.896ZJan 29 19:23:09.863 DEBG [sc] ox waiting for prompt
3962026-01-29T19:23:09.896ZJan 29 19:23:09.874 DEBG [sc] ox: logging in
3972026-01-29T19:23:09.972ZJan 29 19:23:09.961 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
3982026-01-29T19:23:10.616ZJan 29 19:23:10.606 DEBG [sc] ox: starting
3992026-01-29T19:23:10.616ZJan 29 19:23:10.606 DEBG [sc] ox: connecting to [::1]:38597
4002026-01-29T19:23:10.639ZJan 29 19:23:10.607 DEBG [sc] ox waiting for prompt
4012026-01-29T19:23:10.639ZJan 29 19:23:10.618 DEBG [sc] ox: logging in
4022026-01-29T19:23:10.705ZJan 29 19:23:10.695 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4032026-01-29T19:23:10.770ZJan 29 19:23:10.760 DEBG [sc] ox: starting
4042026-01-29T19:23:10.770ZJan 29 19:23:10.760 DEBG [sc] ox: connecting to [::1]:38597
4052026-01-29T19:23:10.794ZJan 29 19:23:10.761 DEBG [sc] ox waiting for prompt
4062026-01-29T19:23:10.794ZJan 29 19:23:10.771 DEBG [sc] ox: logging in
4072026-01-29T19:23:10.870ZJan 29 19:23:10.860 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4082026-01-29T19:23:10.936ZJan 29 19:23:10.926 DEBG [sc] ox: starting
4092026-01-29T19:23:10.936ZJan 29 19:23:10.926 DEBG [sc] ox: connecting to [::1]:38597
4102026-01-29T19:23:10.959ZJan 29 19:23:10.926 DEBG [sc] ox waiting for prompt
4112026-01-29T19:23:10.959ZJan 29 19:23:10.937 DEBG [sc] ox: logging in
4122026-01-29T19:23:11.035ZJan 29 19:23:11.025 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4132026-01-29T19:23:11.101ZJan 29 19:23:11.091 DEBG [sc] ox: starting
4142026-01-29T19:23:11.101ZJan 29 19:23:11.091 DEBG [sc] ox: connecting to [::1]:38597
4152026-01-29T19:23:11.125ZJan 29 19:23:11.091 DEBG [sc] ox waiting for prompt
4162026-01-29T19:23:11.125ZJan 29 19:23:11.102 DEBG [sc] ox: logging in
4172026-01-29T19:23:11.190ZJan 29 19:23:11.180 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4182026-01-29T19:23:12.213ZJan 29 19:23:12.203 DEBG [sc] ox: starting
4192026-01-29T19:23:12.213ZJan 29 19:23:12.203 DEBG [sc] ox: connecting to [::1]:38597
4202026-01-29T19:23:12.237ZJan 29 19:23:12.204 DEBG [sc] ox waiting for prompt
4212026-01-29T19:23:12.237ZJan 29 19:23:12.204 DEBG [sc] ox: logging in
4222026-01-29T19:23:12.306ZJan 29 19:23:12.292 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4232026-01-29T19:23:12.371ZJan 29 19:23:12.360 DEBG [sc] ox: starting
4242026-01-29T19:23:12.371ZJan 29 19:23:12.360 DEBG [sc] ox: connecting to [::1]:38597
4252026-01-29T19:23:12.395ZJan 29 19:23:12.361 DEBG [sc] ox waiting for prompt
4262026-01-29T19:23:12.395ZJan 29 19:23:12.371 DEBG [sc] ox: logging in
4272026-01-29T19:23:12.458ZJan 29 19:23:12.448 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4282026-01-29T19:23:12.525ZJan 29 19:23:12.515 DEBG [sc] ox: starting
4292026-01-29T19:23:12.525ZJan 29 19:23:12.515 DEBG [sc] ox: connecting to [::1]:38597
4302026-01-29T19:23:12.549ZJan 29 19:23:12.516 DEBG [sc] ox waiting for prompt
4312026-01-29T19:23:12.549ZJan 29 19:23:12.526 DEBG [sc] ox: logging in
4322026-01-29T19:23:12.616ZJan 29 19:23:12.603 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4332026-01-29T19:23:12.679ZJan 29 19:23:12.669 DEBG [sc] ox: starting
4342026-01-29T19:23:12.679ZJan 29 19:23:12.669 DEBG [sc] ox: connecting to [::1]:38597
4352026-01-29T19:23:12.702ZJan 29 19:23:12.670 DEBG [sc] ox waiting for prompt
4362026-01-29T19:23:12.702ZJan 29 19:23:12.680 DEBG [sc] ox: logging in
4372026-01-29T19:23:12.768ZJan 29 19:23:12.758 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4382026-01-29T19:23:12.823ZJan 29 19:23:12.813 DEBG [sc] ox: starting
4392026-01-29T19:23:12.823ZJan 29 19:23:12.813 DEBG [sc] ox: connecting to [::1]:38597
4402026-01-29T19:23:12.847ZJan 29 19:23:12.814 DEBG [sc] ox waiting for prompt
4412026-01-29T19:23:12.847ZJan 29 19:23:12.825 DEBG [sc] ox: logging in
4422026-01-29T19:23:12.912ZJan 29 19:23:12.902 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4432026-01-29T19:23:12.990ZJan 29 19:23:12.980 INFO adding BGP router to mgd
4442026-01-29T19:23:15.011ZJan 29 19:23:15.000 INFO cr1: executing frr script show ip bgp json
4452026-01-29T19:23:15.012ZJan 29 19:23:15.000 DEBG [sc] cr1: starting
4462026-01-29T19:23:15.012ZJan 29 19:23:15.000 DEBG [sc] cr1: connecting to [::1]:33737
4472026-01-29T19:23:15.036ZJan 29 19:23:15.001 DEBG [sc] cr1 waiting for prompt
4482026-01-29T19:23:15.037ZJan 29 19:23:15.012 DEBG [sc] cr1: logging in
4492026-01-29T19:23:15.349ZJan 29 19:23:15.336 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4502026-01-29T19:23:17.475ZJan 29 19:23:17.457 INFO cr1: executing frr script show bgp json
4512026-01-29T19:23:17.475ZJan 29 19:23:17.457 DEBG [sc] cr1: starting
4522026-01-29T19:23:17.475ZJan 29 19:23:17.457 DEBG [sc] cr1: connecting to [::1]:33737
4532026-01-29T19:23:17.499ZJan 29 19:23:17.458 DEBG [sc] cr1 waiting for prompt
4542026-01-29T19:23:17.499ZJan 29 19:23:17.468 DEBG [sc] cr1: logging in
4552026-01-29T19:23:17.599ZJan 29 19:23:17.589 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4562026-01-29T19:23:19.734ZJan 29 19:23:19.724 INFO cr2: executing eos script show ip bgp | json
4572026-01-29T19:23:19.734ZJan 29 19:23:19.724 DEBG [sc] cr2: starting
4582026-01-29T19:23:19.734ZJan 29 19:23:19.724 DEBG [sc] cr2: connecting to [::1]:42311
4592026-01-29T19:23:19.758ZJan 29 19:23:19.725 DEBG [sc] cr2 waiting for prompt
4602026-01-29T19:23:19.758ZJan 29 19:23:19.736 DEBG [sc] cr2: logging in
4612026-01-29T19:23:20.086ZJan 29 19:23:20.076 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
4622026-01-29T19:23:22.503ZJan 29 19:23:22.491 INFO cr2: executing eos script show ipv6 bgp | json
4632026-01-29T19:23:22.503ZJan 29 19:23:22.491 DEBG [sc] cr2: starting
4642026-01-29T19:23:22.503ZJan 29 19:23:22.491 DEBG [sc] cr2: connecting to [::1]:42311
4652026-01-29T19:23:22.526ZJan 29 19:23:22.492 DEBG [sc] cr2 waiting for prompt
4662026-01-29T19:23:22.526ZJan 29 19:23:22.501 DEBG [sc] cr2: logging in
4672026-01-29T19:23:22.655ZJan 29 19:23:22.645 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
4682026-01-29T19:23:25.036ZJan 29 19:23:25.026 INFO trio bgp unnumbered test passed 🎉
4692026-01-29T19:23:25.036ZJan 29 19:23:25.026 INFO destroying runner for deployment mgtriou
4702026-01-29T19:23:25.036ZJan 29 19:23:25.026 INFO destroying deployment mgtriou
4712026-01-29T19:23:25.036ZJan 29 19:23:25.026 INFO destroying nodes
4722026-01-29T19:23:25.153ZJan 29 19:23:25.143 INFO destroying links
4732026-01-29T19:23:25.153ZJan 29 19:23:25.143 INFO destroying link mgtriou_ox_sn_vnic0
4742026-01-29T19:23:25.177ZJan 29 19:23:25.145 INFO destroying link mgtriou_ox_sn_sim0
4752026-01-29T19:23:25.177ZJan 29 19:23:25.147 INFO destroying link mgtriou_cr1_vn_vnic0
4762026-01-29T19:23:26.160ZJan 29 19:23:26.150 INFO destroying link mgtriou_cr1_vn_sim0
4772026-01-29T19:23:26.184ZJan 29 19:23:26.151 INFO destroying link mgtriou_ox_sn_vnic1
4782026-01-29T19:23:26.184ZJan 29 19:23:26.152 INFO destroying link mgtriou_ox_sn_sim1
4792026-01-29T19:23:26.184ZJan 29 19:23:26.154 INFO destroying link mgtriou_cr2_vn_vnic0
4802026-01-29T19:23:26.184ZJan 29 19:23:26.155 INFO destroying link mgtriou_cr2_vn_sim0
4812026-01-29T19:23:26.184ZJan 29 19:23:26.156 INFO destroying external links
4822026-01-29T19:23:26.184ZJan 29 19:23:26.156 INFO destroying external link mgtriou_ox_vn_vnic2
4832026-01-29T19:23:26.184ZJan 29 19:23:26.157 INFO destroying external link mgtriou_cr1_vn_vnic1
4842026-01-29T19:23:26.184ZJan 29 19:23:26.158 INFO destroying external link mgtriou_cr2_vn_vnic1
4852026-01-29T19:23:26.184ZJan 29 19:23:26.160 INFO destroying images
4862026-01-29T19:23:26.638ZJan 29 19:23:26.628 INFO destroying workspace at .falcon
4872026-01-29T19:23:26.664Zprocess exited: duration 662996 ms, exit code 0
 
4882026-01-29T19:23:26.714Zfound 0 output files