01KNTRPXVXZ2S09V29ZTGQG04V: falcon

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

Buildomat Job: 01KNTRQ9EFRS6QV1NB8ZCJC9YT

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-10T04:13:32.009Zjob dependencies complete; ready to run (waiting for 13 m 52 s)
22026-04-10T04:16:44.831Zjob assigned to worker 01KNTSGPJ83P42NAJH01X18KRJ [factory edgar, gimlet/BRM42220010/745] (queued for 3 m 12 s)
32026-04-10T04:16:45.121Zdownloading input: /input/build-interop/work/testbed.tar.gz
42026-04-10T04:16:53.626Zdownloaded input: /input/build-interop/work/testbed.tar.gz
52026-04-10T04:16:53.626Zdownloading input: /input/build-interop/work/dhcp-server
62026-04-10T04:16:55.920Zdownloaded input: /input/build-interop/work/dhcp-server
72026-04-10T04:16:55.948Zdownloading input: /input/build/work/debug/ddmadm
82026-04-10T04:17:18.816Zdownloaded input: /input/build/work/debug/ddmadm
92026-04-10T04:17:18.816Zdownloading input: /input/build/work/debug/ddmd
102026-04-10T04:17:46.752Zdownloaded input: /input/build/work/debug/ddmd
112026-04-10T04:17:46.752Zdownloading input: /input/build/work/debug/mgadm
122026-04-10T04:18:13.441Zdownloaded input: /input/build/work/debug/mgadm
132026-04-10T04:18:13.441Zdownloading input: /input/build/work/debug/mgd
142026-04-10T04:18:45.105Zdownloaded input: /input/build/work/debug/mgd
152026-04-10T04:18:45.105Zdownloading input: /input/build/work/release/ddmadm
162026-04-10T04:18:47.255Zdownloaded input: /input/build/work/release/ddmadm
172026-04-10T04:18:47.255Zdownloading input: /input/build/work/release/ddmd
182026-04-10T04:18:50.013Zdownloaded input: /input/build/work/release/ddmd
192026-04-10T04:18:50.013Zdownloading input: /input/build/work/release/falcon-lab
202026-04-10T04:18:51.765Zdownloaded input: /input/build/work/release/falcon-lab
212026-04-10T04:18:51.765Zdownloading input: /input/build/work/release/mgadm
222026-04-10T04:18:54.372Zdownloaded input: /input/build/work/release/mgadm
232026-04-10T04:18:54.372Zdownloading input: /input/build/work/release/mgd
242026-04-10T04:18:57.817Zdownloaded input: /input/build/work/release/mgd
 
252026-04-10T04:18:57.817Zstarting task 0: "setup"
262026-04-10T04:18:57.841Z++ uname -s
272026-04-10T04:18:57.841Z+ kern=SunOS
282026-04-10T04:18:57.841Z+ build_user=build
292026-04-10T04:18:57.841Z+ build_uid=12345
302026-04-10T04:18:57.841Z+ work_dir=/work
312026-04-10T04:18:57.841Z+ input_dir=/input
322026-04-10T04:18:57.841Z+ [[ 0 == 12345 ]]
332026-04-10T04:18:57.841Z+ case "$kern" in
342026-04-10T04:18:57.841Z+ groupadd -g 12345 build
352026-04-10T04:18:57.865Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-04-10T04:18:59.856Z+ zfs create -o mountpoint=/work rpool/work
372026-04-10T04:19:00.403Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-04-10T04:19:00.429Z+ home_fs=
392026-04-10T04:19:00.429Z+ [[ '' == autofs ]]
402026-04-10T04:19:00.429Z+ mkdir -p /home/build
412026-04-10T04:19:00.429Z+ chown build:build /home/build /work
422026-04-10T04:19:01.414Z+ chmod 0700 /home/build /work
432026-04-10T04:19:01.438Zprocess exited: duration 3600 ms, exit code 0
 
442026-04-10T04:19:01.486Zstarting task 1: "authentication"
452026-04-10T04:19:01.584Zprocess exited: duration 97 ms, exit code 0
 
462026-04-10T04:19:01.632Zstarting task 2: "build"
472026-04-10T04:19:01.656Z+ set -e
482026-04-10T04:19:01.656Z+ banner zpool
492026-04-10T04:19:01.657Z
502026-04-10T04:19:01.657Z ###### ##### #### #### #
512026-04-10T04:19:01.657Z # # # # # # # #
522026-04-10T04:19:01.657Z # # # # # # # #
532026-04-10T04:19:01.657Z # ##### # # # # #
542026-04-10T04:19:01.657Z # # # # # # #
552026-04-10T04:19:01.657Z ###### # #### #### ######
562026-04-10T04:19:01.657Z
572026-04-10T04:19:01.657Z++ pfexec diskinfo -pH
582026-04-10T04:19:01.683Z++ sort -k8 -n -r
592026-04-10T04:19:01.683Z++ head -1
602026-04-10T04:19:01.683Z++ awk '{print $2}'
612026-04-10T04:19:01.707Z+ DISK=c9t0014EE81000BC3B1d0
622026-04-10T04:19:01.707Z+ export DISK
632026-04-10T04:19:01.707Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
642026-04-10T04:19:01.770Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-04-10T04:19:01.797Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-04-10T04:19:01.947Z+ [[ true =~ true ]]
672026-04-10T04:19:01.971Z+ pfexec zpool trim cpool
682026-04-10T04:19:01.971Z++ zpool status -t cpool
692026-04-10T04:19:01.995Z+ [[ ! pool: cpool
702026-04-10T04:19:01.995Z state: ONLINE
712026-04-10T04:19:01.995Z scan: none requested
722026-04-10T04:19:01.995Zconfig:
732026-04-10T04:19:01.995Z
742026-04-10T04:19:01.995Z NAME STATE READ WRITE CKSUM
752026-04-10T04:19:01.995Z cpool ONLINE 0 0 0
762026-04-10T04:19:01.995Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at April 10, 2026 at 04:19:01 AM UTC)
772026-04-10T04:19:01.996Z
782026-04-10T04:19:01.996Zerrors: No known data errors =~ 100% ]]
792026-04-10T04:19:01.996Z+ sleep 10
802026-04-10T04:19:11.981Z++ zpool status -t cpool
812026-04-10T04:19:12.005Z+ [[ ! pool: cpool
822026-04-10T04:19:12.005Z state: ONLINE
832026-04-10T04:19:12.005Z scan: none requested
842026-04-10T04:19:12.005Zconfig:
852026-04-10T04:19:12.005Z
862026-04-10T04:19:12.005Z NAME STATE READ WRITE CKSUM
872026-04-10T04:19:12.005Z cpool ONLINE 0 0 0
882026-04-10T04:19:12.005Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at April 10, 2026 at 04:19:01 AM UTC)
892026-04-10T04:19:12.005Z
902026-04-10T04:19:12.005Zerrors: No known data errors =~ 100% ]]
912026-04-10T04:19:12.005Z+ sleep 10
922026-04-10T04:19:21.995Z++ zpool status -t cpool
932026-04-10T04:19:22.020Z+ [[ ! pool: cpool
942026-04-10T04:19:22.020Z state: ONLINE
952026-04-10T04:19:22.020Z scan: none requested
962026-04-10T04:19:22.020Zconfig:
972026-04-10T04:19:22.020Z
982026-04-10T04:19:22.020Z NAME STATE READ WRITE CKSUM
992026-04-10T04:19:22.020Z cpool ONLINE 0 0 0
1002026-04-10T04:19:22.020Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at April 10, 2026 at 04:19:16 AM UTC)
1012026-04-10T04:19:22.020Z
1022026-04-10T04:19:22.020Zerrors: No known data errors =~ 100% ]]
1032026-04-10T04:19:22.020Z+ pfexec chown 12345 /ci
1042026-04-10T04:19:22.020Z+ cd /ci
1052026-04-10T04:19:22.020Z+ export FALCON_DATASET=cpool/falcon
1062026-04-10T04:19:22.020Z+ FALCON_DATASET=cpool/falcon
1072026-04-10T04:19:22.020Z+ banner setup
1082026-04-10T04:19:22.020Z
1092026-04-10T04:19:22.020Z #### ###### ##### # # #####
1102026-04-10T04:19:22.020Z # # # # # # #
1112026-04-10T04:19:22.020Z #### ##### # # # # #
1122026-04-10T04:19:22.020Z # # # # # #####
1132026-04-10T04:19:22.020Z # # # # # # #
1142026-04-10T04:19:22.020Z #### ###### # #### #
1152026-04-10T04:19:22.020Z
1162026-04-10T04:19:22.020Z+ cp /input/build-interop/work/dhcp-server .
1172026-04-10T04:19:22.326Z+ cp /input/build/work/release/falcon-lab .
1182026-04-10T04:19:22.362Z+ cp /input/build/work/release/mgd .
1192026-04-10T04:19:22.428Z+ cp /input/build/work/release/ddmd .
1202026-04-10T04:19:22.492Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-04-10T04:19:22.517Z+ mkdir -p cargo-bay
1222026-04-10T04:19:22.517Z+ mv mgd cargo-bay/
1232026-04-10T04:19:22.517Z+ mv ddmd cargo-bay/
1242026-04-10T04:19:22.517Z+ export EXT_INTERFACE=igb0
1252026-04-10T04:19:22.517Z+ EXT_INTERFACE=igb0
1262026-04-10T04:19:22.517Z++ bmat address ls -f extra -Ho first
1272026-04-10T04:19:22.541Z+ first=10.151.6.164
1282026-04-10T04:19:22.541Z++ bmat address ls -f extra -Ho last
1292026-04-10T04:19:22.565Z+ last=10.151.6.227
1302026-04-10T04:19:22.565Z++ bmat address ls -f extra -Ho gateway
1312026-04-10T04:19:22.589Z+ gw=10.151.6.1
1322026-04-10T04:19:22.589Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-04-10T04:19:22.589Z++ sed 's#/.*##g'
1342026-04-10T04:19:22.613Z+ server=10.151.6.100
1352026-04-10T04:19:22.613Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1362026-04-10T04:19:22.613Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-04-10T04:19:22.613Z+ error=
1382026-04-10T04:19:22.613Z+ RUST_LOG=debug
1392026-04-10T04:19:22.613Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-04-10T04:19:22.672ZApr 10 04:19:22.615 DEBG using default route interface igb0
1412026-04-10T04:19:22.672ZApr 10 04:19:22.616 DEBG using default route interface igb0
1422026-04-10T04:19:22.672ZApr 10 04:19:22.616 DEBG using default route interface igb0
1432026-04-10T04:19:22.672ZApr 10 04:19:22.616 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-04-10T04:19:22.672ZApr 10 04:19:22.616 INFO starting preflight for deployment mgtriou
1452026-04-10T04:19:22.672ZApr 10 04:19:22.616 INFO propolis-server binary not found
1462026-04-10T04:19:22.672ZApr 10 04:19:22.616 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-04-10T04:19:26.408ZApr 10 04:19:26.398 INFO ovmf fd not found
1482026-04-10T04:19:26.408ZApr 10 04:19:26.398 INFO downloading ovmf
1492026-04-10T04:19:26.781ZApr 10 04:19:26.771 INFO base image for helios-2.9 does not exist, attempting to install
1502026-04-10T04:19:26.781ZApr 10 04:19:26.771 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-04-10T04:19:55.479ZApr 10 04:19:55.471 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-04-10T04:20:29.092ZApr 10 04:20:29.069 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-04-10T04:20:29.128ZApr 10 04:20:29.120 INFO copying image data to zvol
1542026-04-10T04:20:39.552ZApr 10 04:20:39.544 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-04-10T04:20:44.297ZApr 10 04:20:44.290 INFO base image for debian-13.2 does not exist, attempting to install
1562026-04-10T04:20:44.297ZApr 10 04:20:44.290 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-04-10T04:21:05.194ZApr 10 04:21:05.187 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-04-10T04:21:26.188ZApr 10 04:21:26.180 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-04-10T04:21:26.213ZApr 10 04:21:26.206 INFO copying image data to zvol
1602026-04-10T04:21:33.264ZApr 10 04:21:33.256 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-04-10T04:21:36.557ZApr 10 04:21:36.549 INFO base image for eos-4.35 does not exist, attempting to install
1622026-04-10T04:21:36.557ZApr 10 04:21:36.549 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-04-10T04:22:19.685ZApr 10 04:22:19.672 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-04-10T04:23:42.662ZApr 10 04:23:42.650 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-04-10T04:23:42.688ZApr 10 04:23:42.676 INFO copying image data to zvol
1662026-04-10T04:24:31.246ZApr 10 04:24:31.236 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-04-10T04:24:34.723ZApr 10 04:24:34.712 INFO creating links
1682026-04-10T04:24:34.723ZApr 10 04:24:34.712 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-04-10T04:24:34.747ZApr 10 04:24:34.712 DEBG destroying link mgtriou_ox_sn_sim0
1702026-04-10T04:24:34.747ZApr 10 04:24:34.712 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-04-10T04:24:34.747ZApr 10 04:24:34.715 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-04-10T04:24:34.747ZApr 10 04:24:34.721 DEBG link pair created
1732026-04-10T04:24:34.747ZApr 10 04:24:34.721 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-04-10T04:24:34.747ZApr 10 04:24:34.721 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-04-10T04:24:34.748ZApr 10 04:24:34.721 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-04-10T04:24:34.748ZApr 10 04:24:34.723 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-04-10T04:24:34.748ZApr 10 04:24:34.729 DEBG link pair created
1782026-04-10T04:24:34.748ZApr 10 04:24:34.729 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-04-10T04:24:34.748ZApr 10 04:24:34.729 DEBG destroying link mgtriou_ox_sn_sim1
1802026-04-10T04:24:34.748ZApr 10 04:24:34.729 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-04-10T04:24:34.748ZApr 10 04:24:34.730 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-04-10T04:24:34.772ZApr 10 04:24:34.737 DEBG link pair created
1832026-04-10T04:24:34.772ZApr 10 04:24:34.737 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-04-10T04:24:34.772ZApr 10 04:24:34.737 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-04-10T04:24:34.772ZApr 10 04:24:34.737 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-04-10T04:24:34.772ZApr 10 04:24:34.739 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-04-10T04:24:34.772ZApr 10 04:24:34.745 DEBG link pair created
1882026-04-10T04:24:34.772ZApr 10 04:24:34.745 INFO creating external links
1892026-04-10T04:24:34.772ZApr 10 04:24:34.745 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-04-10T04:24:34.772ZApr 10 04:24:34.745 INFO creating external link mgtriou_ox_vn_vnic2
1912026-04-10T04:24:34.772ZApr 10 04:24:34.748 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-04-10T04:24:34.772ZApr 10 04:24:34.748 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-04-10T04:24:34.772ZApr 10 04:24:34.748 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-04-10T04:24:34.772ZApr 10 04:24:34.749 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-04-10T04:24:34.772ZApr 10 04:24:34.749 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-04-10T04:24:34.772ZApr 10 04:24:34.749 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-04-10T04:24:34.773ZApr 10 04:24:34.750 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-04-10T04:24:34.773ZApr 10 04:24:34.750 INFO creating nodes
1992026-04-10T04:24:34.773ZApr 10 04:24:34.751 INFO ox: launching node
2002026-04-10T04:24:34.773ZApr 10 04:24:34.753 INFO cr1: launching node
2012026-04-10T04:24:34.773ZApr 10 04:24:34.755 INFO cr2: launching node
2022026-04-10T04:24:34.797ZApr 10 04:24:34.768 INFO launched instance cr1 with pid 853 on port 60526
2032026-04-10T04:24:34.797ZApr 10 04:24:34.769 INFO cr1: instance ensure
2042026-04-10T04:24:34.797ZApr 10 04:24:34.769 INFO launched instance ox with pid 852 on port 42165
2052026-04-10T04:24:34.797ZApr 10 04:24:34.769 INFO ox: instance ensure
2062026-04-10T04:24:34.797ZApr 10 04:24:34.769 INFO launched instance cr2 with pid 854 on port 37112
2072026-04-10T04:24:34.797ZApr 10 04:24:34.769 INFO cr2: instance ensure
2082026-04-10T04:24:37.124ZApr 10 04:24:37.113 INFO cr1: instance run
2092026-04-10T04:24:37.148ZApr 10 04:24:37.114 DEBG [sc] cr1: starting
2102026-04-10T04:24:37.149ZApr 10 04:24:37.114 DEBG [sc] cr1: connecting to [::1]:60526
2112026-04-10T04:24:37.149ZApr 10 04:24:37.115 DEBG [sc] cr1 waiting for prompt
2122026-04-10T04:24:37.173ZApr 10 04:24:37.147 INFO ox: instance run
2132026-04-10T04:24:37.173ZApr 10 04:24:37.147 DEBG [sc] ox: starting
2142026-04-10T04:24:37.174ZApr 10 04:24:37.147 DEBG [sc] ox: connecting to [::1]:42165
2152026-04-10T04:24:37.174ZApr 10 04:24:37.148 DEBG [sc] ox waiting for prompt
2162026-04-10T04:24:37.267ZApr 10 04:24:37.256 INFO cr2: instance run
2172026-04-10T04:24:37.291ZApr 10 04:24:37.257 DEBG [sc] cr2: starting
2182026-04-10T04:24:37.291ZApr 10 04:24:37.257 DEBG [sc] cr2: connecting to [::1]:37112
2192026-04-10T04:24:37.291ZApr 10 04:24:37.257 DEBG [sc] cr2 waiting for prompt
2202026-04-10T04:24:54.209ZApr 10 04:24:54.198 DEBG [sc] cr1: logging in
2212026-04-10T04:24:54.574ZApr 10 04:24:54.564 INFO cr1: mounting /opt/cargo-bay
2222026-04-10T04:24:54.574ZApr 10 04:24:54.564 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-04-10T04:24:54.599ZApr 10 04:24:54.575 DEBG [sc] cr1: executing command `cd`
2242026-04-10T04:24:54.599ZApr 10 04:24:54.585 INFO cr1: finished mounting /opt/cargo-bay
2252026-04-10T04:24:54.599ZApr 10 04:24:54.585 DEBG [sc] cr1: executing command `hostname cr1`
2262026-04-10T04:24:54.623ZApr 10 04:24:54.596 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-04-10T04:24:54.623ZApr 10 04:24:54.607 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-04-10T04:24:54.647ZApr 10 04:24:54.618 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-04-10T04:24:54.647ZApr 10 04:24:54.630 INFO cr1: logging out
2302026-04-10T04:24:56.701ZApr 10 04:24:56.690 INFO cr1: logged out
2312026-04-10T04:24:57.639ZApr 10 04:24:57.629 DEBG [sc] ox: logging in
2322026-04-10T04:24:57.829ZApr 10 04:24:57.819 INFO ox: mounting /opt/cargo-bay
2332026-04-10T04:24:57.829ZApr 10 04:24:57.819 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-04-10T04:24:58.780ZApr 10 04:24:58.770 DEBG [sc] ox: executing command `cd`
2352026-04-10T04:24:58.804ZApr 10 04:24:58.781 INFO ox: finished mounting /opt/cargo-bay
2362026-04-10T04:24:58.804ZApr 10 04:24:58.781 DEBG [sc] ox: executing command `hostname ox`
2372026-04-10T04:24:58.804ZApr 10 04:24:58.791 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2382026-04-10T04:24:58.828ZApr 10 04:24:58.802 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2392026-04-10T04:24:58.828ZApr 10 04:24:58.813 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2402026-04-10T04:24:58.852ZApr 10 04:24:58.820 DEBG [sc] cr2: logging in
2412026-04-10T04:24:58.852ZApr 10 04:24:58.824 INFO ox: logging out
2422026-04-10T04:24:58.901ZApr 10 04:24:58.890 INFO ox: logged out
2432026-04-10T04:24:59.243ZApr 10 04:24:59.232 INFO cr2: mounting /opt/cargo-bay
2442026-04-10T04:24:59.243ZApr 10 04:24:59.232 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-04-10T04:24:59.267ZApr 10 04:24:59.254 DEBG [sc] cr2: executing command `cd`
2462026-04-10T04:24:59.291ZApr 10 04:24:59.265 INFO cr2: finished mounting /opt/cargo-bay
2472026-04-10T04:24:59.291ZApr 10 04:24:59.265 DEBG [sc] cr2: executing command `hostname cr2`
2482026-04-10T04:24:59.291ZApr 10 04:24:59.276 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-04-10T04:24:59.315ZApr 10 04:24:59.287 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-04-10T04:24:59.315ZApr 10 04:24:59.298 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-04-10T04:24:59.338ZApr 10 04:24:59.309 INFO cr2: logging out
2522026-04-10T04:25:02.266ZApr 10 04:25:02.255 INFO cr2: logged out
2532026-04-10T04:25:02.290ZApr 10 04:25:02.255 DEBG [sc] ox: starting
2542026-04-10T04:25:02.290ZApr 10 04:25:02.255 DEBG [sc] ox: connecting to [::1]:42165
2552026-04-10T04:25:02.290ZApr 10 04:25:02.256 DEBG [sc] ox waiting for prompt
2562026-04-10T04:25:02.290ZApr 10 04:25:02.267 DEBG [sc] ox: logging in
2572026-04-10T04:25:02.367ZApr 10 04:25:02.356 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-04-10T04:25:08.553ZApr 10 04:25:08.543 DEBG [sc] ox: starting
2592026-04-10T04:25:08.554ZApr 10 04:25:08.543 DEBG [sc] ox: connecting to [::1]:42165
2602026-04-10T04:25:08.577ZApr 10 04:25:08.543 DEBG [sc] ox waiting for prompt
2612026-04-10T04:25:08.577ZApr 10 04:25:08.554 DEBG [sc] ox: logging in
2622026-04-10T04:25:08.653ZApr 10 04:25:08.643 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-04-10T04:25:08.742ZApr 10 04:25:08.731 DEBG [sc] ox: starting
2642026-04-10T04:25:08.742ZApr 10 04:25:08.731 DEBG [sc] ox: connecting to [::1]:42165
2652026-04-10T04:25:08.766ZApr 10 04:25:08.732 DEBG [sc] ox waiting for prompt
2662026-04-10T04:25:08.766ZApr 10 04:25:08.742 DEBG [sc] ox: logging in
2672026-04-10T04:25:08.841ZApr 10 04:25:08.830 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-04-10T04:25:08.907ZApr 10 04:25:08.896 INFO cr1: installing frr
2692026-04-10T04:25:08.907ZApr 10 04:25:08.897 INFO waiting for ceos to initialize
2702026-04-10T04:25:08.907ZApr 10 04:25:08.897 INFO ox: setting up npuvm
2712026-04-10T04:25:08.931ZApr 10 04:25:08.897 DEBG [sc] cr2: starting
2722026-04-10T04:25:08.931ZApr 10 04:25:08.897 DEBG [sc] cr2: connecting to [::1]:37112
2732026-04-10T04:25:08.931ZApr 10 04:25:08.897 DEBG [sc] ox: starting
2742026-04-10T04:25:08.931ZApr 10 04:25:08.897 DEBG [sc] cr1: starting
2752026-04-10T04:25:08.931ZApr 10 04:25:08.897 DEBG [sc] cr1: connecting to [::1]:60526
2762026-04-10T04:25:08.931ZApr 10 04:25:08.897 DEBG [sc] ox: connecting to [::1]:42165
2772026-04-10T04:25:08.932ZApr 10 04:25:08.897 DEBG [sc] cr2 waiting for prompt
2782026-04-10T04:25:08.932ZApr 10 04:25:08.897 DEBG [sc] cr1 waiting for prompt
2792026-04-10T04:25:08.932ZApr 10 04:25:08.897 DEBG [sc] ox waiting for prompt
2802026-04-10T04:25:08.932ZApr 10 04:25:08.908 DEBG [sc] ox: logging in
2812026-04-10T04:25:08.932ZApr 10 04:25:08.908 DEBG [sc] cr2: logging in
2822026-04-10T04:25:08.932ZApr 10 04:25:08.908 DEBG [sc] cr1: logging in
2832026-04-10T04:25:08.995ZApr 10 04:25:08.985 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-04-10T04:25:09.063ZApr 10 04:25:09.052 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-04-10T04:25:09.300ZApr 10 04:25:09.290 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-04-10T04:25:11.293ZApr 10 04:25:11.282 INFO cr2: executing eos script show version
2872026-04-10T04:25:11.293ZApr 10 04:25:11.282 DEBG [sc] cr2: starting
2882026-04-10T04:25:11.293ZApr 10 04:25:11.282 DEBG [sc] cr2: connecting to [::1]:37112
2892026-04-10T04:25:11.317ZApr 10 04:25:11.283 DEBG [sc] cr2 waiting for prompt
2902026-04-10T04:25:11.317ZApr 10 04:25:11.293 DEBG [sc] cr2: logging in
2912026-04-10T04:25:11.458ZApr 10 04:25:11.447 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-04-10T04:25:14.342ZApr 10 04:25:14.331 DEBG [sc] ox: starting
2932026-04-10T04:25:14.342ZApr 10 04:25:14.331 DEBG [sc] ox: connecting to [::1]:42165
2942026-04-10T04:25:14.366ZApr 10 04:25:14.332 DEBG [sc] ox waiting for prompt
2952026-04-10T04:25:14.366ZApr 10 04:25:14.342 DEBG [sc] ox: logging in
2962026-04-10T04:25:14.429ZApr 10 04:25:14.419 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-04-10T04:25:14.496ZApr 10 04:25:14.485 DEBG [sc] ox: starting
2982026-04-10T04:25:14.497ZApr 10 04:25:14.485 DEBG [sc] ox: connecting to [::1]:42165
2992026-04-10T04:25:14.521ZApr 10 04:25:14.486 DEBG [sc] ox waiting for prompt
3002026-04-10T04:25:14.521ZApr 10 04:25:14.496 DEBG [sc] ox: logging in
3012026-04-10T04:25:14.584ZApr 10 04:25:14.573 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-04-10T04:25:19.735ZApr 10 04:25:19.724 DEBG [sc] cr2: starting
3032026-04-10T04:25:19.735ZApr 10 04:25:19.724 DEBG [sc] cr2: connecting to [::1]:37112
3042026-04-10T04:25:19.759ZApr 10 04:25:19.725 DEBG [sc] cr2 waiting for prompt
3052026-04-10T04:25:19.759ZApr 10 04:25:19.735 DEBG [sc] cr2: logging in
3062026-04-10T04:25:19.878ZApr 10 04:25:19.867 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-04-10T04:25:22.003ZApr 10 04:25:21.993 INFO cr2: executing eos script show version
3082026-04-10T04:25:22.004ZApr 10 04:25:21.993 DEBG [sc] cr2: starting
3092026-04-10T04:25:22.004ZApr 10 04:25:21.993 DEBG [sc] cr2: connecting to [::1]:37112
3102026-04-10T04:25:22.027ZApr 10 04:25:21.993 DEBG [sc] cr2 waiting for prompt
3112026-04-10T04:25:22.028ZApr 10 04:25:22.003 DEBG [sc] cr2: logging in
3122026-04-10T04:25:22.180ZApr 10 04:25:22.169 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-04-10T04:25:24.602ZApr 10 04:25:24.591 DEBG [sc] cr2: starting
3142026-04-10T04:25:24.602ZApr 10 04:25:24.591 DEBG [sc] cr2: connecting to [::1]:37112
3152026-04-10T04:25:24.626ZApr 10 04:25:24.591 DEBG [sc] cr2 waiting for prompt
3162026-04-10T04:25:24.626ZApr 10 04:25:24.602 DEBG [sc] cr2: logging in
3172026-04-10T04:25:24.755ZApr 10 04:25:24.744 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-04-10T04:25:26.899ZApr 10 04:25:26.888 INFO cr2: executing eos script show version
3192026-04-10T04:25:26.900ZApr 10 04:25:26.889 DEBG [sc] cr2: starting
3202026-04-10T04:25:26.900ZApr 10 04:25:26.889 DEBG [sc] cr2: connecting to [::1]:37112
3212026-04-10T04:25:26.924ZApr 10 04:25:26.889 DEBG [sc] cr2 waiting for prompt
3222026-04-10T04:25:26.924ZApr 10 04:25:26.900 DEBG [sc] cr2: logging in
3232026-04-10T04:25:27.075ZApr 10 04:25:27.065 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-04-10T04:25:29.532ZApr 10 04:25:29.521 INFO cr2: executing eos script
3252026-04-10T04:25:29.532Z enable
3262026-04-10T04:25:29.532Z configure
3272026-04-10T04:25:29.532Z ipv6 unicast-routing
3282026-04-10T04:25:29.532Z ip routing ipv6 interfaces
3292026-04-10T04:25:29.532Z ip routing
3302026-04-10T04:25:29.532Z ip route 1.2.3.0/24 null0
3312026-04-10T04:25:29.532Z ipv6 route fd99::/64 null0
3322026-04-10T04:25:29.532Z interface et1
3332026-04-10T04:25:29.532Z no switchport
3342026-04-10T04:25:29.532Z ipv6 enable
3352026-04-10T04:25:29.532Z
3362026-04-10T04:25:29.532Z router bgp 45
3372026-04-10T04:25:29.532Z router-id 1.2.3.1
3382026-04-10T04:25:29.532Z no bgp default ipv4-unicast
3392026-04-10T04:25:29.532Z timers bgp 2 6
3402026-04-10T04:25:29.532Z neighbor ebgp peer group
3412026-04-10T04:25:29.532Z neighbor ebgp remote-as 33
3422026-04-10T04:25:29.532Z neighbor interface Et1 peer-group ebgp
3432026-04-10T04:25:29.532Z address-family ipv4
3442026-04-10T04:25:29.532Z neighbor ebgp activate
3452026-04-10T04:25:29.532Z neighbor ebgp next-hop address-family ipv6 originate
3462026-04-10T04:25:29.532Z network 1.2.3.0/24
3472026-04-10T04:25:29.532Z exit
3482026-04-10T04:25:29.532Z address-family ipv6
3492026-04-10T04:25:29.532Z neighbor ebgp activate
3502026-04-10T04:25:29.532Z neighbor ebgp next-hop address-family ipv6 originate
3512026-04-10T04:25:29.532Z network fd99::/64
3522026-04-10T04:25:29.532Z exit
3532026-04-10T04:25:29.532Z exit
3542026-04-10T04:25:29.532Z
3552026-04-10T04:25:29.532ZApr 10 04:25:29.521 DEBG [sc] cr2: starting
3562026-04-10T04:25:29.532ZApr 10 04:25:29.521 DEBG [sc] cr2: connecting to [::1]:37112
3572026-04-10T04:25:29.557ZApr 10 04:25:29.521 DEBG [sc] cr2 waiting for prompt
3582026-04-10T04:25:29.557ZApr 10 04:25:29.531 DEBG [sc] cr2: logging in
3592026-04-10T04:25:29.696ZApr 10 04:25:29.685 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-04-10T04:25:29.696Z enable
3612026-04-10T04:25:29.696Z configure
3622026-04-10T04:25:29.696Z ipv6 unicast-routing
3632026-04-10T04:25:29.696Z ip routing ipv6 interfaces
3642026-04-10T04:25:29.696Z ip routing
3652026-04-10T04:25:29.696Z ip route 1.2.3.0/24 null0
3662026-04-10T04:25:29.696Z ipv6 route fd99::/64 null0
3672026-04-10T04:25:29.696Z interface et1
3682026-04-10T04:25:29.696Z no switchport
3692026-04-10T04:25:29.696Z ipv6 enable
3702026-04-10T04:25:29.696Z
3712026-04-10T04:25:29.696Z router bgp 45
3722026-04-10T04:25:29.697Z router-id 1.2.3.1
3732026-04-10T04:25:29.697Z no bgp default ipv4-unicast
3742026-04-10T04:25:29.697Z timers bgp 2 6
3752026-04-10T04:25:29.697Z neighbor ebgp peer group
3762026-04-10T04:25:29.697Z neighbor ebgp remote-as 33
3772026-04-10T04:25:29.697Z neighbor interface Et1 peer-group ebgp
3782026-04-10T04:25:29.697Z address-family ipv4
3792026-04-10T04:25:29.697Z neighbor ebgp activate
3802026-04-10T04:25:29.697Z neighbor ebgp next-hop address-family ipv6 originate
3812026-04-10T04:25:29.697Z network 1.2.3.0/24
3822026-04-10T04:25:29.697Z exit
3832026-04-10T04:25:29.697Z address-family ipv6
3842026-04-10T04:25:29.698Z neighbor ebgp activate
3852026-04-10T04:25:29.698Z neighbor ebgp next-hop address-family ipv6 originate
3862026-04-10T04:25:29.698Z network fd99::/64
3872026-04-10T04:25:29.698Z exit
3882026-04-10T04:25:29.698Z exit
3892026-04-10T04:25:29.698Z '`
3902026-04-10T04:26:16.831ZApr 10 04:26:16.820 INFO cr1: enabling frr daemon bgpd
3912026-04-10T04:26:16.831ZApr 10 04:26:16.820 DEBG [sc] cr1: starting
3922026-04-10T04:26:16.831ZApr 10 04:26:16.820 DEBG [sc] cr1: connecting to [::1]:60526
3932026-04-10T04:26:16.855ZApr 10 04:26:16.820 DEBG [sc] cr1 waiting for prompt
3942026-04-10T04:26:16.855ZApr 10 04:26:16.831 DEBG [sc] cr1: logging in
3952026-04-10T04:26:16.975ZApr 10 04:26:16.964 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-04-10T04:26:19.039ZApr 10 04:26:19.028 DEBG [sc] cr1: starting
3972026-04-10T04:26:19.039ZApr 10 04:26:19.028 DEBG [sc] cr1: connecting to [::1]:60526
3982026-04-10T04:26:19.063ZApr 10 04:26:19.029 DEBG [sc] cr1 waiting for prompt
3992026-04-10T04:26:19.063ZApr 10 04:26:19.040 DEBG [sc] cr1: logging in
4002026-04-10T04:26:19.184ZApr 10 04:26:19.173 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-04-10T04:26:26.781ZApr 10 04:26:26.769 INFO cr1: executing frr script
4022026-04-10T04:26:26.781Z configure
4032026-04-10T04:26:26.781Z ip forwarding
4042026-04-10T04:26:26.781Z ipv6 forwarding
4052026-04-10T04:26:26.781Z ip route 1.2.3.0/24 null0
4062026-04-10T04:26:26.781Z ipv6 route fd99::/64 null0
4072026-04-10T04:26:26.781Z route-map PERMIT-ALL permit 10
4082026-04-10T04:26:26.781Z router bgp 44
4092026-04-10T04:26:26.781Z timers bgp 2 6
4102026-04-10T04:26:26.781Z neighbor enp0s8 interface remote-as external
4112026-04-10T04:26:26.781Z neighbor enp0s8 timers connect 1
4122026-04-10T04:26:26.781Z address-family ipv4 unicast
4132026-04-10T04:26:26.781Z network 1.2.3.0/24
4142026-04-10T04:26:26.781Z neighbor enp0s8 activate
4152026-04-10T04:26:26.781Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-04-10T04:26:26.781Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-04-10T04:26:26.781Z exit-address-family
4182026-04-10T04:26:26.781Z address-family ipv6 unicast
4192026-04-10T04:26:26.781Z network fd99::/64
4202026-04-10T04:26:26.781Z neighbor enp0s8 activate
4212026-04-10T04:26:26.781Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-04-10T04:26:26.781Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-04-10T04:26:26.781Z exit-address-family
4242026-04-10T04:26:26.781Z exit
4252026-04-10T04:26:26.781Z
4262026-04-10T04:26:26.781ZApr 10 04:26:26.769 DEBG [sc] cr1: starting
4272026-04-10T04:26:26.781ZApr 10 04:26:26.769 DEBG [sc] cr1: connecting to [::1]:60526
4282026-04-10T04:26:26.806ZApr 10 04:26:26.770 DEBG [sc] cr1 waiting for prompt
4292026-04-10T04:26:26.806ZApr 10 04:26:26.781 DEBG [sc] cr1: logging in
4302026-04-10T04:26:26.925ZApr 10 04:26:26.914 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-04-10T04:26:55.103ZApr 10 04:26:55.092 DEBG [sc] ox: starting
4322026-04-10T04:26:55.103ZApr 10 04:26:55.092 DEBG [sc] ox: connecting to [::1]:42165
4332026-04-10T04:26:55.153ZApr 10 04:26:55.093 DEBG [sc] ox waiting for prompt
4342026-04-10T04:26:55.178ZApr 10 04:26:55.103 DEBG [sc] ox: logging in
4352026-04-10T04:26:55.205ZApr 10 04:26:55.194 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-04-10T04:26:55.539ZApr 10 04:26:55.528 DEBG [sc] ox: starting
4372026-04-10T04:26:55.539ZApr 10 04:26:55.528 DEBG [sc] ox: connecting to [::1]:42165
4382026-04-10T04:26:55.564ZApr 10 04:26:55.529 DEBG [sc] ox waiting for prompt
4392026-04-10T04:26:55.564ZApr 10 04:26:55.540 DEBG [sc] ox: logging in
4402026-04-10T04:26:55.629ZApr 10 04:26:55.618 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-04-10T04:26:56.653ZApr 10 04:26:56.642 DEBG [sc] ox: starting
4422026-04-10T04:26:56.654ZApr 10 04:26:56.642 DEBG [sc] ox: connecting to [::1]:42165
4432026-04-10T04:26:56.679ZApr 10 04:26:56.643 DEBG [sc] ox waiting for prompt
4442026-04-10T04:26:56.679ZApr 10 04:26:56.653 DEBG [sc] ox: logging in
4452026-04-10T04:26:56.742ZApr 10 04:26:56.731 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4462026-04-10T04:26:56.808ZApr 10 04:26:56.797 DEBG [sc] ox: starting
4472026-04-10T04:26:56.808ZApr 10 04:26:56.797 DEBG [sc] ox: connecting to [::1]:42165
4482026-04-10T04:26:56.832ZApr 10 04:26:56.798 DEBG [sc] ox waiting for prompt
4492026-04-10T04:26:56.832ZApr 10 04:26:56.807 DEBG [sc] ox: logging in
4502026-04-10T04:26:56.896ZApr 10 04:26:56.885 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4512026-04-10T04:26:56.951ZApr 10 04:26:56.939 DEBG [sc] ox: starting
4522026-04-10T04:26:56.951ZApr 10 04:26:56.939 DEBG [sc] ox: connecting to [::1]:42165
4532026-04-10T04:26:56.976ZApr 10 04:26:56.940 DEBG [sc] ox waiting for prompt
4542026-04-10T04:26:56.976ZApr 10 04:26:56.951 DEBG [sc] ox: logging in
4552026-04-10T04:26:57.039ZApr 10 04:26:57.028 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4562026-04-10T04:26:57.095ZApr 10 04:26:57.083 DEBG [sc] ox: starting
4572026-04-10T04:26:57.095ZApr 10 04:26:57.083 DEBG [sc] ox: connecting to [::1]:42165
4582026-04-10T04:26:57.119ZApr 10 04:26:57.083 DEBG [sc] ox waiting for prompt
4592026-04-10T04:26:57.119ZApr 10 04:26:57.094 DEBG [sc] ox: logging in
4602026-04-10T04:26:57.182ZApr 10 04:26:57.171 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-04-10T04:26:57.238ZApr 10 04:26:57.227 DEBG [sc] ox: starting
4622026-04-10T04:26:57.238ZApr 10 04:26:57.227 DEBG [sc] ox: connecting to [::1]:42165
4632026-04-10T04:26:57.262ZApr 10 04:26:57.227 DEBG [sc] ox waiting for prompt
4642026-04-10T04:26:57.262ZApr 10 04:26:57.238 DEBG [sc] ox: logging in
4652026-04-10T04:26:57.326ZApr 10 04:26:57.315 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-04-10T04:26:57.381ZApr 10 04:26:57.369 DEBG [sc] ox: starting
4672026-04-10T04:26:57.381ZApr 10 04:26:57.369 DEBG [sc] ox: connecting to [::1]:42165
4682026-04-10T04:26:57.405ZApr 10 04:26:57.370 DEBG [sc] ox waiting for prompt
4692026-04-10T04:26:57.405ZApr 10 04:26:57.381 DEBG [sc] ox: logging in
4702026-04-10T04:26:57.468ZApr 10 04:26:57.457 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-04-10T04:26:57.524ZApr 10 04:26:57.513 DEBG [sc] ox: starting
4722026-04-10T04:26:57.524ZApr 10 04:26:57.513 DEBG [sc] ox: connecting to [::1]:42165
4732026-04-10T04:26:57.548ZApr 10 04:26:57.513 DEBG [sc] ox waiting for prompt
4742026-04-10T04:26:57.548ZApr 10 04:26:57.524 DEBG [sc] ox: logging in
4752026-04-10T04:26:57.612ZApr 10 04:26:57.601 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-04-10T04:26:57.677ZApr 10 04:26:57.666 DEBG [sc] ox: starting
4772026-04-10T04:26:57.678ZApr 10 04:26:57.666 DEBG [sc] ox: connecting to [::1]:42165
4782026-04-10T04:26:57.702ZApr 10 04:26:57.667 DEBG [sc] ox waiting for prompt
4792026-04-10T04:26:57.702ZApr 10 04:26:57.677 DEBG [sc] ox: logging in
4802026-04-10T04:26:57.766ZApr 10 04:26:57.754 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-04-10T04:26:57.835ZApr 10 04:26:57.824 INFO adding BGP router to mgd
4822026-04-10T04:26:59.854ZApr 10 04:26:59.843 INFO cr1: executing frr script show ip bgp json
4832026-04-10T04:26:59.854ZApr 10 04:26:59.843 DEBG [sc] cr1: starting
4842026-04-10T04:26:59.854ZApr 10 04:26:59.843 DEBG [sc] cr1: connecting to [::1]:60526
4852026-04-10T04:26:59.879ZApr 10 04:26:59.843 DEBG [sc] cr1 waiting for prompt
4862026-04-10T04:26:59.879ZApr 10 04:26:59.855 DEBG [sc] cr1: logging in
4872026-04-10T04:27:00.244ZApr 10 04:27:00.233 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-04-10T04:27:02.367ZApr 10 04:27:02.355 INFO cr1: executing frr script show bgp json
4892026-04-10T04:27:02.367ZApr 10 04:27:02.355 DEBG [sc] cr1: starting
4902026-04-10T04:27:02.367ZApr 10 04:27:02.355 DEBG [sc] cr1: connecting to [::1]:60526
4912026-04-10T04:27:02.392ZApr 10 04:27:02.356 DEBG [sc] cr1 waiting for prompt
4922026-04-10T04:27:02.392ZApr 10 04:27:02.366 DEBG [sc] cr1: logging in
4932026-04-10T04:27:02.510ZApr 10 04:27:02.498 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-04-10T04:27:04.640ZApr 10 04:27:04.628 INFO cr2: executing eos script show ip bgp | json
4952026-04-10T04:27:04.640ZApr 10 04:27:04.629 DEBG [sc] cr2: starting
4962026-04-10T04:27:04.640ZApr 10 04:27:04.629 DEBG [sc] cr2: connecting to [::1]:37112
4972026-04-10T04:27:04.664ZApr 10 04:27:04.630 DEBG [sc] cr2 waiting for prompt
4982026-04-10T04:27:04.664ZApr 10 04:27:04.641 DEBG [sc] cr2: logging in
4992026-04-10T04:27:05.001ZApr 10 04:27:04.989 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-04-10T04:27:07.363ZApr 10 04:27:07.352 INFO cr2: executing eos script show ipv6 bgp | json
5012026-04-10T04:27:07.363ZApr 10 04:27:07.352 DEBG [sc] cr2: starting
5022026-04-10T04:27:07.363ZApr 10 04:27:07.352 DEBG [sc] cr2: connecting to [::1]:37112
5032026-04-10T04:27:07.387ZApr 10 04:27:07.353 DEBG [sc] cr2 waiting for prompt
5042026-04-10T04:27:07.388ZApr 10 04:27:07.363 DEBG [sc] cr2: logging in
5052026-04-10T04:27:07.506ZApr 10 04:27:07.495 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-04-10T04:27:09.876ZApr 10 04:27:09.864 INFO trio bgp unnumbered test passed 🎉
5072026-04-10T04:27:09.876ZApr 10 04:27:09.864 INFO destroying runner for deployment mgtriou
5082026-04-10T04:27:09.876ZApr 10 04:27:09.864 INFO destroying deployment mgtriou
5092026-04-10T04:27:09.876ZApr 10 04:27:09.864 INFO destroying nodes
5102026-04-10T04:27:09.970ZApr 10 04:27:09.958 INFO destroying links
5112026-04-10T04:27:09.970ZApr 10 04:27:09.958 INFO destroying link mgtriou_ox_sn_vnic0
5122026-04-10T04:27:09.994ZApr 10 04:27:09.961 INFO destroying link mgtriou_ox_sn_sim0
5132026-04-10T04:27:09.994ZApr 10 04:27:09.964 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-04-10T04:27:10.978ZApr 10 04:27:10.967 INFO destroying link mgtriou_cr1_vn_sim0
5152026-04-10T04:27:11.003ZApr 10 04:27:10.968 INFO destroying link mgtriou_ox_sn_vnic1
5162026-04-10T04:27:11.003ZApr 10 04:27:10.969 INFO destroying link mgtriou_ox_sn_sim1
5172026-04-10T04:27:11.003ZApr 10 04:27:10.970 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-04-10T04:27:11.003ZApr 10 04:27:10.972 INFO destroying link mgtriou_cr2_vn_sim0
5192026-04-10T04:27:11.003ZApr 10 04:27:10.973 INFO destroying external links
5202026-04-10T04:27:11.003ZApr 10 04:27:10.973 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-04-10T04:27:11.003ZApr 10 04:27:10.974 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-04-10T04:27:11.003ZApr 10 04:27:10.975 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-04-10T04:27:11.003ZApr 10 04:27:10.977 INFO destroying images
5242026-04-10T04:27:11.463ZApr 10 04:27:11.452 INFO destroying workspace at .falcon
5252026-04-10T04:27:11.489Zprocess exited: duration 489829 ms, exit code 0
 
5262026-04-10T04:27:11.540Zfound 0 output files