01KNJJPWJAB9ZNSYPYSC7FKYQN: falcon

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

Buildomat Job: 01KNJJQCN725G0T39C0VTXCP1J

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-06T23:55:06.595Zjob dependencies complete; ready to run (waiting for 14 m 10 s)
22026-04-06T23:58:22.194Zjob assigned to worker 01KNJKHBS7YH5X891ZEW7PDK5H [factory edgar, gimlet/BRM42220010/729] (queued for 3 m 15 s)
32026-04-06T23:58:22.535Zdownloading input: /input/build/work/debug/ddmadm
42026-04-06T23:58:45.957Zdownloaded input: /input/build/work/debug/ddmadm
52026-04-06T23:58:45.957Zdownloading input: /input/build/work/debug/ddmd
62026-04-06T23:59:15.318Zdownloaded input: /input/build/work/debug/ddmd
72026-04-06T23:59:15.318Zdownloading input: /input/build/work/debug/mgadm
82026-04-06T23:59:42.767Zdownloaded input: /input/build/work/debug/mgadm
92026-04-06T23:59:42.792Zdownloading input: /input/build/work/debug/mgd
102026-04-07T00:00:16.263Zdownloaded input: /input/build/work/debug/mgd
112026-04-07T00:00:16.288Zdownloading input: /input/build/work/release/ddmadm
122026-04-07T00:00:18.463Zdownloaded input: /input/build/work/release/ddmadm
132026-04-07T00:00:18.488Zdownloading input: /input/build/work/release/ddmd
142026-04-07T00:00:20.936Zdownloaded input: /input/build/work/release/ddmd
152026-04-07T00:00:20.936Zdownloading input: /input/build/work/release/falcon-lab
162026-04-07T00:00:22.686Zdownloaded input: /input/build/work/release/falcon-lab
172026-04-07T00:00:22.711Zdownloading input: /input/build/work/release/mgadm
182026-04-07T00:00:25.361Zdownloaded input: /input/build/work/release/mgadm
192026-04-07T00:00:25.361Zdownloading input: /input/build/work/release/mgd
202026-04-07T00:00:28.854Zdownloaded input: /input/build/work/release/mgd
212026-04-07T00:00:28.878Zdownloading input: /input/build-interop/work/testbed.tar.gz
222026-04-07T00:00:37.332Zdownloaded input: /input/build-interop/work/testbed.tar.gz
232026-04-07T00:00:37.332Zdownloading input: /input/build-interop/work/dhcp-server
242026-04-07T00:00:39.670Zdownloaded input: /input/build-interop/work/dhcp-server
 
252026-04-07T00:00:39.670Zstarting task 0: "setup"
262026-04-07T00:00:39.695Z++ uname -s
272026-04-07T00:00:39.695Z+ kern=SunOS
282026-04-07T00:00:39.695Z+ build_user=build
292026-04-07T00:00:39.695Z+ build_uid=12345
302026-04-07T00:00:39.695Z+ work_dir=/work
312026-04-07T00:00:39.695Z+ input_dir=/input
322026-04-07T00:00:39.695Z+ [[ 0 == 12345 ]]
332026-04-07T00:00:39.695Z+ case "$kern" in
342026-04-07T00:00:39.695Z+ groupadd -g 12345 build
352026-04-07T00:00:39.721Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-04-07T00:00:41.708Z+ zfs create -o mountpoint=/work rpool/work
372026-04-07T00:00:42.405Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-04-07T00:00:42.433Z+ home_fs=
392026-04-07T00:00:42.433Z+ [[ '' == autofs ]]
402026-04-07T00:00:42.433Z+ mkdir -p /home/build
412026-04-07T00:00:42.433Z+ chown build:build /home/build /work
422026-04-07T00:00:43.419Z+ chmod 0700 /home/build /work
432026-04-07T00:00:43.444Zprocess exited: duration 3751 ms, exit code 0
 
442026-04-07T00:00:43.494Zstarting task 1: "authentication"
452026-04-07T00:00:43.591Zprocess exited: duration 96 ms, exit code 0
 
462026-04-07T00:00:43.643Zstarting task 2: "build"
472026-04-07T00:00:43.668Z+ set -e
482026-04-07T00:00:43.668Z+ banner zpool
492026-04-07T00:00:43.668Z
502026-04-07T00:00:43.668Z ###### ##### #### #### #
512026-04-07T00:00:43.668Z # # # # # # # #
522026-04-07T00:00:43.668Z # # # # # # # #
532026-04-07T00:00:43.668Z # ##### # # # # #
542026-04-07T00:00:43.668Z # # # # # # #
552026-04-07T00:00:43.668Z ###### # #### #### ######
562026-04-07T00:00:43.668Z
572026-04-07T00:00:43.668Z++ pfexec diskinfo -pH
582026-04-07T00:00:43.668Z++ sort -k8 -n -r
592026-04-07T00:00:43.694Z++ head -1
602026-04-07T00:00:43.694Z++ awk '{print $2}'
612026-04-07T00:00:43.719Z+ DISK=c9t0014EE81000BC3B1d0
622026-04-07T00:00:43.719Z+ export DISK
632026-04-07T00:00:43.719Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
642026-04-07T00:00:43.781Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-04-07T00:00:43.809Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-04-07T00:00:43.924Z+ [[ true =~ true ]]
672026-04-07T00:00:43.950Z+ pfexec zpool trim cpool
682026-04-07T00:00:43.950Z++ zpool status -t cpool
692026-04-07T00:00:43.975Z+ [[ ! pool: cpool
702026-04-07T00:00:43.975Z state: ONLINE
712026-04-07T00:00:43.975Z scan: none requested
722026-04-07T00:00:43.975Zconfig:
732026-04-07T00:00:43.975Z
742026-04-07T00:00:43.975Z NAME STATE READ WRITE CKSUM
752026-04-07T00:00:43.975Z cpool ONLINE 0 0 0
762026-04-07T00:00:43.975Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at April 7, 2026 at 12:00:43 AM UTC)
772026-04-07T00:00:43.975Z
782026-04-07T00:00:43.975Zerrors: No known data errors =~ 100% ]]
792026-04-07T00:00:43.975Z+ sleep 10
802026-04-07T00:00:53.960Z++ zpool status -t cpool
812026-04-07T00:00:53.985Z+ [[ ! pool: cpool
822026-04-07T00:00:53.985Z state: ONLINE
832026-04-07T00:00:53.985Z scan: none requested
842026-04-07T00:00:53.985Zconfig:
852026-04-07T00:00:53.985Z
862026-04-07T00:00:53.985Z NAME STATE READ WRITE CKSUM
872026-04-07T00:00:53.985Z cpool ONLINE 0 0 0
882026-04-07T00:00:53.985Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at April 7, 2026 at 12:00:43 AM UTC)
892026-04-07T00:00:53.985Z
902026-04-07T00:00:53.985Zerrors: No known data errors =~ 100% ]]
912026-04-07T00:00:53.985Z+ sleep 10
922026-04-07T00:01:03.975Z++ zpool status -t cpool
932026-04-07T00:01:04.000Z+ [[ ! pool: cpool
942026-04-07T00:01:04.000Z state: ONLINE
952026-04-07T00:01:04.000Z scan: none requested
962026-04-07T00:01:04.000Zconfig:
972026-04-07T00:01:04.000Z
982026-04-07T00:01:04.000Z NAME STATE READ WRITE CKSUM
992026-04-07T00:01:04.000Z cpool ONLINE 0 0 0
1002026-04-07T00:01:04.000Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at April 7, 2026 at 12:00:58 AM UTC)
1012026-04-07T00:01:04.000Z
1022026-04-07T00:01:04.000Zerrors: No known data errors =~ 100% ]]
1032026-04-07T00:01:04.000Z+ pfexec chown 12345 /ci
1042026-04-07T00:01:04.000Z+ cd /ci
1052026-04-07T00:01:04.000Z+ export FALCON_DATASET=cpool/falcon
1062026-04-07T00:01:04.000Z+ FALCON_DATASET=cpool/falcon
1072026-04-07T00:01:04.001Z+ banner setup
1082026-04-07T00:01:04.001Z
1092026-04-07T00:01:04.001Z #### ###### ##### # # #####
1102026-04-07T00:01:04.001Z # # # # # # #
1112026-04-07T00:01:04.001Z #### ##### # # # # #
1122026-04-07T00:01:04.001Z # # # # # #####
1132026-04-07T00:01:04.001Z # # # # # # #
1142026-04-07T00:01:04.001Z #### ###### # #### #
1152026-04-07T00:01:04.001Z
1162026-04-07T00:01:04.001Z+ cp /input/build-interop/work/dhcp-server .
1172026-04-07T00:01:04.026Z+ cp /input/build/work/release/falcon-lab .
1182026-04-07T00:01:04.062Z+ cp /input/build/work/release/mgd .
1192026-04-07T00:01:04.124Z+ cp /input/build/work/release/ddmd .
1202026-04-07T00:01:04.190Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-04-07T00:01:04.215Z+ mkdir -p cargo-bay
1222026-04-07T00:01:04.215Z+ mv mgd cargo-bay/
1232026-04-07T00:01:04.215Z+ mv ddmd cargo-bay/
1242026-04-07T00:01:04.215Z+ export EXT_INTERFACE=igb0
1252026-04-07T00:01:04.215Z+ EXT_INTERFACE=igb0
1262026-04-07T00:01:04.215Z++ bmat address ls -f extra -Ho first
1272026-04-07T00:01:04.240Z+ first=10.151.6.164
1282026-04-07T00:01:04.240Z++ bmat address ls -f extra -Ho last
1292026-04-07T00:01:04.265Z+ last=10.151.6.227
1302026-04-07T00:01:04.265Z++ bmat address ls -f extra -Ho gateway
1312026-04-07T00:01:04.289Z+ gw=10.151.6.1
1322026-04-07T00:01:04.289Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-04-07T00:01:04.289Z++ sed 's#/.*##g'
1342026-04-07T00:01:04.314Z+ server=10.151.6.100
1352026-04-07T00:01:04.314Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1362026-04-07T00:01:04.314Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-04-07T00:01:04.314Z+ error=
1382026-04-07T00:01:04.314Z+ RUST_LOG=debug
1392026-04-07T00:01:04.314Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-04-07T00:01:04.339ZApr 07 00:01:04.300 DEBG using default route interface igb0
1412026-04-07T00:01:04.339ZApr 07 00:01:04.301 DEBG using default route interface igb0
1422026-04-07T00:01:04.339ZApr 07 00:01:04.301 DEBG using default route interface igb0
1432026-04-07T00:01:04.340ZApr 07 00:01:04.301 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-04-07T00:01:04.340ZApr 07 00:01:04.301 INFO starting preflight for deployment mgtriou
1452026-04-07T00:01:04.340ZApr 07 00:01:04.301 INFO propolis-server binary not found
1462026-04-07T00:01:04.340ZApr 07 00:01:04.301 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-04-07T00:01:08.226ZApr 07 00:01:08.201 INFO ovmf fd not found
1482026-04-07T00:01:08.226ZApr 07 00:01:08.201 INFO downloading ovmf
1492026-04-07T00:01:08.625ZApr 07 00:01:08.600 INFO base image for helios-2.9 does not exist, attempting to install
1502026-04-07T00:01:08.625ZApr 07 00:01:08.600 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-04-07T00:01:36.615ZApr 07 00:01:36.600 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-04-07T00:02:10.351ZApr 07 00:02:10.333 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-04-07T00:02:10.402ZApr 07 00:02:10.385 INFO copying image data to zvol
1542026-04-07T00:02:20.926ZApr 07 00:02:20.908 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-04-07T00:02:25.812ZApr 07 00:02:25.793 INFO base image for debian-13.2 does not exist, attempting to install
1562026-04-07T00:02:25.812ZApr 07 00:02:25.793 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-04-07T00:02:46.827ZApr 07 00:02:46.807 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-04-07T00:03:08.012ZApr 07 00:03:07.993 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-04-07T00:03:08.038ZApr 07 00:03:08.020 INFO copying image data to zvol
1602026-04-07T00:03:15.311ZApr 07 00:03:15.293 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-04-07T00:03:18.510ZApr 07 00:03:18.492 INFO base image for eos-4.35 does not exist, attempting to install
1622026-04-07T00:03:18.510ZApr 07 00:03:18.492 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-04-07T00:04:01.243ZApr 07 00:04:01.226 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-04-07T00:05:24.945ZApr 07 00:05:24.932 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-04-07T00:05:24.970ZApr 07 00:05:24.958 INFO copying image data to zvol
1662026-04-07T00:06:13.157ZApr 07 00:06:13.144 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-04-07T00:06:16.570ZApr 07 00:06:16.558 INFO creating links
1682026-04-07T00:06:16.570ZApr 07 00:06:16.558 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-04-07T00:06:16.595ZApr 07 00:06:16.558 DEBG destroying link mgtriou_ox_sn_sim0
1702026-04-07T00:06:16.595ZApr 07 00:06:16.558 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-04-07T00:06:16.595ZApr 07 00:06:16.560 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-04-07T00:06:16.595ZApr 07 00:06:16.568 DEBG link pair created
1732026-04-07T00:06:16.595ZApr 07 00:06:16.568 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-04-07T00:06:16.595ZApr 07 00:06:16.568 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-04-07T00:06:16.595ZApr 07 00:06:16.568 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-04-07T00:06:16.595ZApr 07 00:06:16.569 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-04-07T00:06:16.595ZApr 07 00:06:16.576 DEBG link pair created
1782026-04-07T00:06:16.595ZApr 07 00:06:16.576 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-04-07T00:06:16.595ZApr 07 00:06:16.577 DEBG destroying link mgtriou_ox_sn_sim1
1802026-04-07T00:06:16.595ZApr 07 00:06:16.577 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-04-07T00:06:16.595ZApr 07 00:06:16.578 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-04-07T00:06:16.621ZApr 07 00:06:16.584 DEBG link pair created
1832026-04-07T00:06:16.621ZApr 07 00:06:16.584 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-04-07T00:06:16.621ZApr 07 00:06:16.585 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-04-07T00:06:16.621ZApr 07 00:06:16.585 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-04-07T00:06:16.621ZApr 07 00:06:16.587 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-04-07T00:06:16.621ZApr 07 00:06:16.593 DEBG link pair created
1882026-04-07T00:06:16.621ZApr 07 00:06:16.593 INFO creating external links
1892026-04-07T00:06:16.621ZApr 07 00:06:16.593 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-04-07T00:06:16.622ZApr 07 00:06:16.593 INFO creating external link mgtriou_ox_vn_vnic2
1912026-04-07T00:06:16.622ZApr 07 00:06:16.596 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-04-07T00:06:16.622ZApr 07 00:06:16.596 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-04-07T00:06:16.622ZApr 07 00:06:16.596 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-04-07T00:06:16.622ZApr 07 00:06:16.597 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-04-07T00:06:16.622ZApr 07 00:06:16.597 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-04-07T00:06:16.622ZApr 07 00:06:16.597 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-04-07T00:06:16.622ZApr 07 00:06:16.598 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-04-07T00:06:16.622ZApr 07 00:06:16.598 INFO creating nodes
1992026-04-07T00:06:16.622ZApr 07 00:06:16.598 INFO ox: launching node
2002026-04-07T00:06:16.622ZApr 07 00:06:16.600 INFO cr1: launching node
2012026-04-07T00:06:16.622ZApr 07 00:06:16.602 INFO cr2: launching node
2022026-04-07T00:06:16.648ZApr 07 00:06:16.616 INFO launched instance cr1 with pid 854 on port 52657
2032026-04-07T00:06:16.648ZApr 07 00:06:16.616 INFO cr1: instance ensure
2042026-04-07T00:06:16.648ZApr 07 00:06:16.616 INFO launched instance ox with pid 853 on port 42965
2052026-04-07T00:06:16.648ZApr 07 00:06:16.617 INFO ox: instance ensure
2062026-04-07T00:06:16.648ZApr 07 00:06:16.617 INFO launched instance cr2 with pid 855 on port 41419
2072026-04-07T00:06:16.648ZApr 07 00:06:16.617 INFO cr2: instance ensure
2082026-04-07T00:06:19.066ZApr 07 00:06:19.054 INFO ox: instance run
2092026-04-07T00:06:19.090ZApr 07 00:06:19.054 DEBG [sc] ox: starting
2102026-04-07T00:06:19.090ZApr 07 00:06:19.054 DEBG [sc] ox: connecting to [::1]:42965
2112026-04-07T00:06:19.090ZApr 07 00:06:19.055 DEBG [sc] ox waiting for prompt
2122026-04-07T00:06:19.132ZApr 07 00:06:19.120 INFO cr1: instance run
2132026-04-07T00:06:19.156ZApr 07 00:06:19.120 DEBG [sc] cr1: starting
2142026-04-07T00:06:19.156ZApr 07 00:06:19.120 DEBG [sc] cr1: connecting to [::1]:52657
2152026-04-07T00:06:19.156ZApr 07 00:06:19.121 DEBG [sc] cr1 waiting for prompt
2162026-04-07T00:06:19.156ZApr 07 00:06:19.130 INFO cr2: instance run
2172026-04-07T00:06:19.156ZApr 07 00:06:19.131 DEBG [sc] cr2: starting
2182026-04-07T00:06:19.156ZApr 07 00:06:19.131 DEBG [sc] cr2: connecting to [::1]:41419
2192026-04-07T00:06:19.156ZApr 07 00:06:19.132 DEBG [sc] cr2 waiting for prompt
2202026-04-07T00:06:36.259ZApr 07 00:06:36.247 DEBG [sc] cr1: logging in
2212026-04-07T00:06:36.611ZApr 07 00:06:36.599 INFO cr1: mounting /opt/cargo-bay
2222026-04-07T00:06:36.611ZApr 07 00:06:36.599 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-04-07T00:06:36.636ZApr 07 00:06:36.621 DEBG [sc] cr1: executing command `cd`
2242026-04-07T00:06:36.660ZApr 07 00:06:36.633 INFO cr1: finished mounting /opt/cargo-bay
2252026-04-07T00:06:36.660ZApr 07 00:06:36.633 DEBG [sc] cr1: executing command `hostname cr1`
2262026-04-07T00:06:36.660ZApr 07 00:06:36.644 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-04-07T00:06:36.685ZApr 07 00:06:36.655 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-04-07T00:06:36.685ZApr 07 00:06:36.666 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-04-07T00:06:36.710ZApr 07 00:06:36.677 INFO cr1: logging out
2302026-04-07T00:06:38.757ZApr 07 00:06:38.745 INFO cr1: logged out
2312026-04-07T00:06:39.535ZApr 07 00:06:39.523 DEBG [sc] ox: logging in
2322026-04-07T00:06:39.740ZApr 07 00:06:39.727 INFO ox: mounting /opt/cargo-bay
2332026-04-07T00:06:39.740ZApr 07 00:06:39.728 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-04-07T00:06:40.735ZApr 07 00:06:40.723 DEBG [sc] ox: executing command `cd`
2352026-04-07T00:06:40.760ZApr 07 00:06:40.734 INFO ox: finished mounting /opt/cargo-bay
2362026-04-07T00:06:40.760ZApr 07 00:06:40.734 DEBG [sc] ox: executing command `hostname ox`
2372026-04-07T00:06:40.760ZApr 07 00:06:40.746 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2382026-04-07T00:06:40.785ZApr 07 00:06:40.757 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2392026-04-07T00:06:40.785ZApr 07 00:06:40.768 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2402026-04-07T00:06:40.809ZApr 07 00:06:40.779 DEBG [sc] cr2: logging in
2412026-04-07T00:06:40.809ZApr 07 00:06:40.779 INFO ox: logging out
2422026-04-07T00:06:40.909ZApr 07 00:06:40.897 INFO ox: logged out
2432026-04-07T00:06:41.227ZApr 07 00:06:41.215 INFO cr2: mounting /opt/cargo-bay
2442026-04-07T00:06:41.227ZApr 07 00:06:41.215 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-04-07T00:06:41.253ZApr 07 00:06:41.236 DEBG [sc] cr2: executing command `cd`
2462026-04-07T00:06:41.278ZApr 07 00:06:41.247 INFO cr2: finished mounting /opt/cargo-bay
2472026-04-07T00:06:41.278ZApr 07 00:06:41.247 DEBG [sc] cr2: executing command `hostname cr2`
2482026-04-07T00:06:41.278ZApr 07 00:06:41.258 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-04-07T00:06:41.302ZApr 07 00:06:41.269 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-04-07T00:06:41.302ZApr 07 00:06:41.280 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-04-07T00:06:41.327ZApr 07 00:06:41.292 INFO cr2: logging out
2522026-04-07T00:06:44.310ZApr 07 00:06:44.299 INFO cr2: logged out
2532026-04-07T00:06:44.335ZApr 07 00:06:44.299 DEBG [sc] ox: starting
2542026-04-07T00:06:44.335ZApr 07 00:06:44.299 DEBG [sc] ox: connecting to [::1]:42965
2552026-04-07T00:06:44.335ZApr 07 00:06:44.300 DEBG [sc] ox waiting for prompt
2562026-04-07T00:06:44.335ZApr 07 00:06:44.311 DEBG [sc] ox: logging in
2572026-04-07T00:06:44.411ZApr 07 00:06:44.399 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-04-07T00:06:50.643ZApr 07 00:06:50.631 DEBG [sc] ox: starting
2592026-04-07T00:06:50.644ZApr 07 00:06:50.631 DEBG [sc] ox: connecting to [::1]:42965
2602026-04-07T00:06:50.668ZApr 07 00:06:50.632 DEBG [sc] ox waiting for prompt
2612026-04-07T00:06:50.668ZApr 07 00:06:50.642 DEBG [sc] ox: logging in
2622026-04-07T00:06:50.731ZApr 07 00:06:50.719 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-04-07T00:06:50.809ZApr 07 00:06:50.797 DEBG [sc] ox: starting
2642026-04-07T00:06:50.809ZApr 07 00:06:50.797 DEBG [sc] ox: connecting to [::1]:42965
2652026-04-07T00:06:50.834ZApr 07 00:06:50.797 DEBG [sc] ox waiting for prompt
2662026-04-07T00:06:50.834ZApr 07 00:06:50.808 DEBG [sc] ox: logging in
2672026-04-07T00:06:50.897ZApr 07 00:06:50.885 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-04-07T00:06:50.964ZApr 07 00:06:50.952 INFO cr1: installing frr
2692026-04-07T00:06:50.964ZApr 07 00:06:50.952 INFO waiting for ceos to initialize
2702026-04-07T00:06:50.964ZApr 07 00:06:50.952 INFO ox: setting up npuvm
2712026-04-07T00:06:50.989ZApr 07 00:06:50.952 DEBG [sc] cr2: starting
2722026-04-07T00:06:50.989ZApr 07 00:06:50.952 DEBG [sc] cr2: connecting to [::1]:41419
2732026-04-07T00:06:50.989ZApr 07 00:06:50.952 DEBG [sc] cr1: starting
2742026-04-07T00:06:50.989ZApr 07 00:06:50.952 DEBG [sc] cr1: connecting to [::1]:52657
2752026-04-07T00:06:50.989ZApr 07 00:06:50.952 DEBG [sc] ox: starting
2762026-04-07T00:06:50.989ZApr 07 00:06:50.952 DEBG [sc] ox: connecting to [::1]:42965
2772026-04-07T00:06:50.989ZApr 07 00:06:50.953 DEBG [sc] cr2 waiting for prompt
2782026-04-07T00:06:50.989ZApr 07 00:06:50.953 DEBG [sc] ox waiting for prompt
2792026-04-07T00:06:50.989ZApr 07 00:06:50.953 DEBG [sc] cr1 waiting for prompt
2802026-04-07T00:06:50.989ZApr 07 00:06:50.963 DEBG [sc] ox: logging in
2812026-04-07T00:06:50.989ZApr 07 00:06:50.964 DEBG [sc] cr2: logging in
2822026-04-07T00:06:50.989ZApr 07 00:06:50.964 DEBG [sc] cr1: logging in
2832026-04-07T00:06:51.052ZApr 07 00:06:51.040 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-04-07T00:06:51.118ZApr 07 00:06:51.107 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-04-07T00:06:51.330ZApr 07 00:06:51.318 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-04-07T00:06:53.370ZApr 07 00:06:53.358 INFO cr2: executing eos script show version
2872026-04-07T00:06:53.370ZApr 07 00:06:53.358 DEBG [sc] cr2: starting
2882026-04-07T00:06:53.370ZApr 07 00:06:53.358 DEBG [sc] cr2: connecting to [::1]:41419
2892026-04-07T00:06:53.395ZApr 07 00:06:53.358 DEBG [sc] cr2 waiting for prompt
2902026-04-07T00:06:53.395ZApr 07 00:06:53.369 DEBG [sc] cr2: logging in
2912026-04-07T00:06:53.513ZApr 07 00:06:53.501 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-04-07T00:06:56.556ZApr 07 00:06:56.544 DEBG [sc] ox: starting
2932026-04-07T00:06:56.556ZApr 07 00:06:56.544 DEBG [sc] ox: connecting to [::1]:42965
2942026-04-07T00:06:56.580ZApr 07 00:06:56.544 DEBG [sc] ox waiting for prompt
2952026-04-07T00:06:56.580ZApr 07 00:06:56.555 DEBG [sc] ox: logging in
2962026-04-07T00:06:56.643ZApr 07 00:06:56.631 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-04-07T00:06:56.710ZApr 07 00:06:56.698 DEBG [sc] ox: starting
2982026-04-07T00:06:56.710ZApr 07 00:06:56.698 DEBG [sc] ox: connecting to [::1]:42965
2992026-04-07T00:06:56.734ZApr 07 00:06:56.698 DEBG [sc] ox waiting for prompt
3002026-04-07T00:06:56.734ZApr 07 00:06:56.709 DEBG [sc] ox: logging in
3012026-04-07T00:06:56.798ZApr 07 00:06:56.786 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-04-07T00:07:02.023ZApr 07 00:07:02.011 DEBG [sc] cr2: starting
3032026-04-07T00:07:02.023ZApr 07 00:07:02.011 DEBG [sc] cr2: connecting to [::1]:41419
3042026-04-07T00:07:02.048ZApr 07 00:07:02.012 DEBG [sc] cr2 waiting for prompt
3052026-04-07T00:07:02.048ZApr 07 00:07:02.022 DEBG [sc] cr2: logging in
3062026-04-07T00:07:02.211ZApr 07 00:07:02.199 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-04-07T00:07:04.341ZApr 07 00:07:04.329 INFO cr2: executing eos script show version
3082026-04-07T00:07:04.341ZApr 07 00:07:04.329 DEBG [sc] cr2: starting
3092026-04-07T00:07:04.341ZApr 07 00:07:04.329 DEBG [sc] cr2: connecting to [::1]:41419
3102026-04-07T00:07:04.366ZApr 07 00:07:04.330 DEBG [sc] cr2 waiting for prompt
3112026-04-07T00:07:04.366ZApr 07 00:07:04.340 DEBG [sc] cr2: logging in
3122026-04-07T00:07:04.495ZApr 07 00:07:04.483 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-04-07T00:07:06.841ZApr 07 00:07:06.829 INFO cr2: executing eos script
3142026-04-07T00:07:06.842Z enable
3152026-04-07T00:07:06.842Z configure
3162026-04-07T00:07:06.842Z ipv6 unicast-routing
3172026-04-07T00:07:06.842Z ip routing ipv6 interfaces
3182026-04-07T00:07:06.842Z ip routing
3192026-04-07T00:07:06.842Z ip route 1.2.3.0/24 null0
3202026-04-07T00:07:06.842Z ipv6 route fd99::/64 null0
3212026-04-07T00:07:06.842Z interface et1
3222026-04-07T00:07:06.842Z no switchport
3232026-04-07T00:07:06.842Z ipv6 enable
3242026-04-07T00:07:06.842Z
3252026-04-07T00:07:06.842Z router bgp 45
3262026-04-07T00:07:06.842Z router-id 1.2.3.1
3272026-04-07T00:07:06.842Z no bgp default ipv4-unicast
3282026-04-07T00:07:06.842Z timers bgp 2 6
3292026-04-07T00:07:06.842Z neighbor ebgp peer group
3302026-04-07T00:07:06.842Z neighbor ebgp remote-as 33
3312026-04-07T00:07:06.842Z neighbor interface Et1 peer-group ebgp
3322026-04-07T00:07:06.842Z address-family ipv4
3332026-04-07T00:07:06.842Z neighbor ebgp activate
3342026-04-07T00:07:06.842Z neighbor ebgp next-hop address-family ipv6 originate
3352026-04-07T00:07:06.842Z network 1.2.3.0/24
3362026-04-07T00:07:06.842Z exit
3372026-04-07T00:07:06.842Z address-family ipv6
3382026-04-07T00:07:06.842Z neighbor ebgp activate
3392026-04-07T00:07:06.843Z neighbor ebgp next-hop address-family ipv6 originate
3402026-04-07T00:07:06.843Z network fd99::/64
3412026-04-07T00:07:06.843Z exit
3422026-04-07T00:07:06.843Z exit
3432026-04-07T00:07:06.843Z
3442026-04-07T00:07:06.843ZApr 07 00:07:06.829 DEBG [sc] cr2: starting
3452026-04-07T00:07:06.843ZApr 07 00:07:06.829 DEBG [sc] cr2: connecting to [::1]:41419
3462026-04-07T00:07:06.870ZApr 07 00:07:06.830 DEBG [sc] cr2 waiting for prompt
3472026-04-07T00:07:06.871ZApr 07 00:07:06.840 DEBG [sc] cr2: logging in
3482026-04-07T00:07:07.019ZApr 07 00:07:07.006 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3492026-04-07T00:07:07.019Z enable
3502026-04-07T00:07:07.019Z configure
3512026-04-07T00:07:07.019Z ipv6 unicast-routing
3522026-04-07T00:07:07.019Z ip routing ipv6 interfaces
3532026-04-07T00:07:07.019Z ip routing
3542026-04-07T00:07:07.019Z ip route 1.2.3.0/24 null0
3552026-04-07T00:07:07.019Z ipv6 route fd99::/64 null0
3562026-04-07T00:07:07.019Z interface et1
3572026-04-07T00:07:07.019Z no switchport
3582026-04-07T00:07:07.019Z ipv6 enable
3592026-04-07T00:07:07.019Z
3602026-04-07T00:07:07.019Z router bgp 45
3612026-04-07T00:07:07.019Z router-id 1.2.3.1
3622026-04-07T00:07:07.019Z no bgp default ipv4-unicast
3632026-04-07T00:07:07.019Z timers bgp 2 6
3642026-04-07T00:07:07.019Z neighbor ebgp peer group
3652026-04-07T00:07:07.019Z neighbor ebgp remote-as 33
3662026-04-07T00:07:07.020Z neighbor interface Et1 peer-group ebgp
3672026-04-07T00:07:07.020Z address-family ipv4
3682026-04-07T00:07:07.020Z neighbor ebgp activate
3692026-04-07T00:07:07.020Z neighbor ebgp next-hop address-family ipv6 originate
3702026-04-07T00:07:07.020Z network 1.2.3.0/24
3712026-04-07T00:07:07.020Z exit
3722026-04-07T00:07:07.020Z address-family ipv6
3732026-04-07T00:07:07.020Z neighbor ebgp activate
3742026-04-07T00:07:07.020Z neighbor ebgp next-hop address-family ipv6 originate
3752026-04-07T00:07:07.020Z network fd99::/64
3762026-04-07T00:07:07.020Z exit
3772026-04-07T00:07:07.020Z exit
3782026-04-07T00:07:07.020Z '`
3792026-04-07T00:07:28.723ZApr 07 00:07:28.711 INFO cr1: enabling frr daemon bgpd
3802026-04-07T00:07:28.748ZApr 07 00:07:28.711 DEBG [sc] cr1: starting
3812026-04-07T00:07:28.748ZApr 07 00:07:28.711 DEBG [sc] cr1: connecting to [::1]:52657
3822026-04-07T00:07:28.748ZApr 07 00:07:28.712 DEBG [sc] cr1 waiting for prompt
3832026-04-07T00:07:28.748ZApr 07 00:07:28.723 DEBG [sc] cr1: logging in
3842026-04-07T00:07:28.877ZApr 07 00:07:28.865 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3852026-04-07T00:07:30.957ZApr 07 00:07:30.945 DEBG [sc] cr1: starting
3862026-04-07T00:07:30.957ZApr 07 00:07:30.945 DEBG [sc] cr1: connecting to [::1]:52657
3872026-04-07T00:07:30.982ZApr 07 00:07:30.945 DEBG [sc] cr1 waiting for prompt
3882026-04-07T00:07:30.982ZApr 07 00:07:30.956 DEBG [sc] cr1: logging in
3892026-04-07T00:07:31.101ZApr 07 00:07:31.088 DEBG [sc] cr1: executing command `systemctl restart frr`
3902026-04-07T00:07:38.726ZApr 07 00:07:38.714 INFO cr1: executing frr script
3912026-04-07T00:07:38.726Z configure
3922026-04-07T00:07:38.726Z ip forwarding
3932026-04-07T00:07:38.726Z ipv6 forwarding
3942026-04-07T00:07:38.726Z ip route 1.2.3.0/24 null0
3952026-04-07T00:07:38.726Z ipv6 route fd99::/64 null0
3962026-04-07T00:07:38.726Z route-map PERMIT-ALL permit 10
3972026-04-07T00:07:38.726Z router bgp 44
3982026-04-07T00:07:38.726Z timers bgp 2 6
3992026-04-07T00:07:38.726Z neighbor enp0s8 interface remote-as external
4002026-04-07T00:07:38.726Z neighbor enp0s8 timers connect 1
4012026-04-07T00:07:38.726Z address-family ipv4 unicast
4022026-04-07T00:07:38.726Z network 1.2.3.0/24
4032026-04-07T00:07:38.726Z neighbor enp0s8 activate
4042026-04-07T00:07:38.726Z neighbor enp0s8 route-map PERMIT-ALL out
4052026-04-07T00:07:38.726Z neighbor enp0s8 route-map PERMIT-ALL in
4062026-04-07T00:07:38.726Z exit-address-family
4072026-04-07T00:07:38.726Z address-family ipv6 unicast
4082026-04-07T00:07:38.726Z network fd99::/64
4092026-04-07T00:07:38.726Z neighbor enp0s8 activate
4102026-04-07T00:07:38.726Z neighbor enp0s8 route-map PERMIT-ALL out
4112026-04-07T00:07:38.726Z neighbor enp0s8 route-map PERMIT-ALL in
4122026-04-07T00:07:38.726Z exit-address-family
4132026-04-07T00:07:38.726Z exit
4142026-04-07T00:07:38.726Z
4152026-04-07T00:07:38.726ZApr 07 00:07:38.714 DEBG [sc] cr1: starting
4162026-04-07T00:07:38.726ZApr 07 00:07:38.714 DEBG [sc] cr1: connecting to [::1]:52657
4172026-04-07T00:07:38.752ZApr 07 00:07:38.714 DEBG [sc] cr1 waiting for prompt
4182026-04-07T00:07:38.752ZApr 07 00:07:38.725 DEBG [sc] cr1: logging in
4192026-04-07T00:07:38.893ZApr 07 00:07:38.881 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 ' '`
4202026-04-07T00:08:36.400ZApr 07 00:08:36.389 DEBG [sc] ox: starting
4212026-04-07T00:08:36.401ZApr 07 00:08:36.389 DEBG [sc] ox: connecting to [::1]:42965
4222026-04-07T00:08:36.425ZApr 07 00:08:36.389 DEBG [sc] ox waiting for prompt
4232026-04-07T00:08:36.425ZApr 07 00:08:36.400 DEBG [sc] ox: logging in
4242026-04-07T00:08:36.489ZApr 07 00:08:36.477 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4252026-04-07T00:08:36.745ZApr 07 00:08:36.733 DEBG [sc] ox: starting
4262026-04-07T00:08:36.745ZApr 07 00:08:36.733 DEBG [sc] ox: connecting to [::1]:42965
4272026-04-07T00:08:36.771ZApr 07 00:08:36.734 DEBG [sc] ox waiting for prompt
4282026-04-07T00:08:36.771ZApr 07 00:08:36.744 DEBG [sc] ox: logging in
4292026-04-07T00:08:36.833ZApr 07 00:08:36.821 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4302026-04-07T00:08:37.855ZApr 07 00:08:37.843 DEBG [sc] ox: starting
4312026-04-07T00:08:37.855ZApr 07 00:08:37.843 DEBG [sc] ox: connecting to [::1]:42965
4322026-04-07T00:08:37.880ZApr 07 00:08:37.845 DEBG [sc] ox waiting for prompt
4332026-04-07T00:08:37.881ZApr 07 00:08:37.845 DEBG [sc] ox: logging in
4342026-04-07T00:08:37.935ZApr 07 00:08:37.923 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4352026-04-07T00:08:37.990ZApr 07 00:08:37.978 DEBG [sc] ox: starting
4362026-04-07T00:08:37.990ZApr 07 00:08:37.978 DEBG [sc] ox: connecting to [::1]:42965
4372026-04-07T00:08:38.015ZApr 07 00:08:37.978 DEBG [sc] ox waiting for prompt
4382026-04-07T00:08:38.015ZApr 07 00:08:37.989 DEBG [sc] ox: logging in
4392026-04-07T00:08:38.088ZApr 07 00:08:38.076 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4402026-04-07T00:08:38.145ZApr 07 00:08:38.132 DEBG [sc] ox: starting
4412026-04-07T00:08:38.145ZApr 07 00:08:38.132 DEBG [sc] ox: connecting to [::1]:42965
4422026-04-07T00:08:38.170ZApr 07 00:08:38.133 DEBG [sc] ox waiting for prompt
4432026-04-07T00:08:38.170ZApr 07 00:08:38.143 DEBG [sc] ox: logging in
4442026-04-07T00:08:38.232ZApr 07 00:08:38.220 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4452026-04-07T00:08:38.288ZApr 07 00:08:38.276 DEBG [sc] ox: starting
4462026-04-07T00:08:38.288ZApr 07 00:08:38.276 DEBG [sc] ox: connecting to [::1]:42965
4472026-04-07T00:08:38.313ZApr 07 00:08:38.277 DEBG [sc] ox waiting for prompt
4482026-04-07T00:08:38.313ZApr 07 00:08:38.288 DEBG [sc] ox: logging in
4492026-04-07T00:08:38.378ZApr 07 00:08:38.366 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4502026-04-07T00:08:38.433ZApr 07 00:08:38.420 DEBG [sc] ox: starting
4512026-04-07T00:08:38.433ZApr 07 00:08:38.420 DEBG [sc] ox: connecting to [::1]:42965
4522026-04-07T00:08:38.457ZApr 07 00:08:38.421 DEBG [sc] ox waiting for prompt
4532026-04-07T00:08:38.457ZApr 07 00:08:38.432 DEBG [sc] ox: logging in
4542026-04-07T00:08:38.520ZApr 07 00:08:38.509 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4552026-04-07T00:08:38.586ZApr 07 00:08:38.574 DEBG [sc] ox: starting
4562026-04-07T00:08:38.586ZApr 07 00:08:38.574 DEBG [sc] ox: connecting to [::1]:42965
4572026-04-07T00:08:38.611ZApr 07 00:08:38.575 DEBG [sc] ox waiting for prompt
4582026-04-07T00:08:38.611ZApr 07 00:08:38.585 DEBG [sc] ox: logging in
4592026-04-07T00:08:38.674ZApr 07 00:08:38.662 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4602026-04-07T00:08:38.730ZApr 07 00:08:38.718 DEBG [sc] ox: starting
4612026-04-07T00:08:38.730ZApr 07 00:08:38.718 DEBG [sc] ox: connecting to [::1]:42965
4622026-04-07T00:08:38.754ZApr 07 00:08:38.719 DEBG [sc] ox waiting for prompt
4632026-04-07T00:08:38.754ZApr 07 00:08:38.729 DEBG [sc] ox: logging in
4642026-04-07T00:08:38.818ZApr 07 00:08:38.806 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4652026-04-07T00:08:38.874ZApr 07 00:08:38.861 DEBG [sc] ox: starting
4662026-04-07T00:08:38.874ZApr 07 00:08:38.862 DEBG [sc] ox: connecting to [::1]:42965
4672026-04-07T00:08:38.898ZApr 07 00:08:38.862 DEBG [sc] ox waiting for prompt
4682026-04-07T00:08:38.898ZApr 07 00:08:38.873 DEBG [sc] ox: logging in
4692026-04-07T00:08:38.961ZApr 07 00:08:38.949 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4702026-04-07T00:08:39.018ZApr 07 00:08:39.007 INFO adding BGP router to mgd
4712026-04-07T00:08:41.037ZApr 07 00:08:41.025 INFO cr1: executing frr script show ip bgp json
4722026-04-07T00:08:41.037ZApr 07 00:08:41.025 DEBG [sc] cr1: starting
4732026-04-07T00:08:41.037ZApr 07 00:08:41.025 DEBG [sc] cr1: connecting to [::1]:52657
4742026-04-07T00:08:41.062ZApr 07 00:08:41.025 DEBG [sc] cr1 waiting for prompt
4752026-04-07T00:08:41.062ZApr 07 00:08:41.037 DEBG [sc] cr1: logging in
4762026-04-07T00:08:41.361ZApr 07 00:08:41.349 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4772026-04-07T00:08:43.472ZApr 07 00:08:43.460 INFO cr1: executing frr script show bgp json
4782026-04-07T00:08:43.472ZApr 07 00:08:43.460 DEBG [sc] cr1: starting
4792026-04-07T00:08:43.472ZApr 07 00:08:43.460 DEBG [sc] cr1: connecting to [::1]:52657
4802026-04-07T00:08:43.497ZApr 07 00:08:43.461 DEBG [sc] cr1 waiting for prompt
4812026-04-07T00:08:43.497ZApr 07 00:08:43.471 DEBG [sc] cr1: logging in
4822026-04-07T00:08:43.626ZApr 07 00:08:43.614 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4832026-04-07T00:08:45.755ZApr 07 00:08:45.743 INFO cr2: executing eos script show ip bgp | json
4842026-04-07T00:08:45.755ZApr 07 00:08:45.743 DEBG [sc] cr2: starting
4852026-04-07T00:08:45.755ZApr 07 00:08:45.743 DEBG [sc] cr2: connecting to [::1]:41419
4862026-04-07T00:08:45.780ZApr 07 00:08:45.744 DEBG [sc] cr2 waiting for prompt
4872026-04-07T00:08:45.780ZApr 07 00:08:45.754 DEBG [sc] cr2: logging in
4882026-04-07T00:08:46.094ZApr 07 00:08:46.082 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
4892026-04-07T00:08:48.458ZApr 07 00:08:48.445 INFO cr2: executing eos script show ipv6 bgp | json
4902026-04-07T00:08:48.458ZApr 07 00:08:48.445 DEBG [sc] cr2: starting
4912026-04-07T00:08:48.458ZApr 07 00:08:48.445 DEBG [sc] cr2: connecting to [::1]:41419
4922026-04-07T00:08:48.483ZApr 07 00:08:48.446 DEBG [sc] cr2 waiting for prompt
4932026-04-07T00:08:48.483ZApr 07 00:08:48.457 DEBG [sc] cr2: logging in
4942026-04-07T00:08:48.602ZApr 07 00:08:48.590 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
4952026-04-07T00:08:50.970ZApr 07 00:08:50.958 INFO trio bgp unnumbered test passed 🎉
4962026-04-07T00:08:50.970ZApr 07 00:08:50.958 INFO destroying runner for deployment mgtriou
4972026-04-07T00:08:50.970ZApr 07 00:08:50.958 INFO destroying deployment mgtriou
4982026-04-07T00:08:50.970ZApr 07 00:08:50.958 INFO destroying nodes
4992026-04-07T00:08:51.065ZApr 07 00:08:51.053 INFO destroying links
5002026-04-07T00:08:51.066ZApr 07 00:08:51.053 INFO destroying link mgtriou_ox_sn_vnic0
5012026-04-07T00:08:51.090ZApr 07 00:08:51.056 INFO destroying link mgtriou_ox_sn_sim0
5022026-04-07T00:08:51.090ZApr 07 00:08:51.057 INFO destroying link mgtriou_cr1_vn_vnic0
5032026-04-07T00:08:52.072ZApr 07 00:08:52.060 INFO destroying link mgtriou_cr1_vn_sim0
5042026-04-07T00:08:52.097ZApr 07 00:08:52.061 INFO destroying link mgtriou_ox_sn_vnic1
5052026-04-07T00:08:52.097ZApr 07 00:08:52.062 INFO destroying link mgtriou_ox_sn_sim1
5062026-04-07T00:08:52.097ZApr 07 00:08:52.064 INFO destroying link mgtriou_cr2_vn_vnic0
5072026-04-07T00:08:52.097ZApr 07 00:08:52.065 INFO destroying link mgtriou_cr2_vn_sim0
5082026-04-07T00:08:52.097ZApr 07 00:08:52.066 INFO destroying external links
5092026-04-07T00:08:52.097ZApr 07 00:08:52.066 INFO destroying external link mgtriou_ox_vn_vnic2
5102026-04-07T00:08:52.097ZApr 07 00:08:52.067 INFO destroying external link mgtriou_cr1_vn_vnic1
5112026-04-07T00:08:52.097ZApr 07 00:08:52.068 INFO destroying external link mgtriou_cr2_vn_vnic1
5122026-04-07T00:08:52.097ZApr 07 00:08:52.069 INFO destroying images
5132026-04-07T00:08:52.546ZApr 07 00:08:52.534 INFO destroying workspace at .falcon
5142026-04-07T00:08:52.570Zprocess exited: duration 488904 ms, exit code 0
 
5152026-04-07T00:08:52.622Zfound 0 output files