01KMGMBFXZ1GX7M1J282Z222BT: falcon

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

Buildomat Job: 01KMGMCKDB58MH7V04467FBR24

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-03-24T19:33:39.866Zjob dependencies complete; ready to run (waiting for 17 m 51 s)
22026-03-24T19:56:22.206Zjob assigned to worker 01KMGPH2J1H4GJNTA8MTVCVNS9 [factory edgar, gimlet/BRM42220060/249] (queued for 22 m 42 s)
32026-03-24T19:56:23.221Zdownloading input: /input/build/work/debug/ddmadm
42026-03-24T19:56:45.911Zdownloaded input: /input/build/work/debug/ddmadm
52026-03-24T19:56:45.912Zdownloading input: /input/build/work/debug/ddmd
62026-03-24T19:57:10.621Zdownloaded input: /input/build/work/debug/ddmd
72026-03-24T19:57:10.621Zdownloading input: /input/build/work/debug/mgadm
82026-03-24T19:57:36.986Zdownloaded input: /input/build/work/debug/mgadm
92026-03-24T19:57:37.010Zdownloading input: /input/build/work/debug/mgd
102026-03-24T19:58:09.770Zdownloaded input: /input/build/work/debug/mgd
112026-03-24T19:58:09.795Zdownloading input: /input/build-interop/work/testbed.tar.gz
122026-03-24T19:58:20.073Zdownloaded input: /input/build-interop/work/testbed.tar.gz
132026-03-24T19:58:20.098Zdownloading input: /input/build/work/release/ddmadm
142026-03-24T19:58:21.681Zdownloaded input: /input/build/work/release/ddmadm
152026-03-24T19:58:21.681Zdownloading input: /input/build-interop/work/dhcp-server
162026-03-24T19:58:26.343Zdownloaded input: /input/build-interop/work/dhcp-server
172026-03-24T19:58:26.344Zdownloading input: /input/build/work/release/ddmd
182026-03-24T19:58:29.603Zdownloaded input: /input/build/work/release/ddmd
192026-03-24T19:58:29.652Zdownloading input: /input/build/work/release/falcon-lab
202026-03-24T19:58:31.566Zdownloaded input: /input/build/work/release/falcon-lab
212026-03-24T19:58:31.596Zdownloading input: /input/build/work/release/mgadm
222026-03-24T19:58:36.469Zdownloaded input: /input/build/work/release/mgadm
232026-03-24T19:58:36.469Zdownloading input: /input/build/work/release/mgd
242026-03-24T19:58:40.943Zdownloaded input: /input/build/work/release/mgd
 
252026-03-24T19:58:40.943Zstarting task 0: "setup"
262026-03-24T19:58:40.967Z++ uname -s
272026-03-24T19:58:40.967Z+ kern=SunOS
282026-03-24T19:58:40.967Z+ build_user=build
292026-03-24T19:58:40.967Z+ build_uid=12345
302026-03-24T19:58:40.968Z+ work_dir=/work
312026-03-24T19:58:40.968Z+ input_dir=/input
322026-03-24T19:58:40.968Z+ [[ 0 == 12345 ]]
332026-03-24T19:58:40.968Z+ case "$kern" in
342026-03-24T19:58:40.968Z+ groupadd -g 12345 build
352026-03-24T19:58:40.993Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-03-24T19:58:42.978Z+ zfs create -o mountpoint=/work rpool/work
372026-03-24T19:58:43.075Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-03-24T19:58:43.100Z+ home_fs=
392026-03-24T19:58:43.100Z+ [[ '' == autofs ]]
402026-03-24T19:58:43.100Z+ mkdir -p /home/build
412026-03-24T19:58:43.100Z+ chown build:build /home/build /work
422026-03-24T19:58:44.088Z+ chmod 0700 /home/build /work
432026-03-24T19:58:44.113Zprocess exited: duration 3148 ms, exit code 0
 
442026-03-24T19:58:44.163Zstarting task 1: "authentication"
452026-03-24T19:58:44.259Zprocess exited: duration 95 ms, exit code 0
 
462026-03-24T19:58:44.309Zstarting task 2: "build"
472026-03-24T19:58:44.334Z+ set -e
482026-03-24T19:58:44.334Z+ banner zpool
492026-03-24T19:58:44.334Z
502026-03-24T19:58:44.335Z ###### ##### #### #### #
512026-03-24T19:58:44.335Z # # # # # # # #
522026-03-24T19:58:44.335Z # # # # # # # #
532026-03-24T19:58:44.335Z # ##### # # # # #
542026-03-24T19:58:44.335Z # # # # # # #
552026-03-24T19:58:44.335Z ###### # #### #### ######
562026-03-24T19:58:44.335Z
572026-03-24T19:58:44.335Z++ pfexec diskinfo -pH
582026-03-24T19:58:44.361Z++ sort -k8 -n -r
592026-03-24T19:58:44.361Z++ head -1
602026-03-24T19:58:44.361Z++ awk '{print $2}'
612026-03-24T19:58:44.385Z+ DISK=c9t0014EE81000BC57Cd0
622026-03-24T19:58:44.386Z+ export DISK
632026-03-24T19:58:44.386Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-03-24T19:58:44.450Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-03-24T19:58:44.478Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-03-24T19:58:44.618Z+ [[ true =~ true ]]
672026-03-24T19:58:44.642Z+ pfexec zpool trim cpool
682026-03-24T19:58:44.643Z++ zpool status -t cpool
692026-03-24T19:58:44.668Z+ [[ ! pool: cpool
702026-03-24T19:58:44.668Z state: ONLINE
712026-03-24T19:58:44.668Z scan: none requested
722026-03-24T19:58:44.668Zconfig:
732026-03-24T19:58:44.668Z
742026-03-24T19:58:44.668Z NAME STATE READ WRITE CKSUM
752026-03-24T19:58:44.668Z cpool ONLINE 0 0 0
762026-03-24T19:58:44.668Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at March 24, 2026 at 07:58:44 PM UTC)
772026-03-24T19:58:44.668Z
782026-03-24T19:58:44.668Zerrors: No known data errors =~ 100% ]]
792026-03-24T19:58:44.668Z+ sleep 10
802026-03-24T19:58:54.651Z++ zpool status -t cpool
812026-03-24T19:58:54.676Z+ [[ ! pool: cpool
822026-03-24T19:58:54.676Z state: ONLINE
832026-03-24T19:58:54.676Z scan: none requested
842026-03-24T19:58:54.676Zconfig:
852026-03-24T19:58:54.677Z
862026-03-24T19:58:54.677Z NAME STATE READ WRITE CKSUM
872026-03-24T19:58:54.677Z cpool ONLINE 0 0 0
882026-03-24T19:58:54.677Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (70% trimmed, started at March 24, 2026 at 07:58:44 PM UTC)
892026-03-24T19:58:54.677Z
902026-03-24T19:58:54.677Zerrors: No known data errors =~ 100% ]]
912026-03-24T19:58:54.677Z+ sleep 10
922026-03-24T19:59:04.667Z++ zpool status -t cpool
932026-03-24T19:59:04.691Z+ [[ ! pool: cpool
942026-03-24T19:59:04.691Z state: ONLINE
952026-03-24T19:59:04.691Z scan: none requested
962026-03-24T19:59:04.691Zconfig:
972026-03-24T19:59:04.691Z
982026-03-24T19:59:04.691Z NAME STATE READ WRITE CKSUM
992026-03-24T19:59:04.692Z cpool ONLINE 0 0 0
1002026-03-24T19:59:04.692Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at March 24, 2026 at 07:58:59 PM UTC)
1012026-03-24T19:59:04.692Z
1022026-03-24T19:59:04.692Zerrors: No known data errors =~ 100% ]]
1032026-03-24T19:59:04.692Z+ pfexec chown 12345 /ci
1042026-03-24T19:59:04.692Z+ cd /ci
1052026-03-24T19:59:04.692Z+ export FALCON_DATASET=cpool/falcon
1062026-03-24T19:59:04.692Z+ FALCON_DATASET=cpool/falcon
1072026-03-24T19:59:04.692Z+ banner setup
1082026-03-24T19:59:04.692Z
1092026-03-24T19:59:04.692Z #### ###### ##### # # #####
1102026-03-24T19:59:04.692Z # # # # # # #
1112026-03-24T19:59:04.692Z #### ##### # # # # #
1122026-03-24T19:59:04.692Z # # # # # #####
1132026-03-24T19:59:04.692Z # # # # # # #
1142026-03-24T19:59:04.692Z #### ###### # #### #
1152026-03-24T19:59:04.692Z
1162026-03-24T19:59:04.693Z+ cp /input/build-interop/work/dhcp-server .
1172026-03-24T19:59:04.727Z+ cp /input/build/work/release/falcon-lab .
1182026-03-24T19:59:04.761Z+ cp /input/build/work/release/mgd .
1192026-03-24T19:59:04.819Z+ cp /input/build/work/release/ddmd .
1202026-03-24T19:59:04.869Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-03-24T19:59:04.899Z+ mkdir -p cargo-bay
1222026-03-24T19:59:04.899Z+ mv mgd cargo-bay/
1232026-03-24T19:59:04.899Z+ mv ddmd cargo-bay/
1242026-03-24T19:59:04.899Z+ export EXT_INTERFACE=igb0
1252026-03-24T19:59:04.899Z+ EXT_INTERFACE=igb0
1262026-03-24T19:59:04.899Z++ bmat address ls -f extra -Ho first
1272026-03-24T19:59:04.924Z+ first=10.151.7.164
1282026-03-24T19:59:04.924Z++ bmat address ls -f extra -Ho last
1292026-03-24T19:59:04.949Z+ last=10.151.7.227
1302026-03-24T19:59:04.949Z++ bmat address ls -f extra -Ho gateway
1312026-03-24T19:59:04.974Z+ gw=10.151.7.1
1322026-03-24T19:59:04.974Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-03-24T19:59:04.974Z++ sed 's#/.*##g'
1342026-03-24T19:59:04.974Z+ server=10.151.7.100
1352026-03-24T19:59:04.974Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1362026-03-24T19:59:04.998Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-03-24T19:59:04.998Z+ error=
1382026-03-24T19:59:04.999Z+ RUST_LOG=debug
1392026-03-24T19:59:04.999Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-03-24T19:59:05.025ZMar 24 19:59:05.005 DEBG using default route interface igb0
1412026-03-24T19:59:05.025ZMar 24 19:59:05.006 DEBG using default route interface igb0
1422026-03-24T19:59:05.026ZMar 24 19:59:05.006 DEBG using default route interface igb0
1432026-03-24T19:59:05.026ZMar 24 19:59:05.006 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-03-24T19:59:05.026ZMar 24 19:59:05.006 INFO starting preflight for deployment mgtriou
1452026-03-24T19:59:05.026ZMar 24 19:59:05.006 INFO propolis-server binary not found
1462026-03-24T19:59:05.026ZMar 24 19:59:05.006 INFO downloading propolis server rev ff31c527515d65886e599fc07eb41240aeb767c6, writing to .falcon/bin/propolis-server
1472026-03-24T19:59:08.844ZMar 24 19:59:08.846 INFO ovmf fd not found
1482026-03-24T19:59:08.844ZMar 24 19:59:08.846 INFO downloading ovmf
1492026-03-24T19:59:09.245ZMar 24 19:59:09.248 INFO base image for helios-2.9 does not exist, attempting to install
1502026-03-24T19:59:09.245ZMar 24 19:59:09.248 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-03-24T19:59:37.930ZMar 24 19:59:37.930 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-03-24T20:00:11.448ZMar 24 20:00:11.440 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-03-24T20:00:11.502ZMar 24 20:00:11.495 INFO copying image data to zvol
1542026-03-24T20:00:21.726ZMar 24 20:00:21.720 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-03-24T20:00:25.105ZMar 24 20:00:25.099 INFO base image for debian-13.2 does not exist, attempting to install
1562026-03-24T20:00:25.105ZMar 24 20:00:25.099 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-03-24T20:00:45.537ZMar 24 20:00:45.531 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-03-24T20:01:06.343ZMar 24 20:01:06.336 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-03-24T20:01:06.370ZMar 24 20:01:06.363 INFO copying image data to zvol
1602026-03-24T20:01:12.361ZMar 24 20:01:12.354 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-03-24T20:01:15.707ZMar 24 20:01:15.701 INFO base image for eos-4.35 does not exist, attempting to install
1622026-03-24T20:01:15.707ZMar 24 20:01:15.701 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-03-24T20:02:03.906ZMar 24 20:02:03.895 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-03-24T20:03:26.750ZMar 24 20:03:26.739 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-03-24T20:03:26.776ZMar 24 20:03:26.766 INFO copying image data to zvol
1662026-03-24T20:04:14.804ZMar 24 20:04:14.792 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-03-24T20:04:18.144ZMar 24 20:04:18.132 INFO creating links
1682026-03-24T20:04:18.144ZMar 24 20:04:18.132 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-03-24T20:04:18.168ZMar 24 20:04:18.133 DEBG destroying link mgtriou_ox_sn_sim0
1702026-03-24T20:04:18.169ZMar 24 20:04:18.133 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-03-24T20:04:18.169ZMar 24 20:04:18.135 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-03-24T20:04:18.169ZMar 24 20:04:18.142 DEBG link pair created
1732026-03-24T20:04:18.169ZMar 24 20:04:18.142 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-03-24T20:04:18.169ZMar 24 20:04:18.142 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-03-24T20:04:18.169ZMar 24 20:04:18.142 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-03-24T20:04:18.169ZMar 24 20:04:18.143 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-03-24T20:04:18.169ZMar 24 20:04:18.150 DEBG link pair created
1782026-03-24T20:04:18.169ZMar 24 20:04:18.150 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-03-24T20:04:18.169ZMar 24 20:04:18.150 DEBG destroying link mgtriou_ox_sn_sim1
1802026-03-24T20:04:18.169ZMar 24 20:04:18.150 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-03-24T20:04:18.169ZMar 24 20:04:18.152 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-03-24T20:04:18.194ZMar 24 20:04:18.159 DEBG link pair created
1832026-03-24T20:04:18.194ZMar 24 20:04:18.159 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-03-24T20:04:18.194ZMar 24 20:04:18.159 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-03-24T20:04:18.194ZMar 24 20:04:18.159 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-03-24T20:04:18.194ZMar 24 20:04:18.161 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-03-24T20:04:18.194ZMar 24 20:04:18.167 DEBG link pair created
1882026-03-24T20:04:18.194ZMar 24 20:04:18.167 INFO creating external links
1892026-03-24T20:04:18.194ZMar 24 20:04:18.167 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-03-24T20:04:18.194ZMar 24 20:04:18.167 INFO creating external link mgtriou_ox_vn_vnic2
1912026-03-24T20:04:18.194ZMar 24 20:04:18.169 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-03-24T20:04:18.194ZMar 24 20:04:18.169 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-03-24T20:04:18.194ZMar 24 20:04:18.169 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-03-24T20:04:18.194ZMar 24 20:04:18.170 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-03-24T20:04:18.194ZMar 24 20:04:18.170 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-03-24T20:04:18.194ZMar 24 20:04:18.170 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-03-24T20:04:18.194ZMar 24 20:04:18.171 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-03-24T20:04:18.194ZMar 24 20:04:18.171 INFO creating nodes
1992026-03-24T20:04:18.194ZMar 24 20:04:18.171 INFO ox: launching node
2002026-03-24T20:04:18.194ZMar 24 20:04:18.174 INFO cr1: launching node
2012026-03-24T20:04:18.194ZMar 24 20:04:18.176 INFO cr2: launching node
2022026-03-24T20:04:18.243ZMar 24 20:04:18.190 INFO launched instance ox with pid 857 on port 40627
2032026-03-24T20:04:18.244ZMar 24 20:04:18.190 INFO ox: instance ensure
2042026-03-24T20:04:18.244ZMar 24 20:04:18.190 INFO launched instance cr1 with pid 858 on port 48101
2052026-03-24T20:04:18.244ZMar 24 20:04:18.190 INFO cr1: instance ensure
2062026-03-24T20:04:18.244ZMar 24 20:04:18.191 INFO launched instance cr2 with pid 859 on port 42383
2072026-03-24T20:04:18.244ZMar 24 20:04:18.191 INFO cr2: instance ensure
2082026-03-24T20:04:20.397ZMar 24 20:04:20.384 INFO cr2: instance run
2092026-03-24T20:04:20.421ZMar 24 20:04:20.385 DEBG [sc] cr2: starting
2102026-03-24T20:04:20.421ZMar 24 20:04:20.385 DEBG [sc] cr2: connecting to [::1]:42383
2112026-03-24T20:04:20.421ZMar 24 20:04:20.386 DEBG [sc] cr2 waiting for prompt
2122026-03-24T20:04:20.582ZMar 24 20:04:20.570 INFO ox: instance run
2132026-03-24T20:04:20.606ZMar 24 20:04:20.571 DEBG [sc] ox: starting
2142026-03-24T20:04:20.606ZMar 24 20:04:20.571 DEBG [sc] ox: connecting to [::1]:40627
2152026-03-24T20:04:20.606ZMar 24 20:04:20.571 DEBG [sc] ox waiting for prompt
2162026-03-24T20:04:20.631ZMar 24 20:04:20.596 INFO cr1: instance run
2172026-03-24T20:04:20.631ZMar 24 20:04:20.596 DEBG [sc] cr1: starting
2182026-03-24T20:04:20.631ZMar 24 20:04:20.596 DEBG [sc] cr1: connecting to [::1]:48101
2192026-03-24T20:04:20.631ZMar 24 20:04:20.597 DEBG [sc] cr1 waiting for prompt
2202026-03-24T20:04:37.683ZMar 24 20:04:37.671 DEBG [sc] cr1: logging in
2212026-03-24T20:04:38.075ZMar 24 20:04:38.064 INFO cr1: mounting /opt/cargo-bay
2222026-03-24T20:04:38.075ZMar 24 20:04:38.064 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-03-24T20:04:38.100ZMar 24 20:04:38.086 DEBG [sc] cr1: executing command `cd`
2242026-03-24T20:04:38.125ZMar 24 20:04:38.097 INFO cr1: finished mounting /opt/cargo-bay
2252026-03-24T20:04:38.125ZMar 24 20:04:38.097 DEBG [sc] cr1: executing command `hostname cr1`
2262026-03-24T20:04:38.125ZMar 24 20:04:38.108 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-03-24T20:04:38.149ZMar 24 20:04:38.119 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-03-24T20:04:38.149ZMar 24 20:04:38.130 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-03-24T20:04:38.173ZMar 24 20:04:38.141 INFO cr1: logging out
2302026-03-24T20:04:40.220ZMar 24 20:04:40.207 INFO cr1: logged out
2312026-03-24T20:04:41.195ZMar 24 20:04:41.183 DEBG [sc] ox: logging in
2322026-03-24T20:04:41.366ZMar 24 20:04:41.354 INFO ox: mounting /opt/cargo-bay
2332026-03-24T20:04:41.366ZMar 24 20:04:41.354 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-03-24T20:04:41.973ZMar 24 20:04:41.961 DEBG [sc] cr2: logging in
2352026-03-24T20:04:42.272ZMar 24 20:04:42.254 DEBG [sc] ox: executing command `cd`
2362026-03-24T20:04:42.296ZMar 24 20:04:42.265 INFO ox: finished mounting /opt/cargo-bay
2372026-03-24T20:04:42.296ZMar 24 20:04:42.265 DEBG [sc] ox: executing command `hostname ox`
2382026-03-24T20:04:42.296ZMar 24 20:04:42.276 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2392026-03-24T20:04:42.322ZMar 24 20:04:42.287 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2402026-03-24T20:04:42.322ZMar 24 20:04:42.298 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2412026-03-24T20:04:42.346ZMar 24 20:04:42.310 INFO ox: logging out
2422026-03-24T20:04:42.371ZMar 24 20:04:42.349 INFO cr2: mounting /opt/cargo-bay
2432026-03-24T20:04:42.371ZMar 24 20:04:42.349 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2442026-03-24T20:04:42.395ZMar 24 20:04:42.360 DEBG [sc] cr2: executing command `cd`
2452026-03-24T20:04:42.395ZMar 24 20:04:42.371 INFO cr2: finished mounting /opt/cargo-bay
2462026-03-24T20:04:42.395ZMar 24 20:04:42.371 DEBG [sc] cr2: executing command `hostname cr2`
2472026-03-24T20:04:42.395ZMar 24 20:04:42.375 INFO ox: logged out
2482026-03-24T20:04:42.395ZMar 24 20:04:42.381 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-03-24T20:04:42.421ZMar 24 20:04:42.393 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-03-24T20:04:42.421ZMar 24 20:04:42.404 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-03-24T20:04:42.445ZMar 24 20:04:42.415 INFO cr2: logging out
2522026-03-24T20:04:45.438ZMar 24 20:04:45.426 INFO cr2: logged out
2532026-03-24T20:04:45.438ZMar 24 20:04:45.426 DEBG [sc] ox: starting
2542026-03-24T20:04:45.438ZMar 24 20:04:45.426 DEBG [sc] ox: connecting to [::1]:40627
2552026-03-24T20:04:45.463ZMar 24 20:04:45.427 DEBG [sc] ox waiting for prompt
2562026-03-24T20:04:45.463ZMar 24 20:04:45.438 DEBG [sc] ox: logging in
2572026-03-24T20:04:45.538ZMar 24 20:04:45.527 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-03-24T20:04:52.060ZMar 24 20:04:52.048 DEBG [sc] ox: starting
2592026-03-24T20:04:52.061ZMar 24 20:04:52.048 DEBG [sc] ox: connecting to [::1]:40627
2602026-03-24T20:04:52.085ZMar 24 20:04:52.048 DEBG [sc] ox waiting for prompt
2612026-03-24T20:04:52.085ZMar 24 20:04:52.058 DEBG [sc] ox: logging in
2622026-03-24T20:04:52.147ZMar 24 20:04:52.135 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-03-24T20:04:52.236ZMar 24 20:04:52.224 DEBG [sc] ox: starting
2642026-03-24T20:04:52.236ZMar 24 20:04:52.224 DEBG [sc] ox: connecting to [::1]:40627
2652026-03-24T20:04:52.262ZMar 24 20:04:52.224 DEBG [sc] ox waiting for prompt
2662026-03-24T20:04:52.262ZMar 24 20:04:52.235 DEBG [sc] ox: logging in
2672026-03-24T20:04:52.335ZMar 24 20:04:52.323 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-03-24T20:04:52.400ZMar 24 20:04:52.389 INFO cr1: installing frr
2692026-03-24T20:04:52.400ZMar 24 20:04:52.389 INFO ox: setting up npuvm
2702026-03-24T20:04:52.400ZMar 24 20:04:52.389 INFO waiting for ceos to initialize
2712026-03-24T20:04:52.425ZMar 24 20:04:52.389 DEBG [sc] ox: starting
2722026-03-24T20:04:52.425ZMar 24 20:04:52.389 DEBG [sc] ox: connecting to [::1]:40627
2732026-03-24T20:04:52.425ZMar 24 20:04:52.389 DEBG [sc] cr1: starting
2742026-03-24T20:04:52.425ZMar 24 20:04:52.389 DEBG [sc] cr1: connecting to [::1]:48101
2752026-03-24T20:04:52.425ZMar 24 20:04:52.389 DEBG [sc] cr2: starting
2762026-03-24T20:04:52.425ZMar 24 20:04:52.389 DEBG [sc] cr2: connecting to [::1]:42383
2772026-03-24T20:04:52.425ZMar 24 20:04:52.390 DEBG [sc] ox waiting for prompt
2782026-03-24T20:04:52.425ZMar 24 20:04:52.390 DEBG [sc] cr2 waiting for prompt
2792026-03-24T20:04:52.425ZMar 24 20:04:52.390 DEBG [sc] cr1 waiting for prompt
2802026-03-24T20:04:52.425ZMar 24 20:04:52.400 DEBG [sc] ox: logging in
2812026-03-24T20:04:52.425ZMar 24 20:04:52.401 DEBG [sc] cr2: logging in
2822026-03-24T20:04:52.425ZMar 24 20:04:52.401 DEBG [sc] cr1: logging in
2832026-03-24T20:04:52.500ZMar 24 20:04:52.488 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-03-24T20:04:52.567ZMar 24 20:04:52.555 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-03-24T20:04:52.740ZMar 24 20:04:52.728 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-03-24T20:04:54.811ZMar 24 20:04:54.798 INFO cr2: executing eos script show version
2872026-03-24T20:04:54.811ZMar 24 20:04:54.798 DEBG [sc] cr2: starting
2882026-03-24T20:04:54.811ZMar 24 20:04:54.798 DEBG [sc] cr2: connecting to [::1]:42383
2892026-03-24T20:04:54.837ZMar 24 20:04:54.799 DEBG [sc] cr2 waiting for prompt
2902026-03-24T20:04:54.837ZMar 24 20:04:54.810 DEBG [sc] cr2: logging in
2912026-03-24T20:04:54.942ZMar 24 20:04:54.930 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-03-24T20:04:57.889ZMar 24 20:04:57.874 DEBG [sc] ox: starting
2932026-03-24T20:04:57.889ZMar 24 20:04:57.874 DEBG [sc] ox: connecting to [::1]:40627
2942026-03-24T20:04:57.915ZMar 24 20:04:57.875 DEBG [sc] ox waiting for prompt
2952026-03-24T20:04:57.915ZMar 24 20:04:57.885 DEBG [sc] ox: logging in
2962026-03-24T20:04:57.986ZMar 24 20:04:57.973 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-03-24T20:04:58.051ZMar 24 20:04:58.040 DEBG [sc] ox: starting
2982026-03-24T20:04:58.051ZMar 24 20:04:58.040 DEBG [sc] ox: connecting to [::1]:40627
2992026-03-24T20:04:58.076ZMar 24 20:04:58.040 DEBG [sc] ox waiting for prompt
3002026-03-24T20:04:58.076ZMar 24 20:04:58.051 DEBG [sc] ox: logging in
3012026-03-24T20:04:58.140ZMar 24 20:04:58.128 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-03-24T20:05:03.119ZMar 24 20:05:03.107 DEBG [sc] cr2: starting
3032026-03-24T20:05:03.119ZMar 24 20:05:03.107 DEBG [sc] cr2: connecting to [::1]:42383
3042026-03-24T20:05:03.143ZMar 24 20:05:03.107 DEBG [sc] cr2 waiting for prompt
3052026-03-24T20:05:03.143ZMar 24 20:05:03.118 DEBG [sc] cr2: logging in
3062026-03-24T20:05:03.273ZMar 24 20:05:03.261 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-03-24T20:05:05.403ZMar 24 20:05:05.391 INFO cr2: executing eos script show version
3082026-03-24T20:05:05.403ZMar 24 20:05:05.391 DEBG [sc] cr2: starting
3092026-03-24T20:05:05.403ZMar 24 20:05:05.391 DEBG [sc] cr2: connecting to [::1]:42383
3102026-03-24T20:05:05.428ZMar 24 20:05:05.392 DEBG [sc] cr2 waiting for prompt
3112026-03-24T20:05:05.428ZMar 24 20:05:05.402 DEBG [sc] cr2: logging in
3122026-03-24T20:05:05.581ZMar 24 20:05:05.568 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-03-24T20:05:07.922ZMar 24 20:05:07.910 DEBG [sc] cr2: starting
3142026-03-24T20:05:07.923ZMar 24 20:05:07.910 DEBG [sc] cr2: connecting to [::1]:42383
3152026-03-24T20:05:07.947ZMar 24 20:05:07.911 DEBG [sc] cr2 waiting for prompt
3162026-03-24T20:05:07.947ZMar 24 20:05:07.921 DEBG [sc] cr2: logging in
3172026-03-24T20:05:08.089ZMar 24 20:05:08.077 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-03-24T20:05:10.241ZMar 24 20:05:10.229 INFO cr2: executing eos script show version
3192026-03-24T20:05:10.241ZMar 24 20:05:10.229 DEBG [sc] cr2: starting
3202026-03-24T20:05:10.241ZMar 24 20:05:10.229 DEBG [sc] cr2: connecting to [::1]:42383
3212026-03-24T20:05:10.266ZMar 24 20:05:10.230 DEBG [sc] cr2 waiting for prompt
3222026-03-24T20:05:10.266ZMar 24 20:05:10.240 DEBG [sc] cr2: logging in
3232026-03-24T20:05:10.421ZMar 24 20:05:10.407 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-03-24T20:05:12.950ZMar 24 20:05:12.937 INFO cr2: executing eos script
3252026-03-24T20:05:12.950Z enable
3262026-03-24T20:05:12.950Z configure
3272026-03-24T20:05:12.950Z ipv6 unicast-routing
3282026-03-24T20:05:12.950Z ip routing ipv6 interfaces
3292026-03-24T20:05:12.950Z ip routing
3302026-03-24T20:05:12.950Z ip route 1.2.3.0/24 null0
3312026-03-24T20:05:12.950Z ipv6 route fd99::/64 null0
3322026-03-24T20:05:12.950Z interface et1
3332026-03-24T20:05:12.950Z no switchport
3342026-03-24T20:05:12.950Z ipv6 enable
3352026-03-24T20:05:12.950Z
3362026-03-24T20:05:12.950Z router bgp 45
3372026-03-24T20:05:12.950Z router-id 1.2.3.1
3382026-03-24T20:05:12.950Z no bgp default ipv4-unicast
3392026-03-24T20:05:12.950Z timers bgp 2 6
3402026-03-24T20:05:12.950Z neighbor ebgp peer group
3412026-03-24T20:05:12.950Z neighbor ebgp remote-as 33
3422026-03-24T20:05:12.950Z neighbor interface Et1 peer-group ebgp
3432026-03-24T20:05:12.950Z address-family ipv4
3442026-03-24T20:05:12.950Z neighbor ebgp activate
3452026-03-24T20:05:12.950Z neighbor ebgp next-hop address-family ipv6 originate
3462026-03-24T20:05:12.950Z network 1.2.3.0/24
3472026-03-24T20:05:12.950Z exit
3482026-03-24T20:05:12.950Z address-family ipv6
3492026-03-24T20:05:12.950Z neighbor ebgp activate
3502026-03-24T20:05:12.950Z neighbor ebgp next-hop address-family ipv6 originate
3512026-03-24T20:05:12.950Z network fd99::/64
3522026-03-24T20:05:12.950Z exit
3532026-03-24T20:05:12.950Z exit
3542026-03-24T20:05:12.950Z
3552026-03-24T20:05:12.950ZMar 24 20:05:12.937 DEBG [sc] cr2: starting
3562026-03-24T20:05:12.950ZMar 24 20:05:12.937 DEBG [sc] cr2: connecting to [::1]:42383
3572026-03-24T20:05:12.975ZMar 24 20:05:12.938 DEBG [sc] cr2 waiting for prompt
3582026-03-24T20:05:12.975ZMar 24 20:05:12.948 DEBG [sc] cr2: logging in
3592026-03-24T20:05:13.114ZMar 24 20:05:13.102 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-03-24T20:05:13.115Z enable
3612026-03-24T20:05:13.115Z configure
3622026-03-24T20:05:13.115Z ipv6 unicast-routing
3632026-03-24T20:05:13.115Z ip routing ipv6 interfaces
3642026-03-24T20:05:13.115Z ip routing
3652026-03-24T20:05:13.115Z ip route 1.2.3.0/24 null0
3662026-03-24T20:05:13.115Z ipv6 route fd99::/64 null0
3672026-03-24T20:05:13.115Z interface et1
3682026-03-24T20:05:13.115Z no switchport
3692026-03-24T20:05:13.115Z ipv6 enable
3702026-03-24T20:05:13.115Z
3712026-03-24T20:05:13.115Z router bgp 45
3722026-03-24T20:05:13.115Z router-id 1.2.3.1
3732026-03-24T20:05:13.115Z no bgp default ipv4-unicast
3742026-03-24T20:05:13.115Z timers bgp 2 6
3752026-03-24T20:05:13.115Z neighbor ebgp peer group
3762026-03-24T20:05:13.115Z neighbor ebgp remote-as 33
3772026-03-24T20:05:13.115Z neighbor interface Et1 peer-group ebgp
3782026-03-24T20:05:13.115Z address-family ipv4
3792026-03-24T20:05:13.115Z neighbor ebgp activate
3802026-03-24T20:05:13.115Z neighbor ebgp next-hop address-family ipv6 originate
3812026-03-24T20:05:13.115Z network 1.2.3.0/24
3822026-03-24T20:05:13.115Z exit
3832026-03-24T20:05:13.115Z address-family ipv6
3842026-03-24T20:05:13.115Z neighbor ebgp activate
3852026-03-24T20:05:13.115Z neighbor ebgp next-hop address-family ipv6 originate
3862026-03-24T20:05:13.115Z network fd99::/64
3872026-03-24T20:05:13.115Z exit
3882026-03-24T20:05:13.115Z exit
3892026-03-24T20:05:13.115Z '`
3902026-03-24T20:05:30.009ZMar 24 20:05:29.997 INFO cr1: enabling frr daemon bgpd
3912026-03-24T20:05:30.034ZMar 24 20:05:29.997 DEBG [sc] cr1: starting
3922026-03-24T20:05:30.034ZMar 24 20:05:29.997 DEBG [sc] cr1: connecting to [::1]:48101
3932026-03-24T20:05:30.034ZMar 24 20:05:29.998 DEBG [sc] cr1 waiting for prompt
3942026-03-24T20:05:30.034ZMar 24 20:05:30.008 DEBG [sc] cr1: logging in
3952026-03-24T20:05:30.154ZMar 24 20:05:30.142 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-03-24T20:05:32.225ZMar 24 20:05:32.213 DEBG [sc] cr1: starting
3972026-03-24T20:05:32.225ZMar 24 20:05:32.213 DEBG [sc] cr1: connecting to [::1]:48101
3982026-03-24T20:05:32.249ZMar 24 20:05:32.214 DEBG [sc] cr1 waiting for prompt
3992026-03-24T20:05:32.249ZMar 24 20:05:32.224 DEBG [sc] cr1: logging in
4002026-03-24T20:05:32.390ZMar 24 20:05:32.378 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-03-24T20:05:39.998ZMar 24 20:05:39.984 INFO cr1: executing frr script
4022026-03-24T20:05:39.998Z configure
4032026-03-24T20:05:39.998Z ip forwarding
4042026-03-24T20:05:39.998Z ipv6 forwarding
4052026-03-24T20:05:39.998Z ip route 1.2.3.0/24 null0
4062026-03-24T20:05:39.998Z ipv6 route fd99::/64 null0
4072026-03-24T20:05:39.998Z route-map PERMIT-ALL permit 10
4082026-03-24T20:05:39.998Z router bgp 44
4092026-03-24T20:05:39.998Z timers bgp 2 6
4102026-03-24T20:05:39.998Z neighbor enp0s8 interface remote-as external
4112026-03-24T20:05:39.999Z neighbor enp0s8 timers connect 1
4122026-03-24T20:05:39.999Z address-family ipv4 unicast
4132026-03-24T20:05:39.999Z network 1.2.3.0/24
4142026-03-24T20:05:39.999Z neighbor enp0s8 activate
4152026-03-24T20:05:40.030Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-03-24T20:05:40.030Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-03-24T20:05:40.030Z exit-address-family
4182026-03-24T20:05:40.030Z address-family ipv6 unicast
4192026-03-24T20:05:40.030Z network fd99::/64
4202026-03-24T20:05:40.030Z neighbor enp0s8 activate
4212026-03-24T20:05:40.030Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-03-24T20:05:40.030Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-03-24T20:05:40.030Z exit-address-family
4242026-03-24T20:05:40.030Z exit
4252026-03-24T20:05:40.030Z
4262026-03-24T20:05:40.030ZMar 24 20:05:39.984 DEBG [sc] cr1: starting
4272026-03-24T20:05:40.030ZMar 24 20:05:39.984 DEBG [sc] cr1: connecting to [::1]:48101
4282026-03-24T20:05:40.030ZMar 24 20:05:39.984 DEBG [sc] cr1 waiting for prompt
4292026-03-24T20:05:40.030ZMar 24 20:05:39.995 DEBG [sc] cr1: logging in
4302026-03-24T20:05:40.130ZMar 24 20:05:40.117 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-24T20:06:34.589ZMar 24 20:06:34.577 DEBG [sc] ox: starting
4322026-03-24T20:06:34.589ZMar 24 20:06:34.577 DEBG [sc] ox: connecting to [::1]:40627
4332026-03-24T20:06:34.589ZMar 24 20:06:34.577 DEBG [sc] ox waiting for prompt
4342026-03-24T20:06:34.614ZMar 24 20:06:34.587 DEBG [sc] ox: logging in
4352026-03-24T20:06:34.677ZMar 24 20:06:34.664 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-03-24T20:06:34.950ZMar 24 20:06:34.937 DEBG [sc] ox: starting
4372026-03-24T20:06:34.950ZMar 24 20:06:34.937 DEBG [sc] ox: connecting to [::1]:40627
4382026-03-24T20:06:34.974ZMar 24 20:06:34.938 DEBG [sc] ox waiting for prompt
4392026-03-24T20:06:34.974ZMar 24 20:06:34.949 DEBG [sc] ox: logging in
4402026-03-24T20:06:35.039ZMar 24 20:06:35.026 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-03-24T20:06:36.061ZMar 24 20:06:36.049 DEBG [sc] ox: starting
4422026-03-24T20:06:36.061ZMar 24 20:06:36.049 DEBG [sc] ox: connecting to [::1]:40627
4432026-03-24T20:06:36.085ZMar 24 20:06:36.049 DEBG [sc] ox waiting for prompt
4442026-03-24T20:06:36.085ZMar 24 20:06:36.049 DEBG [sc] ox: logging in
4452026-03-24T20:06:36.138ZMar 24 20:06:36.127 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4462026-03-24T20:06:36.194ZMar 24 20:06:36.182 DEBG [sc] ox: starting
4472026-03-24T20:06:36.194ZMar 24 20:06:36.182 DEBG [sc] ox: connecting to [::1]:40627
4482026-03-24T20:06:36.218ZMar 24 20:06:36.183 DEBG [sc] ox waiting for prompt
4492026-03-24T20:06:36.218ZMar 24 20:06:36.193 DEBG [sc] ox: logging in
4502026-03-24T20:06:36.283ZMar 24 20:06:36.271 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4512026-03-24T20:06:36.339ZMar 24 20:06:36.328 DEBG [sc] ox: starting
4522026-03-24T20:06:36.339ZMar 24 20:06:36.328 DEBG [sc] ox: connecting to [::1]:40627
4532026-03-24T20:06:36.364ZMar 24 20:06:36.328 DEBG [sc] ox waiting for prompt
4542026-03-24T20:06:36.364ZMar 24 20:06:36.339 DEBG [sc] ox: logging in
4552026-03-24T20:06:36.427ZMar 24 20:06:36.415 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4562026-03-24T20:06:36.483ZMar 24 20:06:36.471 DEBG [sc] ox: starting
4572026-03-24T20:06:36.483ZMar 24 20:06:36.471 DEBG [sc] ox: connecting to [::1]:40627
4582026-03-24T20:06:36.508ZMar 24 20:06:36.472 DEBG [sc] ox waiting for prompt
4592026-03-24T20:06:36.508ZMar 24 20:06:36.482 DEBG [sc] ox: logging in
4602026-03-24T20:06:36.572ZMar 24 20:06:36.560 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-03-24T20:06:36.628ZMar 24 20:06:36.615 DEBG [sc] ox: starting
4622026-03-24T20:06:36.628ZMar 24 20:06:36.615 DEBG [sc] ox: connecting to [::1]:40627
4632026-03-24T20:06:36.652ZMar 24 20:06:36.616 DEBG [sc] ox waiting for prompt
4642026-03-24T20:06:36.652ZMar 24 20:06:36.626 DEBG [sc] ox: logging in
4652026-03-24T20:06:36.715ZMar 24 20:06:36.703 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-03-24T20:06:36.770ZMar 24 20:06:36.758 DEBG [sc] ox: starting
4672026-03-24T20:06:36.770ZMar 24 20:06:36.758 DEBG [sc] ox: connecting to [::1]:40627
4682026-03-24T20:06:36.794ZMar 24 20:06:36.758 DEBG [sc] ox waiting for prompt
4692026-03-24T20:06:36.794ZMar 24 20:06:36.769 DEBG [sc] ox: logging in
4702026-03-24T20:06:36.868ZMar 24 20:06:36.846 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-03-24T20:06:36.912ZMar 24 20:06:36.901 DEBG [sc] ox: starting
4722026-03-24T20:06:36.913ZMar 24 20:06:36.901 DEBG [sc] ox: connecting to [::1]:40627
4732026-03-24T20:06:36.937ZMar 24 20:06:36.901 DEBG [sc] ox waiting for prompt
4742026-03-24T20:06:36.937ZMar 24 20:06:36.912 DEBG [sc] ox: logging in
4752026-03-24T20:06:37.002ZMar 24 20:06:36.989 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-03-24T20:06:37.057ZMar 24 20:06:37.046 DEBG [sc] ox: starting
4772026-03-24T20:06:37.058ZMar 24 20:06:37.046 DEBG [sc] ox: connecting to [::1]:40627
4782026-03-24T20:06:37.082ZMar 24 20:06:37.046 DEBG [sc] ox waiting for prompt
4792026-03-24T20:06:37.082ZMar 24 20:06:37.055 DEBG [sc] ox: logging in
4802026-03-24T20:06:37.147ZMar 24 20:06:37.134 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-03-24T20:06:37.215ZMar 24 20:06:37.203 INFO adding BGP router to mgd
4822026-03-24T20:06:39.236ZMar 24 20:06:39.223 INFO cr1: executing frr script show ip bgp json
4832026-03-24T20:06:39.236ZMar 24 20:06:39.223 DEBG [sc] cr1: starting
4842026-03-24T20:06:39.236ZMar 24 20:06:39.223 DEBG [sc] cr1: connecting to [::1]:48101
4852026-03-24T20:06:39.260ZMar 24 20:06:39.224 DEBG [sc] cr1 waiting for prompt
4862026-03-24T20:06:39.260ZMar 24 20:06:39.235 DEBG [sc] cr1: logging in
4872026-03-24T20:06:39.563ZMar 24 20:06:39.551 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-03-24T20:06:41.673ZMar 24 20:06:41.661 INFO cr1: executing frr script show bgp json
4892026-03-24T20:06:41.673ZMar 24 20:06:41.661 DEBG [sc] cr1: starting
4902026-03-24T20:06:41.673ZMar 24 20:06:41.661 DEBG [sc] cr1: connecting to [::1]:48101
4912026-03-24T20:06:41.698ZMar 24 20:06:41.662 DEBG [sc] cr1 waiting for prompt
4922026-03-24T20:06:41.698ZMar 24 20:06:41.672 DEBG [sc] cr1: logging in
4932026-03-24T20:06:41.816ZMar 24 20:06:41.804 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-03-24T20:06:43.937ZMar 24 20:06:43.925 INFO cr2: executing eos script show ip bgp | json
4952026-03-24T20:06:43.961ZMar 24 20:06:43.925 DEBG [sc] cr2: starting
4962026-03-24T20:06:43.962ZMar 24 20:06:43.925 DEBG [sc] cr2: connecting to [::1]:42383
4972026-03-24T20:06:43.962ZMar 24 20:06:43.926 DEBG [sc] cr2 waiting for prompt
4982026-03-24T20:06:43.962ZMar 24 20:06:43.937 DEBG [sc] cr2: logging in
4992026-03-24T20:06:44.289ZMar 24 20:06:44.277 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-03-24T20:06:46.690ZMar 24 20:06:46.678 INFO cr2: executing eos script show ipv6 bgp | json
5012026-03-24T20:06:46.714ZMar 24 20:06:46.678 DEBG [sc] cr2: starting
5022026-03-24T20:06:46.714ZMar 24 20:06:46.678 DEBG [sc] cr2: connecting to [::1]:42383
5032026-03-24T20:06:46.714ZMar 24 20:06:46.679 DEBG [sc] cr2 waiting for prompt
5042026-03-24T20:06:46.714ZMar 24 20:06:46.689 DEBG [sc] cr2: logging in
5052026-03-24T20:06:46.822ZMar 24 20:06:46.810 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-03-24T20:06:49.237ZMar 24 20:06:49.225 INFO trio bgp unnumbered test passed 🎉
5072026-03-24T20:06:49.237ZMar 24 20:06:49.225 INFO destroying runner for deployment mgtriou
5082026-03-24T20:06:49.237ZMar 24 20:06:49.225 INFO destroying deployment mgtriou
5092026-03-24T20:06:49.237ZMar 24 20:06:49.225 INFO destroying nodes
5102026-03-24T20:06:49.337ZMar 24 20:06:49.325 INFO destroying links
5112026-03-24T20:06:49.337ZMar 24 20:06:49.325 INFO destroying link mgtriou_ox_sn_vnic0
5122026-03-24T20:06:49.362ZMar 24 20:06:49.328 INFO destroying link mgtriou_ox_sn_sim0
5132026-03-24T20:06:49.362ZMar 24 20:06:49.329 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-03-24T20:06:50.344ZMar 24 20:06:50.332 INFO destroying link mgtriou_cr1_vn_sim0
5152026-03-24T20:06:50.369ZMar 24 20:06:50.334 INFO destroying link mgtriou_ox_sn_vnic1
5162026-03-24T20:06:50.369ZMar 24 20:06:50.335 INFO destroying link mgtriou_ox_sn_sim1
5172026-03-24T20:06:50.369ZMar 24 20:06:50.336 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-03-24T20:06:50.369ZMar 24 20:06:50.337 INFO destroying link mgtriou_cr2_vn_sim0
5192026-03-24T20:06:50.369ZMar 24 20:06:50.338 INFO destroying external links
5202026-03-24T20:06:50.369ZMar 24 20:06:50.338 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-03-24T20:06:50.369ZMar 24 20:06:50.340 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-03-24T20:06:50.369ZMar 24 20:06:50.341 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-03-24T20:06:50.369ZMar 24 20:06:50.342 INFO destroying images
5242026-03-24T20:06:50.814ZMar 24 20:06:50.802 INFO destroying workspace at .falcon
5252026-03-24T20:06:50.839Zprocess exited: duration 486502 ms, exit code 0
 
5262026-03-24T20:06:50.891Zfound 0 output files