01KMGRHHKE0SYC3PRKEWQFG6D0: falcon

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

Buildomat Job: 01KMGRJ87B2FB9R75GSHB1VJD6

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-03-24T20:46:11.180Zjob dependencies complete; ready to run (waiting for 17 m 22 s)
22026-03-24T21:32:48.617Zjob assigned to worker 01KMGW1NRE8N6RNN3DRHKDG14H [factory edgar, gimlet/BRM42220010/609] (queued for 46 m 37 s)
32026-03-24T21:32:49.041Zdownloading input: /input/build/work/debug/ddmadm
42026-03-24T21:33:11.971Zdownloaded input: /input/build/work/debug/ddmadm
52026-03-24T21:33:11.971Zdownloading input: /input/build/work/debug/ddmd
62026-03-24T21:33:38.970Zdownloaded input: /input/build/work/debug/ddmd
72026-03-24T21:33:38.970Zdownloading input: /input/build/work/debug/mgadm
82026-03-24T21:34:09.982Zdownloaded input: /input/build/work/debug/mgadm
92026-03-24T21:34:09.982Zdownloading input: /input/build/work/debug/mgd
102026-03-24T21:34:40.935Zdownloaded input: /input/build/work/debug/mgd
112026-03-24T21:34:40.959Zdownloading input: /input/build/work/release/ddmadm
122026-03-24T21:34:43.294Zdownloaded input: /input/build/work/release/ddmadm
132026-03-24T21:34:43.294Zdownloading input: /input/build/work/release/ddmd
142026-03-24T21:34:46.064Zdownloaded input: /input/build/work/release/ddmd
152026-03-24T21:34:46.090Zdownloading input: /input/build-interop/work/testbed.tar.gz
162026-03-24T21:34:54.685Zdownloaded input: /input/build-interop/work/testbed.tar.gz
172026-03-24T21:34:54.686Zdownloading input: /input/build/work/release/falcon-lab
182026-03-24T21:34:56.414Zdownloaded input: /input/build/work/release/falcon-lab
192026-03-24T21:34:56.414Zdownloading input: /input/build-interop/work/dhcp-server
202026-03-24T21:34:58.836Zdownloaded input: /input/build-interop/work/dhcp-server
212026-03-24T21:34:58.837Zdownloading input: /input/build/work/release/mgadm
222026-03-24T21:35:01.576Zdownloaded input: /input/build/work/release/mgadm
232026-03-24T21:35:01.576Zdownloading input: /input/build/work/release/mgd
242026-03-24T21:35:05.218Zdownloaded input: /input/build/work/release/mgd
 
252026-03-24T21:35:05.218Zstarting task 0: "setup"
262026-03-24T21:35:05.244Z++ uname -s
272026-03-24T21:35:05.244Z+ kern=SunOS
282026-03-24T21:35:05.244Z+ build_user=build
292026-03-24T21:35:05.244Z+ build_uid=12345
302026-03-24T21:35:05.244Z+ work_dir=/work
312026-03-24T21:35:05.244Z+ input_dir=/input
322026-03-24T21:35:05.244Z+ [[ 0 == 12345 ]]
332026-03-24T21:35:05.244Z+ case "$kern" in
342026-03-24T21:35:05.244Z+ groupadd -g 12345 build
352026-03-24T21:35:05.269Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-03-24T21:35:07.254Z+ zfs create -o mountpoint=/work rpool/work
372026-03-24T21:35:08.170Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-03-24T21:35:08.195Z+ home_fs=
392026-03-24T21:35:08.195Z+ [[ '' == autofs ]]
402026-03-24T21:35:08.195Z+ mkdir -p /home/build
412026-03-24T21:35:08.195Z+ chown build:build /home/build /work
422026-03-24T21:35:09.183Z+ chmod 0700 /home/build /work
432026-03-24T21:35:09.208Zprocess exited: duration 3968 ms, exit code 0
 
442026-03-24T21:35:09.257Zstarting task 1: "authentication"
452026-03-24T21:35:09.355Zprocess exited: duration 95 ms, exit code 0
 
462026-03-24T21:35:09.405Zstarting task 2: "build"
472026-03-24T21:35:09.430Z+ set -e
482026-03-24T21:35:09.430Z+ banner zpool
492026-03-24T21:35:09.430Z
502026-03-24T21:35:09.430Z ###### ##### #### #### #
512026-03-24T21:35:09.430Z # # # # # # # #
522026-03-24T21:35:09.430Z # # # # # # # #
532026-03-24T21:35:09.430Z # ##### # # # # #
542026-03-24T21:35:09.430Z # # # # # # #
552026-03-24T21:35:09.430Z ###### # #### #### ######
562026-03-24T21:35:09.430Z
572026-03-24T21:35:09.430Z++ pfexec diskinfo -pH
582026-03-24T21:35:09.455Z++ sort -k8 -n -r
592026-03-24T21:35:09.455Z++ head -1
602026-03-24T21:35:09.455Z++ awk '{print $2}'
612026-03-24T21:35:09.479Z+ DISK=c9t0014EE81000BC3B1d0
622026-03-24T21:35:09.479Z+ export DISK
632026-03-24T21:35:09.480Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
642026-03-24T21:35:09.545Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-03-24T21:35:09.573Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-03-24T21:35:09.711Z+ [[ true =~ true ]]
672026-03-24T21:35:09.711Z+ pfexec zpool trim cpool
682026-03-24T21:35:09.736Z++ zpool status -t cpool
692026-03-24T21:35:09.761Z+ [[ ! pool: cpool
702026-03-24T21:35:09.761Z state: ONLINE
712026-03-24T21:35:09.761Z scan: none requested
722026-03-24T21:35:09.761Zconfig:
732026-03-24T21:35:09.761Z
742026-03-24T21:35:09.761Z NAME STATE READ WRITE CKSUM
752026-03-24T21:35:09.761Z cpool ONLINE 0 0 0
762026-03-24T21:35:09.761Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at March 24, 2026 at 09:35:09 PM UTC)
772026-03-24T21:35:09.761Z
782026-03-24T21:35:09.761Zerrors: No known data errors =~ 100% ]]
792026-03-24T21:35:09.761Z+ sleep 10
802026-03-24T21:35:19.747Z++ zpool status -t cpool
812026-03-24T21:35:19.772Z+ [[ ! pool: cpool
822026-03-24T21:35:19.772Z state: ONLINE
832026-03-24T21:35:19.772Z scan: none requested
842026-03-24T21:35:19.772Zconfig:
852026-03-24T21:35:19.772Z
862026-03-24T21:35:19.772Z NAME STATE READ WRITE CKSUM
872026-03-24T21:35:19.772Z cpool ONLINE 0 0 0
882026-03-24T21:35:19.772Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (68% trimmed, started at March 24, 2026 at 09:35:09 PM UTC)
892026-03-24T21:35:19.772Z
902026-03-24T21:35:19.772Zerrors: No known data errors =~ 100% ]]
912026-03-24T21:35:19.772Z+ sleep 10
922026-03-24T21:35:29.761Z++ zpool status -t cpool
932026-03-24T21:35:29.786Z+ [[ ! pool: cpool
942026-03-24T21:35:29.786Z state: ONLINE
952026-03-24T21:35:29.786Z scan: none requested
962026-03-24T21:35:29.786Zconfig:
972026-03-24T21:35:29.786Z
982026-03-24T21:35:29.786Z NAME STATE READ WRITE CKSUM
992026-03-24T21:35:29.786Z cpool ONLINE 0 0 0
1002026-03-24T21:35:29.786Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at March 24, 2026 at 09:35:24 PM UTC)
1012026-03-24T21:35:29.786Z
1022026-03-24T21:35:29.786Zerrors: No known data errors =~ 100% ]]
1032026-03-24T21:35:29.786Z+ pfexec chown 12345 /ci
1042026-03-24T21:35:29.786Z+ cd /ci
1052026-03-24T21:35:29.786Z+ export FALCON_DATASET=cpool/falcon
1062026-03-24T21:35:29.786Z+ FALCON_DATASET=cpool/falcon
1072026-03-24T21:35:29.786Z+ banner setup
1082026-03-24T21:35:29.786Z
1092026-03-24T21:35:29.786Z #### ###### ##### # # #####
1102026-03-24T21:35:29.786Z # # # # # # #
1112026-03-24T21:35:29.786Z #### ##### # # # # #
1122026-03-24T21:35:29.787Z # # # # # #####
1132026-03-24T21:35:29.787Z # # # # # # #
1142026-03-24T21:35:29.787Z #### ###### # #### #
1152026-03-24T21:35:29.787Z
1162026-03-24T21:35:29.787Z+ cp /input/build-interop/work/dhcp-server .
1172026-03-24T21:35:29.816Z+ cp /input/build/work/release/falcon-lab .
1182026-03-24T21:35:29.853Z+ cp /input/build/work/release/mgd .
1192026-03-24T21:35:29.924Z+ cp /input/build/work/release/ddmd .
1202026-03-24T21:35:30.006Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-03-24T21:35:30.031Z+ mkdir -p cargo-bay
1222026-03-24T21:35:30.031Z+ mv mgd cargo-bay/
1232026-03-24T21:35:30.031Z+ mv ddmd cargo-bay/
1242026-03-24T21:35:30.031Z+ export EXT_INTERFACE=igb0
1252026-03-24T21:35:30.031Z+ EXT_INTERFACE=igb0
1262026-03-24T21:35:30.031Z++ bmat address ls -f extra -Ho first
1272026-03-24T21:35:30.056Z+ first=10.151.6.164
1282026-03-24T21:35:30.056Z++ bmat address ls -f extra -Ho last
1292026-03-24T21:35:30.082Z+ last=10.151.6.227
1302026-03-24T21:35:30.082Z++ bmat address ls -f extra -Ho gateway
1312026-03-24T21:35:30.107Z+ gw=10.151.6.1
1322026-03-24T21:35:30.107Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-03-24T21:35:30.107Z++ sed 's#/.*##g'
1342026-03-24T21:35:30.107Z+ server=10.151.6.100
1352026-03-24T21:35:30.132Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1362026-03-24T21:35:30.132Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-03-24T21:35:30.132Z+ error=
1382026-03-24T21:35:30.132Z+ RUST_LOG=debug
1392026-03-24T21:35:30.132Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-03-24T21:35:30.157ZMar 24 21:35:30.118 DEBG using default route interface igb0
1412026-03-24T21:35:30.157ZMar 24 21:35:30.118 DEBG using default route interface igb0
1422026-03-24T21:35:30.157ZMar 24 21:35:30.118 DEBG using default route interface igb0
1432026-03-24T21:35:30.157ZMar 24 21:35:30.118 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-03-24T21:35:30.160ZMar 24 21:35:30.118 INFO starting preflight for deployment mgtriou
1452026-03-24T21:35:30.160ZMar 24 21:35:30.118 INFO propolis-server binary not found
1462026-03-24T21:35:30.160ZMar 24 21:35:30.118 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-03-24T21:35:33.896ZMar 24 21:35:33.876 INFO ovmf fd not found
1482026-03-24T21:35:33.896ZMar 24 21:35:33.876 INFO downloading ovmf
1492026-03-24T21:35:34.250ZMar 24 21:35:34.230 INFO base image for helios-2.9 does not exist, attempting to install
1502026-03-24T21:35:34.250ZMar 24 21:35:34.230 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-03-24T21:36:09.990ZMar 24 21:36:09.965 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-03-24T21:36:43.922ZMar 24 21:36:43.895 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-03-24T21:36:43.974ZMar 24 21:36:43.948 INFO copying image data to zvol
1542026-03-24T21:36:54.280ZMar 24 21:36:54.252 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-03-24T21:36:57.674ZMar 24 21:36:57.647 INFO base image for debian-13.2 does not exist, attempting to install
1562026-03-24T21:36:57.674ZMar 24 21:36:57.647 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-03-24T21:37:19.670ZMar 24 21:37:19.645 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-03-24T21:37:40.634ZMar 24 21:37:40.609 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-03-24T21:37:40.659ZMar 24 21:37:40.637 INFO copying image data to zvol
1602026-03-24T21:37:48.921ZMar 24 21:37:48.900 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-03-24T21:37:51.991ZMar 24 21:37:51.970 INFO base image for eos-4.35 does not exist, attempting to install
1622026-03-24T21:37:51.991ZMar 24 21:37:51.970 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-03-24T21:38:51.973ZMar 24 21:38:51.962 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-03-24T21:40:15.530ZMar 24 21:40:15.520 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-03-24T21:40:15.556ZMar 24 21:40:15.546 INFO copying image data to zvol
1662026-03-24T21:41:03.077ZMar 24 21:41:03.065 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-03-24T21:41:06.488ZMar 24 21:41:06.478 INFO creating links
1682026-03-24T21:41:06.488ZMar 24 21:41:06.478 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-03-24T21:41:06.489ZMar 24 21:41:06.478 DEBG destroying link mgtriou_ox_sn_sim0
1702026-03-24T21:41:06.489ZMar 24 21:41:06.478 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-03-24T21:41:06.514ZMar 24 21:41:06.480 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-03-24T21:41:06.514ZMar 24 21:41:06.488 DEBG link pair created
1732026-03-24T21:41:06.514ZMar 24 21:41:06.488 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-03-24T21:41:06.514ZMar 24 21:41:06.488 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-03-24T21:41:06.514ZMar 24 21:41:06.488 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-03-24T21:41:06.514ZMar 24 21:41:06.489 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-03-24T21:41:06.514ZMar 24 21:41:06.496 DEBG link pair created
1782026-03-24T21:41:06.514ZMar 24 21:41:06.496 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-03-24T21:41:06.514ZMar 24 21:41:06.496 DEBG destroying link mgtriou_ox_sn_sim1
1802026-03-24T21:41:06.514ZMar 24 21:41:06.496 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-03-24T21:41:06.514ZMar 24 21:41:06.498 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-03-24T21:41:06.539ZMar 24 21:41:06.504 DEBG link pair created
1832026-03-24T21:41:06.539ZMar 24 21:41:06.504 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-03-24T21:41:06.539ZMar 24 21:41:06.504 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-03-24T21:41:06.539ZMar 24 21:41:06.504 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-03-24T21:41:06.539ZMar 24 21:41:06.505 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-03-24T21:41:06.539ZMar 24 21:41:06.512 DEBG link pair created
1882026-03-24T21:41:06.539ZMar 24 21:41:06.512 INFO creating external links
1892026-03-24T21:41:06.539ZMar 24 21:41:06.512 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-03-24T21:41:06.539ZMar 24 21:41:06.512 INFO creating external link mgtriou_ox_vn_vnic2
1912026-03-24T21:41:06.539ZMar 24 21:41:06.513 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-03-24T21:41:06.539ZMar 24 21:41:06.513 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-03-24T21:41:06.539ZMar 24 21:41:06.513 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-03-24T21:41:06.539ZMar 24 21:41:06.514 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-03-24T21:41:06.539ZMar 24 21:41:06.514 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-03-24T21:41:06.539ZMar 24 21:41:06.514 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-03-24T21:41:06.539ZMar 24 21:41:06.516 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-03-24T21:41:06.539ZMar 24 21:41:06.516 INFO creating nodes
1992026-03-24T21:41:06.539ZMar 24 21:41:06.516 INFO ox: launching node
2002026-03-24T21:41:06.539ZMar 24 21:41:06.519 INFO cr1: launching node
2012026-03-24T21:41:06.539ZMar 24 21:41:06.520 INFO cr2: launching node
2022026-03-24T21:41:06.565ZMar 24 21:41:06.534 INFO launched instance ox with pid 854 on port 42407
2032026-03-24T21:41:06.565ZMar 24 21:41:06.534 INFO ox: instance ensure
2042026-03-24T21:41:06.565ZMar 24 21:41:06.535 INFO launched instance cr1 with pid 855 on port 32939
2052026-03-24T21:41:06.565ZMar 24 21:41:06.535 INFO cr1: instance ensure
2062026-03-24T21:41:06.565ZMar 24 21:41:06.535 INFO launched instance cr2 with pid 856 on port 50893
2072026-03-24T21:41:06.565ZMar 24 21:41:06.535 INFO cr2: instance ensure
2082026-03-24T21:41:09.152ZMar 24 21:41:09.143 INFO ox: instance run
2092026-03-24T21:41:09.177ZMar 24 21:41:09.143 DEBG [sc] ox: starting
2102026-03-24T21:41:09.177ZMar 24 21:41:09.143 DEBG [sc] ox: connecting to [::1]:42407
2112026-03-24T21:41:09.178ZMar 24 21:41:09.145 DEBG [sc] ox waiting for prompt
2122026-03-24T21:41:09.178ZMar 24 21:41:09.147 INFO cr2: instance run
2132026-03-24T21:41:09.178ZMar 24 21:41:09.147 DEBG [sc] cr2: starting
2142026-03-24T21:41:09.178ZMar 24 21:41:09.147 DEBG [sc] cr2: connecting to [::1]:50893
2152026-03-24T21:41:09.178ZMar 24 21:41:09.148 DEBG [sc] cr2 waiting for prompt
2162026-03-24T21:41:09.178ZMar 24 21:41:09.155 INFO cr1: instance run
2172026-03-24T21:41:09.178ZMar 24 21:41:09.156 DEBG [sc] cr1: starting
2182026-03-24T21:41:09.178ZMar 24 21:41:09.156 DEBG [sc] cr1: connecting to [::1]:32939
2192026-03-24T21:41:09.178ZMar 24 21:41:09.157 DEBG [sc] cr1 waiting for prompt
2202026-03-24T21:41:26.201ZMar 24 21:41:26.191 DEBG [sc] cr1: logging in
2212026-03-24T21:41:26.579ZMar 24 21:41:26.569 INFO cr1: mounting /opt/cargo-bay
2222026-03-24T21:41:26.580ZMar 24 21:41:26.569 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-03-24T21:41:26.604ZMar 24 21:41:26.592 DEBG [sc] cr1: executing command `cd`
2242026-03-24T21:41:26.629ZMar 24 21:41:26.603 INFO cr1: finished mounting /opt/cargo-bay
2252026-03-24T21:41:26.629ZMar 24 21:41:26.603 DEBG [sc] cr1: executing command `hostname cr1`
2262026-03-24T21:41:26.629ZMar 24 21:41:26.614 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-03-24T21:41:26.654ZMar 24 21:41:26.625 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-03-24T21:41:26.654ZMar 24 21:41:26.636 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-03-24T21:41:26.678ZMar 24 21:41:26.646 INFO cr1: logging out
2302026-03-24T21:41:28.721ZMar 24 21:41:28.711 INFO cr1: logged out
2312026-03-24T21:41:29.556ZMar 24 21:41:29.546 DEBG [sc] ox: logging in
2322026-03-24T21:41:29.723ZMar 24 21:41:29.713 INFO ox: mounting /opt/cargo-bay
2332026-03-24T21:41:29.723ZMar 24 21:41:29.713 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-03-24T21:41:30.799ZMar 24 21:41:30.788 DEBG [sc] cr2: logging in
2352026-03-24T21:41:31.066ZMar 24 21:41:31.056 DEBG [sc] ox: executing command `cd`
2362026-03-24T21:41:31.090ZMar 24 21:41:31.067 INFO ox: finished mounting /opt/cargo-bay
2372026-03-24T21:41:31.090ZMar 24 21:41:31.067 DEBG [sc] ox: executing command `hostname ox`
2382026-03-24T21:41:31.090ZMar 24 21:41:31.078 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2392026-03-24T21:41:31.115ZMar 24 21:41:31.089 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2402026-03-24T21:41:31.115ZMar 24 21:41:31.099 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2412026-03-24T21:41:31.139ZMar 24 21:41:31.110 INFO ox: logging out
2422026-03-24T21:41:31.187ZMar 24 21:41:31.177 INFO ox: logged out
2432026-03-24T21:41:31.212ZMar 24 21:41:31.202 INFO cr2: mounting /opt/cargo-bay
2442026-03-24T21:41:31.212ZMar 24 21:41:31.202 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-03-24T21:41:31.237ZMar 24 21:41:31.224 DEBG [sc] cr2: executing command `cd`
2462026-03-24T21:41:31.261ZMar 24 21:41:31.235 INFO cr2: finished mounting /opt/cargo-bay
2472026-03-24T21:41:31.261ZMar 24 21:41:31.235 DEBG [sc] cr2: executing command `hostname cr2`
2482026-03-24T21:41:31.261ZMar 24 21:41:31.246 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-03-24T21:41:31.286ZMar 24 21:41:31.257 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-03-24T21:41:31.286ZMar 24 21:41:31.268 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-03-24T21:41:31.311ZMar 24 21:41:31.280 INFO cr2: logging out
2522026-03-24T21:41:34.276ZMar 24 21:41:34.265 INFO cr2: logged out
2532026-03-24T21:41:34.300ZMar 24 21:41:34.266 DEBG [sc] ox: starting
2542026-03-24T21:41:34.300ZMar 24 21:41:34.266 DEBG [sc] ox: connecting to [::1]:42407
2552026-03-24T21:41:34.300ZMar 24 21:41:34.266 DEBG [sc] ox waiting for prompt
2562026-03-24T21:41:34.300ZMar 24 21:41:34.278 DEBG [sc] ox: logging in
2572026-03-24T21:41:34.377ZMar 24 21:41:34.367 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-03-24T21:41:40.971ZMar 24 21:41:40.961 DEBG [sc] ox: starting
2592026-03-24T21:41:40.971ZMar 24 21:41:40.961 DEBG [sc] ox: connecting to [::1]:42407
2602026-03-24T21:41:40.995ZMar 24 21:41:40.962 DEBG [sc] ox waiting for prompt
2612026-03-24T21:41:40.996ZMar 24 21:41:40.972 DEBG [sc] ox: logging in
2622026-03-24T21:41:41.059ZMar 24 21:41:41.049 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-03-24T21:41:41.147ZMar 24 21:41:41.137 DEBG [sc] ox: starting
2642026-03-24T21:41:41.147ZMar 24 21:41:41.137 DEBG [sc] ox: connecting to [::1]:42407
2652026-03-24T21:41:41.172ZMar 24 21:41:41.138 DEBG [sc] ox waiting for prompt
2662026-03-24T21:41:41.172ZMar 24 21:41:41.148 DEBG [sc] ox: logging in
2672026-03-24T21:41:41.247ZMar 24 21:41:41.237 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-03-24T21:41:41.314ZMar 24 21:41:41.303 INFO cr1: installing frr
2692026-03-24T21:41:41.314ZMar 24 21:41:41.303 INFO waiting for ceos to initialize
2702026-03-24T21:41:41.339ZMar 24 21:41:41.303 INFO ox: setting up npuvm
2712026-03-24T21:41:41.339ZMar 24 21:41:41.304 DEBG [sc] cr2: starting
2722026-03-24T21:41:41.339ZMar 24 21:41:41.304 DEBG [sc] cr2: connecting to [::1]:50893
2732026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] cr1: starting
2742026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] cr1: connecting to [::1]:32939
2752026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] ox: starting
2762026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] ox: connecting to [::1]:42407
2772026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] cr2 waiting for prompt
2782026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] cr1 waiting for prompt
2792026-03-24T21:41:41.340ZMar 24 21:41:41.304 DEBG [sc] ox waiting for prompt
2802026-03-24T21:41:41.340ZMar 24 21:41:41.313 DEBG [sc] ox: logging in
2812026-03-24T21:41:41.340ZMar 24 21:41:41.315 DEBG [sc] cr2: logging in
2822026-03-24T21:41:41.340ZMar 24 21:41:41.315 DEBG [sc] cr1: logging in
2832026-03-24T21:41:41.401ZMar 24 21:41:41.390 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-03-24T21:41:41.458ZMar 24 21:41:41.447 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-03-24T21:41:41.680ZMar 24 21:41:41.670 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-03-24T21:41:43.684ZMar 24 21:41:43.673 INFO cr2: executing eos script show version
2872026-03-24T21:41:43.684ZMar 24 21:41:43.673 DEBG [sc] cr2: starting
2882026-03-24T21:41:43.684ZMar 24 21:41:43.673 DEBG [sc] cr2: connecting to [::1]:50893
2892026-03-24T21:41:43.711ZMar 24 21:41:43.673 DEBG [sc] cr2 waiting for prompt
2902026-03-24T21:41:43.711ZMar 24 21:41:43.683 DEBG [sc] cr2: logging in
2912026-03-24T21:41:43.859ZMar 24 21:41:43.849 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-03-24T21:41:46.886ZMar 24 21:41:46.875 DEBG [sc] ox: starting
2932026-03-24T21:41:46.886ZMar 24 21:41:46.876 DEBG [sc] ox: connecting to [::1]:42407
2942026-03-24T21:41:46.911ZMar 24 21:41:46.876 DEBG [sc] ox waiting for prompt
2952026-03-24T21:41:46.911ZMar 24 21:41:46.886 DEBG [sc] ox: logging in
2962026-03-24T21:41:46.973ZMar 24 21:41:46.963 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-03-24T21:41:47.040ZMar 24 21:41:47.030 DEBG [sc] ox: starting
2982026-03-24T21:41:47.040ZMar 24 21:41:47.030 DEBG [sc] ox: connecting to [::1]:42407
2992026-03-24T21:41:47.066ZMar 24 21:41:47.030 DEBG [sc] ox waiting for prompt
3002026-03-24T21:41:47.066ZMar 24 21:41:47.040 DEBG [sc] ox: logging in
3012026-03-24T21:41:47.138ZMar 24 21:41:47.128 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-03-24T21:41:51.663ZMar 24 21:41:51.652 DEBG [sc] cr2: starting
3032026-03-24T21:41:51.663ZMar 24 21:41:51.652 DEBG [sc] cr2: connecting to [::1]:50893
3042026-03-24T21:41:51.688ZMar 24 21:41:51.653 DEBG [sc] cr2 waiting for prompt
3052026-03-24T21:41:51.688ZMar 24 21:41:51.663 DEBG [sc] cr2: logging in
3062026-03-24T21:41:51.816ZMar 24 21:41:51.805 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-03-24T21:41:53.926ZMar 24 21:41:53.915 INFO cr2: executing eos script show version
3082026-03-24T21:41:53.926ZMar 24 21:41:53.915 DEBG [sc] cr2: starting
3092026-03-24T21:41:53.926ZMar 24 21:41:53.915 DEBG [sc] cr2: connecting to [::1]:50893
3102026-03-24T21:41:53.951ZMar 24 21:41:53.916 DEBG [sc] cr2 waiting for prompt
3112026-03-24T21:41:53.951ZMar 24 21:41:53.927 DEBG [sc] cr2: logging in
3122026-03-24T21:41:54.092ZMar 24 21:41:54.081 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-03-24T21:41:56.504ZMar 24 21:41:56.493 DEBG [sc] cr2: starting
3142026-03-24T21:41:56.504ZMar 24 21:41:56.493 DEBG [sc] cr2: connecting to [::1]:50893
3152026-03-24T21:41:56.529ZMar 24 21:41:56.493 DEBG [sc] cr2 waiting for prompt
3162026-03-24T21:41:56.530ZMar 24 21:41:56.504 DEBG [sc] cr2: logging in
3172026-03-24T21:41:56.668ZMar 24 21:41:56.658 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-03-24T21:41:58.847ZMar 24 21:41:58.837 INFO cr2: executing eos script show version
3192026-03-24T21:41:58.848ZMar 24 21:41:58.837 DEBG [sc] cr2: starting
3202026-03-24T21:41:58.848ZMar 24 21:41:58.837 DEBG [sc] cr2: connecting to [::1]:50893
3212026-03-24T21:41:58.873ZMar 24 21:41:58.838 DEBG [sc] cr2 waiting for prompt
3222026-03-24T21:41:58.873ZMar 24 21:41:58.848 DEBG [sc] cr2: logging in
3232026-03-24T21:41:59.037ZMar 24 21:41:59.003 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-03-24T21:42:01.657ZMar 24 21:42:01.646 INFO cr2: executing eos script
3252026-03-24T21:42:01.657Z enable
3262026-03-24T21:42:01.657Z configure
3272026-03-24T21:42:01.657Z ipv6 unicast-routing
3282026-03-24T21:42:01.657Z ip routing ipv6 interfaces
3292026-03-24T21:42:01.657Z ip routing
3302026-03-24T21:42:01.657Z ip route 1.2.3.0/24 null0
3312026-03-24T21:42:01.657Z ipv6 route fd99::/64 null0
3322026-03-24T21:42:01.657Z interface et1
3332026-03-24T21:42:01.657Z no switchport
3342026-03-24T21:42:01.657Z ipv6 enable
3352026-03-24T21:42:01.657Z
3362026-03-24T21:42:01.657Z router bgp 45
3372026-03-24T21:42:01.658Z router-id 1.2.3.1
3382026-03-24T21:42:01.658Z no bgp default ipv4-unicast
3392026-03-24T21:42:01.658Z timers bgp 2 6
3402026-03-24T21:42:01.658Z neighbor ebgp peer group
3412026-03-24T21:42:01.658Z neighbor ebgp remote-as 33
3422026-03-24T21:42:01.658Z neighbor interface Et1 peer-group ebgp
3432026-03-24T21:42:01.658Z address-family ipv4
3442026-03-24T21:42:01.658Z neighbor ebgp activate
3452026-03-24T21:42:01.658Z neighbor ebgp next-hop address-family ipv6 originate
3462026-03-24T21:42:01.658Z network 1.2.3.0/24
3472026-03-24T21:42:01.658Z exit
3482026-03-24T21:42:01.658Z address-family ipv6
3492026-03-24T21:42:01.658Z neighbor ebgp activate
3502026-03-24T21:42:01.658Z neighbor ebgp next-hop address-family ipv6 originate
3512026-03-24T21:42:01.658Z network fd99::/64
3522026-03-24T21:42:01.658Z exit
3532026-03-24T21:42:01.658Z exit
3542026-03-24T21:42:01.658Z
3552026-03-24T21:42:01.658ZMar 24 21:42:01.646 DEBG [sc] cr2: starting
3562026-03-24T21:42:01.658ZMar 24 21:42:01.646 DEBG [sc] cr2: connecting to [::1]:50893
3572026-03-24T21:42:01.683ZMar 24 21:42:01.647 DEBG [sc] cr2 waiting for prompt
3582026-03-24T21:42:01.683ZMar 24 21:42:01.658 DEBG [sc] cr2: logging in
3592026-03-24T21:42:01.812ZMar 24 21:42:01.801 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-03-24T21:42:01.812Z enable
3612026-03-24T21:42:01.812Z configure
3622026-03-24T21:42:01.812Z ipv6 unicast-routing
3632026-03-24T21:42:01.812Z ip routing ipv6 interfaces
3642026-03-24T21:42:01.812Z ip routing
3652026-03-24T21:42:01.812Z ip route 1.2.3.0/24 null0
3662026-03-24T21:42:01.812Z ipv6 route fd99::/64 null0
3672026-03-24T21:42:01.812Z interface et1
3682026-03-24T21:42:01.812Z no switchport
3692026-03-24T21:42:01.812Z ipv6 enable
3702026-03-24T21:42:01.812Z
3712026-03-24T21:42:01.812Z router bgp 45
3722026-03-24T21:42:01.812Z router-id 1.2.3.1
3732026-03-24T21:42:01.812Z no bgp default ipv4-unicast
3742026-03-24T21:42:01.812Z timers bgp 2 6
3752026-03-24T21:42:01.812Z neighbor ebgp peer group
3762026-03-24T21:42:01.812Z neighbor ebgp remote-as 33
3772026-03-24T21:42:01.812Z neighbor interface Et1 peer-group ebgp
3782026-03-24T21:42:01.812Z address-family ipv4
3792026-03-24T21:42:01.812Z neighbor ebgp activate
3802026-03-24T21:42:01.812Z neighbor ebgp next-hop address-family ipv6 originate
3812026-03-24T21:42:01.812Z network 1.2.3.0/24
3822026-03-24T21:42:01.812Z exit
3832026-03-24T21:42:01.812Z address-family ipv6
3842026-03-24T21:42:01.812Z neighbor ebgp activate
3852026-03-24T21:42:01.812Z neighbor ebgp next-hop address-family ipv6 originate
3862026-03-24T21:42:01.812Z network fd99::/64
3872026-03-24T21:42:01.812Z exit
3882026-03-24T21:42:01.813Z exit
3892026-03-24T21:42:01.813Z '`
3902026-03-24T21:42:19.418ZMar 24 21:42:19.407 INFO cr1: enabling frr daemon bgpd
3912026-03-24T21:42:19.418ZMar 24 21:42:19.407 DEBG [sc] cr1: starting
3922026-03-24T21:42:19.418ZMar 24 21:42:19.407 DEBG [sc] cr1: connecting to [::1]:32939
3932026-03-24T21:42:19.443ZMar 24 21:42:19.408 DEBG [sc] cr1 waiting for prompt
3942026-03-24T21:42:19.443ZMar 24 21:42:19.418 DEBG [sc] cr1: logging in
3952026-03-24T21:42:19.573ZMar 24 21:42:19.562 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-03-24T21:42:21.650ZMar 24 21:42:21.640 DEBG [sc] cr1: starting
3972026-03-24T21:42:21.650ZMar 24 21:42:21.640 DEBG [sc] cr1: connecting to [::1]:32939
3982026-03-24T21:42:21.676ZMar 24 21:42:21.640 DEBG [sc] cr1 waiting for prompt
3992026-03-24T21:42:21.676ZMar 24 21:42:21.651 DEBG [sc] cr1: logging in
4002026-03-24T21:42:21.795ZMar 24 21:42:21.784 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-03-24T21:42:29.415ZMar 24 21:42:29.404 INFO cr1: executing frr script
4022026-03-24T21:42:29.415Z configure
4032026-03-24T21:42:29.415Z ip forwarding
4042026-03-24T21:42:29.415Z ipv6 forwarding
4052026-03-24T21:42:29.415Z ip route 1.2.3.0/24 null0
4062026-03-24T21:42:29.415Z ipv6 route fd99::/64 null0
4072026-03-24T21:42:29.415Z route-map PERMIT-ALL permit 10
4082026-03-24T21:42:29.415Z router bgp 44
4092026-03-24T21:42:29.415Z timers bgp 2 6
4102026-03-24T21:42:29.415Z neighbor enp0s8 interface remote-as external
4112026-03-24T21:42:29.416Z neighbor enp0s8 timers connect 1
4122026-03-24T21:42:29.416Z address-family ipv4 unicast
4132026-03-24T21:42:29.416Z network 1.2.3.0/24
4142026-03-24T21:42:29.416Z neighbor enp0s8 activate
4152026-03-24T21:42:29.416Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-03-24T21:42:29.416Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-03-24T21:42:29.416Z exit-address-family
4182026-03-24T21:42:29.416Z address-family ipv6 unicast
4192026-03-24T21:42:29.416Z network fd99::/64
4202026-03-24T21:42:29.416Z neighbor enp0s8 activate
4212026-03-24T21:42:29.416Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-03-24T21:42:29.416Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-03-24T21:42:29.416Z exit-address-family
4242026-03-24T21:42:29.416Z exit
4252026-03-24T21:42:29.416Z
4262026-03-24T21:42:29.416ZMar 24 21:42:29.404 DEBG [sc] cr1: starting
4272026-03-24T21:42:29.416ZMar 24 21:42:29.404 DEBG [sc] cr1: connecting to [::1]:32939
4282026-03-24T21:42:29.441ZMar 24 21:42:29.405 DEBG [sc] cr1 waiting for prompt
4292026-03-24T21:42:29.441ZMar 24 21:42:29.416 DEBG [sc] cr1: logging in
4302026-03-24T21:42:29.549ZMar 24 21:42:29.538 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-24T21:43:22.584ZMar 24 21:43:22.505 DEBG [sc] ox: starting
4322026-03-24T21:43:22.584ZMar 24 21:43:22.505 DEBG [sc] ox: connecting to [::1]:42407
4332026-03-24T21:43:22.682ZMar 24 21:43:22.506 DEBG [sc] ox waiting for prompt
4342026-03-24T21:43:22.682ZMar 24 21:43:22.517 DEBG [sc] ox: logging in
4352026-03-24T21:43:22.682ZMar 24 21:43:22.594 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-03-24T21:43:22.922ZMar 24 21:43:22.910 DEBG [sc] ox: starting
4372026-03-24T21:43:22.922ZMar 24 21:43:22.910 DEBG [sc] ox: connecting to [::1]:42407
4382026-03-24T21:43:22.952ZMar 24 21:43:22.911 DEBG [sc] ox waiting for prompt
4392026-03-24T21:43:22.952ZMar 24 21:43:22.921 DEBG [sc] ox: logging in
4402026-03-24T21:43:23.010ZMar 24 21:43:22.999 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-03-24T21:43:24.042ZMar 24 21:43:24.022 DEBG [sc] ox: starting
4422026-03-24T21:43:24.042ZMar 24 21:43:24.022 DEBG [sc] ox: connecting to [::1]:42407
4432026-03-24T21:43:24.042ZMar 24 21:43:24.023 DEBG [sc] ox waiting for prompt
4442026-03-24T21:43:24.042ZMar 24 21:43:24.023 DEBG [sc] ox: logging in
4452026-03-24T21:43:24.111ZMar 24 21:43:24.100 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4462026-03-24T21:43:24.167ZMar 24 21:43:24.156 DEBG [sc] ox: starting
4472026-03-24T21:43:24.167ZMar 24 21:43:24.156 DEBG [sc] ox: connecting to [::1]:42407
4482026-03-24T21:43:24.197ZMar 24 21:43:24.157 DEBG [sc] ox waiting for prompt
4492026-03-24T21:43:24.197ZMar 24 21:43:24.167 DEBG [sc] ox: logging in
4502026-03-24T21:43:24.255ZMar 24 21:43:24.244 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4512026-03-24T21:43:24.324ZMar 24 21:43:24.310 DEBG [sc] ox: starting
4522026-03-24T21:43:24.324ZMar 24 21:43:24.310 DEBG [sc] ox: connecting to [::1]:42407
4532026-03-24T21:43:24.360ZMar 24 21:43:24.311 DEBG [sc] ox waiting for prompt
4542026-03-24T21:43:24.360ZMar 24 21:43:24.321 DEBG [sc] ox: logging in
4552026-03-24T21:43:24.408ZMar 24 21:43:24.398 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4562026-03-24T21:43:24.464ZMar 24 21:43:24.453 DEBG [sc] ox: starting
4572026-03-24T21:43:24.464ZMar 24 21:43:24.453 DEBG [sc] ox: connecting to [::1]:42407
4582026-03-24T21:43:24.497ZMar 24 21:43:24.454 DEBG [sc] ox waiting for prompt
4592026-03-24T21:43:24.497ZMar 24 21:43:24.464 DEBG [sc] ox: logging in
4602026-03-24T21:43:24.552ZMar 24 21:43:24.541 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-03-24T21:43:24.608ZMar 24 21:43:24.597 DEBG [sc] ox: starting
4622026-03-24T21:43:24.608ZMar 24 21:43:24.597 DEBG [sc] ox: connecting to [::1]:42407
4632026-03-24T21:43:24.641ZMar 24 21:43:24.598 DEBG [sc] ox waiting for prompt
4642026-03-24T21:43:24.641ZMar 24 21:43:24.608 DEBG [sc] ox: logging in
4652026-03-24T21:43:24.696ZMar 24 21:43:24.686 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-03-24T21:43:24.752ZMar 24 21:43:24.742 DEBG [sc] ox: starting
4672026-03-24T21:43:24.752ZMar 24 21:43:24.742 DEBG [sc] ox: connecting to [::1]:42407
4682026-03-24T21:43:24.787ZMar 24 21:43:24.742 DEBG [sc] ox waiting for prompt
4692026-03-24T21:43:24.787ZMar 24 21:43:24.753 DEBG [sc] ox: logging in
4702026-03-24T21:43:24.842ZMar 24 21:43:24.831 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-03-24T21:43:24.899ZMar 24 21:43:24.886 DEBG [sc] ox: starting
4722026-03-24T21:43:24.899ZMar 24 21:43:24.886 DEBG [sc] ox: connecting to [::1]:42407
4732026-03-24T21:43:24.930ZMar 24 21:43:24.887 DEBG [sc] ox waiting for prompt
4742026-03-24T21:43:24.930ZMar 24 21:43:24.897 DEBG [sc] ox: logging in
4752026-03-24T21:43:24.986ZMar 24 21:43:24.974 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-03-24T21:43:25.040ZMar 24 21:43:25.029 DEBG [sc] ox: starting
4772026-03-24T21:43:25.040ZMar 24 21:43:25.029 DEBG [sc] ox: connecting to [::1]:42407
4782026-03-24T21:43:25.070ZMar 24 21:43:25.030 DEBG [sc] ox waiting for prompt
4792026-03-24T21:43:25.070ZMar 24 21:43:25.039 DEBG [sc] ox: logging in
4802026-03-24T21:43:25.201ZMar 24 21:43:25.117 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-03-24T21:43:25.228ZMar 24 21:43:25.186 INFO adding BGP router to mgd
4822026-03-24T21:43:27.225ZMar 24 21:43:27.205 INFO cr1: executing frr script show ip bgp json
4832026-03-24T21:43:27.225ZMar 24 21:43:27.206 DEBG [sc] cr1: starting
4842026-03-24T21:43:27.225ZMar 24 21:43:27.206 DEBG [sc] cr1: connecting to [::1]:32939
4852026-03-24T21:43:27.261ZMar 24 21:43:27.206 DEBG [sc] cr1 waiting for prompt
4862026-03-24T21:43:27.261ZMar 24 21:43:27.218 DEBG [sc] cr1: logging in
4872026-03-24T21:43:27.593ZMar 24 21:43:27.582 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-03-24T21:43:33.103ZMar 24 21:43:29.710 INFO cr1: executing frr script show bgp json
4892026-03-24T21:43:33.103ZMar 24 21:43:29.710 DEBG [sc] cr1: starting
4902026-03-24T21:43:33.104ZMar 24 21:43:29.710 DEBG [sc] cr1: connecting to [::1]:32939
4912026-03-24T21:43:33.104ZMar 24 21:43:29.711 DEBG [sc] cr1 waiting for prompt
4922026-03-24T21:43:33.104ZMar 24 21:43:29.721 DEBG [sc] cr1: logging in
4932026-03-24T21:43:33.104ZMar 24 21:43:29.843 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-03-24T21:43:33.104ZMar 24 21:43:31.981 INFO cr2: executing eos script show ip bgp | json
4952026-03-24T21:43:33.104ZMar 24 21:43:31.981 DEBG [sc] cr2: starting
4962026-03-24T21:43:33.104ZMar 24 21:43:31.981 DEBG [sc] cr2: connecting to [::1]:50893
4972026-03-24T21:43:33.104ZMar 24 21:43:31.982 DEBG [sc] cr2 waiting for prompt
4982026-03-24T21:43:33.104ZMar 24 21:43:31.993 DEBG [sc] cr2: logging in
4992026-03-24T21:43:33.104ZMar 24 21:43:32.336 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-03-24T21:43:34.738ZMar 24 21:43:34.727 INFO cr2: executing eos script show ipv6 bgp | json
5012026-03-24T21:43:34.738ZMar 24 21:43:34.727 DEBG [sc] cr2: starting
5022026-03-24T21:43:34.738ZMar 24 21:43:34.727 DEBG [sc] cr2: connecting to [::1]:50893
5032026-03-24T21:43:34.764ZMar 24 21:43:34.728 DEBG [sc] cr2 waiting for prompt
5042026-03-24T21:43:34.764ZMar 24 21:43:34.738 DEBG [sc] cr2: logging in
5052026-03-24T21:43:34.893ZMar 24 21:43:34.882 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-03-24T21:43:37.281ZMar 24 21:43:37.269 INFO trio bgp unnumbered test passed 🎉
5072026-03-24T21:43:37.281ZMar 24 21:43:37.269 INFO destroying runner for deployment mgtriou
5082026-03-24T21:43:37.281ZMar 24 21:43:37.269 INFO destroying deployment mgtriou
5092026-03-24T21:43:37.281ZMar 24 21:43:37.269 INFO destroying nodes
5102026-03-24T21:43:37.381ZMar 24 21:43:37.369 INFO destroying links
5112026-03-24T21:43:37.381ZMar 24 21:43:37.370 INFO destroying link mgtriou_ox_sn_vnic0
5122026-03-24T21:43:37.406ZMar 24 21:43:37.372 INFO destroying link mgtriou_ox_sn_sim0
5132026-03-24T21:43:37.406ZMar 24 21:43:37.373 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-03-24T21:43:38.388ZMar 24 21:43:38.377 INFO destroying link mgtriou_cr1_vn_sim0
5152026-03-24T21:43:38.413ZMar 24 21:43:38.378 INFO destroying link mgtriou_ox_sn_vnic1
5162026-03-24T21:43:38.413ZMar 24 21:43:38.379 INFO destroying link mgtriou_ox_sn_sim1
5172026-03-24T21:43:38.413ZMar 24 21:43:38.380 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-03-24T21:43:38.413ZMar 24 21:43:38.382 INFO destroying link mgtriou_cr2_vn_sim0
5192026-03-24T21:43:38.413ZMar 24 21:43:38.383 INFO destroying external links
5202026-03-24T21:43:38.413ZMar 24 21:43:38.383 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-03-24T21:43:38.413ZMar 24 21:43:38.384 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-03-24T21:43:38.413ZMar 24 21:43:38.385 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-03-24T21:43:38.413ZMar 24 21:43:38.387 INFO destroying images
5242026-03-24T21:43:38.887ZMar 24 21:43:38.877 INFO destroying workspace at .falcon
5252026-03-24T21:43:38.916Zprocess exited: duration 509491 ms, exit code 0
 
5262026-03-24T21:43:38.970Zfound 0 output files