01KMK9KJ6JXDKHBSFFWT2MXSQQ: falcon

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

Buildomat Job: 01KMK9MSQWGZMBE8M3RHXB95NS

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-03-25T20:24:35.207Zjob dependencies complete; ready to run (waiting for 18 m 48 s)
22026-03-25T20:32:46.088Zjob assigned to worker 01KMKB0A39FHSY8YV6S2HYM622 [factory edgar, gimlet/BRM42220060/261] (queued for 8 m 10 s)
32026-03-25T20:32:47.072Zdownloading input: /input/build/work/debug/ddmadm
42026-03-25T20:33:09.777Zdownloaded input: /input/build/work/debug/ddmadm
52026-03-25T20:33:09.777Zdownloading input: /input/build/work/debug/ddmd
62026-03-25T20:33:36.603Zdownloaded input: /input/build/work/debug/ddmd
72026-03-25T20:33:36.603Zdownloading input: /input/build/work/debug/mgadm
82026-03-25T20:34:03.149Zdownloaded input: /input/build/work/debug/mgadm
92026-03-25T20:34:03.149Zdownloading input: /input/build/work/debug/mgd
102026-03-25T20:34:33.775Zdownloaded input: /input/build/work/debug/mgd
112026-03-25T20:34:33.799Zdownloading input: /input/build/work/release/ddmadm
122026-03-25T20:34:36.119Zdownloaded input: /input/build/work/release/ddmadm
132026-03-25T20:34:36.120Zdownloading input: /input/build/work/release/ddmd
142026-03-25T20:34:40.013Zdownloaded input: /input/build/work/release/ddmd
152026-03-25T20:34:40.013Zdownloading input: /input/build/work/release/falcon-lab
162026-03-25T20:34:42.190Zdownloaded input: /input/build/work/release/falcon-lab
172026-03-25T20:34:42.190Zdownloading input: /input/build/work/release/mgadm
182026-03-25T20:34:45.159Zdownloaded input: /input/build/work/release/mgadm
192026-03-25T20:34:45.159Zdownloading input: /input/build/work/release/mgd
202026-03-25T20:34:48.672Zdownloaded input: /input/build/work/release/mgd
212026-03-25T20:34:48.697Zdownloading input: /input/build-interop/work/testbed.tar.gz
222026-03-25T20:34:57.124Zdownloaded input: /input/build-interop/work/testbed.tar.gz
232026-03-25T20:34:57.124Zdownloading input: /input/build-interop/work/dhcp-server
242026-03-25T20:34:59.555Zdownloaded input: /input/build-interop/work/dhcp-server
 
252026-03-25T20:34:59.555Zstarting task 0: "setup"
262026-03-25T20:34:59.580Z++ uname -s
272026-03-25T20:34:59.580Z+ kern=SunOS
282026-03-25T20:34:59.580Z+ build_user=build
292026-03-25T20:34:59.580Z+ build_uid=12345
302026-03-25T20:34:59.580Z+ work_dir=/work
312026-03-25T20:34:59.580Z+ input_dir=/input
322026-03-25T20:34:59.580Z+ [[ 0 == 12345 ]]
332026-03-25T20:34:59.580Z+ case "$kern" in
342026-03-25T20:34:59.580Z+ groupadd -g 12345 build
352026-03-25T20:34:59.604Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-03-25T20:35:01.592Z+ zfs create -o mountpoint=/work rpool/work
372026-03-25T20:35:02.017Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-03-25T20:35:02.041Z+ home_fs=
392026-03-25T20:35:02.041Z+ [[ '' == autofs ]]
402026-03-25T20:35:02.041Z+ mkdir -p /home/build
412026-03-25T20:35:02.041Z+ chown build:build /home/build /work
422026-03-25T20:35:03.030Z+ chmod 0700 /home/build /work
432026-03-25T20:35:03.054Zprocess exited: duration 3478 ms, exit code 0
 
442026-03-25T20:35:03.103Zstarting task 1: "authentication"
452026-03-25T20:35:03.202Zprocess exited: duration 98 ms, exit code 0
 
462026-03-25T20:35:03.250Zstarting task 2: "build"
472026-03-25T20:35:03.274Z+ set -e
482026-03-25T20:35:03.275Z+ banner zpool
492026-03-25T20:35:03.275Z
502026-03-25T20:35:03.275Z ###### ##### #### #### #
512026-03-25T20:35:03.275Z # # # # # # # #
522026-03-25T20:35:03.275Z # # # # # # # #
532026-03-25T20:35:03.275Z # ##### # # # # #
542026-03-25T20:35:03.275Z # # # # # # #
552026-03-25T20:35:03.275Z ###### # #### #### ######
562026-03-25T20:35:03.275Z
572026-03-25T20:35:03.275Z++ pfexec diskinfo -pH
582026-03-25T20:35:03.299Z++ sort -k8 -n -r
592026-03-25T20:35:03.299Z++ head -1
602026-03-25T20:35:03.299Z++ awk '{print $2}'
612026-03-25T20:35:03.325Z+ DISK=c9t0014EE81000BC57Cd0
622026-03-25T20:35:03.325Z+ export DISK
632026-03-25T20:35:03.325Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-03-25T20:35:03.392Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-03-25T20:35:03.419Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-03-25T20:35:03.566Z+ [[ true =~ true ]]
672026-03-25T20:35:03.566Z+ pfexec zpool trim cpool
682026-03-25T20:35:03.590Z++ zpool status -t cpool
692026-03-25T20:35:03.615Z+ [[ ! pool: cpool
702026-03-25T20:35:03.615Z state: ONLINE
712026-03-25T20:35:03.615Z scan: none requested
722026-03-25T20:35:03.615Zconfig:
732026-03-25T20:35:03.615Z
742026-03-25T20:35:03.615Z NAME STATE READ WRITE CKSUM
752026-03-25T20:35:03.615Z cpool ONLINE 0 0 0
762026-03-25T20:35:03.615Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at March 25, 2026 at 08:35:03 PM UTC)
772026-03-25T20:35:03.615Z
782026-03-25T20:35:03.615Zerrors: No known data errors =~ 100% ]]
792026-03-25T20:35:03.615Z+ sleep 10
802026-03-25T20:35:13.689Z++ zpool status -t cpool
812026-03-25T20:35:13.714Z+ [[ ! pool: cpool
822026-03-25T20:35:13.714Z state: ONLINE
832026-03-25T20:35:13.714Z scan: none requested
842026-03-25T20:35:13.714Zconfig:
852026-03-25T20:35:13.714Z
862026-03-25T20:35:13.714Z NAME STATE READ WRITE CKSUM
872026-03-25T20:35:13.714Z cpool ONLINE 0 0 0
882026-03-25T20:35:13.714Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (68% trimmed, started at March 25, 2026 at 08:35:03 PM UTC)
892026-03-25T20:35:13.714Z
902026-03-25T20:35:13.714Zerrors: No known data errors =~ 100% ]]
912026-03-25T20:35:13.714Z+ sleep 10
922026-03-25T20:35:23.614Z++ zpool status -t cpool
932026-03-25T20:35:23.639Z+ [[ ! pool: cpool
942026-03-25T20:35:23.639Z state: ONLINE
952026-03-25T20:35:23.639Z scan: none requested
962026-03-25T20:35:23.639Zconfig:
972026-03-25T20:35:23.639Z
982026-03-25T20:35:23.639Z NAME STATE READ WRITE CKSUM
992026-03-25T20:35:23.639Z cpool ONLINE 0 0 0
1002026-03-25T20:35:23.639Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at March 25, 2026 at 08:35:18 PM UTC)
1012026-03-25T20:35:23.639Z
1022026-03-25T20:35:23.639Zerrors: No known data errors =~ 100% ]]
1032026-03-25T20:35:23.639Z+ pfexec chown 12345 /ci
1042026-03-25T20:35:23.639Z+ cd /ci
1052026-03-25T20:35:23.639Z+ export FALCON_DATASET=cpool/falcon
1062026-03-25T20:35:23.639Z+ FALCON_DATASET=cpool/falcon
1072026-03-25T20:35:23.639Z+ banner setup
1082026-03-25T20:35:23.639Z
1092026-03-25T20:35:23.639Z #### ###### ##### # # #####
1102026-03-25T20:35:23.639Z # # # # # # #
1112026-03-25T20:35:23.639Z #### ##### # # # # #
1122026-03-25T20:35:23.639Z # # # # # #####
1132026-03-25T20:35:23.639Z # # # # # # #
1142026-03-25T20:35:23.639Z #### ###### # #### #
1152026-03-25T20:35:23.639Z
1162026-03-25T20:35:23.639Z+ cp /input/build-interop/work/dhcp-server .
1172026-03-25T20:35:23.664Z+ cp /input/build/work/release/falcon-lab .
1182026-03-25T20:35:23.706Z+ cp /input/build/work/release/mgd .
1192026-03-25T20:35:23.780Z+ cp /input/build/work/release/ddmd .
1202026-03-25T20:35:23.865Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-03-25T20:35:23.889Z+ mkdir -p cargo-bay
1222026-03-25T20:35:23.890Z+ mv mgd cargo-bay/
1232026-03-25T20:35:23.890Z+ mv ddmd cargo-bay/
1242026-03-25T20:35:23.890Z+ export EXT_INTERFACE=igb0
1252026-03-25T20:35:23.890Z+ EXT_INTERFACE=igb0
1262026-03-25T20:35:23.890Z++ bmat address ls -f extra -Ho first
1272026-03-25T20:35:23.914Z+ first=10.151.7.164
1282026-03-25T20:35:23.914Z++ bmat address ls -f extra -Ho last
1292026-03-25T20:35:23.939Z+ last=10.151.7.227
1302026-03-25T20:35:23.939Z++ bmat address ls -f extra -Ho gateway
1312026-03-25T20:35:23.963Z+ gw=10.151.7.1
1322026-03-25T20:35:23.963Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-03-25T20:35:23.988Z++ sed 's#/.*##g'
1342026-03-25T20:35:23.988Z+ server=10.151.7.100
1352026-03-25T20:35:23.988Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1362026-03-25T20:35:23.988Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-03-25T20:35:23.988Z+ error=
1382026-03-25T20:35:23.988Z+ RUST_LOG=debug
1392026-03-25T20:35:23.988Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-03-25T20:35:24.013ZMar 25 20:35:23.991 DEBG using default route interface igb0
1412026-03-25T20:35:24.013ZMar 25 20:35:23.991 DEBG using default route interface igb0
1422026-03-25T20:35:24.013ZMar 25 20:35:23.991 DEBG using default route interface igb0
1432026-03-25T20:35:24.013ZMar 25 20:35:23.991 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-03-25T20:35:24.013ZMar 25 20:35:23.991 INFO starting preflight for deployment mgtriou
1452026-03-25T20:35:24.013ZMar 25 20:35:23.991 INFO propolis-server binary not found
1462026-03-25T20:35:24.013ZMar 25 20:35:23.991 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-03-25T20:35:27.910ZMar 25 20:35:27.898 INFO ovmf fd not found
1482026-03-25T20:35:27.910ZMar 25 20:35:27.898 INFO downloading ovmf
1492026-03-25T20:35:28.346ZMar 25 20:35:28.335 INFO base image for helios-2.9 does not exist, attempting to install
1502026-03-25T20:35:28.346ZMar 25 20:35:28.335 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-03-25T20:35:56.513ZMar 25 20:35:56.504 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-03-25T20:36:30.003ZMar 25 20:36:29.993 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-03-25T20:36:30.054ZMar 25 20:36:30.044 INFO copying image data to zvol
1542026-03-25T20:36:40.424ZMar 25 20:36:40.417 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-03-25T20:36:45.255ZMar 25 20:36:45.248 INFO base image for debian-13.2 does not exist, attempting to install
1562026-03-25T20:36:45.255ZMar 25 20:36:45.248 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-03-25T20:37:05.615ZMar 25 20:37:05.609 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-03-25T20:37:26.468ZMar 25 20:37:26.462 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-03-25T20:37:26.493ZMar 25 20:37:26.488 INFO copying image data to zvol
1602026-03-25T20:37:33.417ZMar 25 20:37:33.411 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-03-25T20:37:36.682ZMar 25 20:37:36.677 INFO base image for eos-4.35 does not exist, attempting to install
1622026-03-25T20:37:36.682ZMar 25 20:37:36.677 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-03-25T20:38:27.642ZMar 25 20:38:27.638 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-03-25T20:39:50.675ZMar 25 20:39:50.671 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-03-25T20:39:50.699ZMar 25 20:39:50.695 INFO copying image data to zvol
1662026-03-25T20:40:39.277ZMar 25 20:40:39.273 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-03-25T20:40:42.577ZMar 25 20:40:42.573 INFO creating links
1682026-03-25T20:40:42.577ZMar 25 20:40:42.573 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-03-25T20:40:42.578ZMar 25 20:40:42.574 DEBG destroying link mgtriou_ox_sn_sim0
1702026-03-25T20:40:42.578ZMar 25 20:40:42.574 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-03-25T20:40:42.603ZMar 25 20:40:42.576 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-03-25T20:40:42.603ZMar 25 20:40:42.584 DEBG link pair created
1732026-03-25T20:40:42.603ZMar 25 20:40:42.584 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-03-25T20:40:42.603ZMar 25 20:40:42.584 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-03-25T20:40:42.604ZMar 25 20:40:42.584 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-03-25T20:40:42.604ZMar 25 20:40:42.586 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-03-25T20:40:42.604ZMar 25 20:40:42.593 DEBG link pair created
1782026-03-25T20:40:42.604ZMar 25 20:40:42.593 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-03-25T20:40:42.604ZMar 25 20:40:42.593 DEBG destroying link mgtriou_ox_sn_sim1
1802026-03-25T20:40:42.604ZMar 25 20:40:42.593 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-03-25T20:40:42.604ZMar 25 20:40:42.594 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-03-25T20:40:42.630ZMar 25 20:40:42.601 DEBG link pair created
1832026-03-25T20:40:42.630ZMar 25 20:40:42.601 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-03-25T20:40:42.630ZMar 25 20:40:42.601 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-03-25T20:40:42.630ZMar 25 20:40:42.601 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-03-25T20:40:42.630ZMar 25 20:40:42.603 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-03-25T20:40:42.630ZMar 25 20:40:42.609 DEBG link pair created
1882026-03-25T20:40:42.630ZMar 25 20:40:42.609 INFO creating external links
1892026-03-25T20:40:42.630ZMar 25 20:40:42.609 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-03-25T20:40:42.631ZMar 25 20:40:42.609 INFO creating external link mgtriou_ox_vn_vnic2
1912026-03-25T20:40:42.631ZMar 25 20:40:42.610 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-03-25T20:40:42.631ZMar 25 20:40:42.610 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-03-25T20:40:42.631ZMar 25 20:40:42.610 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-03-25T20:40:42.631ZMar 25 20:40:42.612 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-03-25T20:40:42.631ZMar 25 20:40:42.612 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-03-25T20:40:42.631ZMar 25 20:40:42.612 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-03-25T20:40:42.631ZMar 25 20:40:42.613 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-03-25T20:40:42.631ZMar 25 20:40:42.613 INFO creating nodes
1992026-03-25T20:40:42.631ZMar 25 20:40:42.613 INFO ox: launching node
2002026-03-25T20:40:42.631ZMar 25 20:40:42.616 INFO cr1: launching node
2012026-03-25T20:40:42.631ZMar 25 20:40:42.617 INFO cr2: launching node
2022026-03-25T20:40:42.657ZMar 25 20:40:42.643 INFO launched instance ox with pid 856 on port 61559
2032026-03-25T20:40:42.657ZMar 25 20:40:42.643 INFO ox: instance ensure
2042026-03-25T20:40:42.657ZMar 25 20:40:42.643 INFO launched instance cr1 with pid 857 on port 54340
2052026-03-25T20:40:42.657ZMar 25 20:40:42.643 INFO cr1: instance ensure
2062026-03-25T20:40:42.657ZMar 25 20:40:42.644 INFO launched instance cr2 with pid 858 on port 64901
2072026-03-25T20:40:42.657ZMar 25 20:40:42.644 INFO cr2: instance ensure
2082026-03-25T20:40:44.986ZMar 25 20:40:44.982 INFO ox: instance run
2092026-03-25T20:40:45.012ZMar 25 20:40:44.983 DEBG [sc] ox: starting
2102026-03-25T20:40:45.012ZMar 25 20:40:44.983 DEBG [sc] ox: connecting to [::1]:61559
2112026-03-25T20:40:45.012ZMar 25 20:40:44.984 DEBG [sc] ox waiting for prompt
2122026-03-25T20:40:45.074ZMar 25 20:40:45.070 INFO cr2: instance run
2132026-03-25T20:40:45.099ZMar 25 20:40:45.070 DEBG [sc] cr2: starting
2142026-03-25T20:40:45.099ZMar 25 20:40:45.070 DEBG [sc] cr2: connecting to [::1]:64901
2152026-03-25T20:40:45.099ZMar 25 20:40:45.071 DEBG [sc] cr2 waiting for prompt
2162026-03-25T20:40:45.125ZMar 25 20:40:45.102 INFO cr1: instance run
2172026-03-25T20:40:45.125ZMar 25 20:40:45.103 DEBG [sc] cr1: starting
2182026-03-25T20:40:45.125ZMar 25 20:40:45.103 DEBG [sc] cr1: connecting to [::1]:54340
2192026-03-25T20:40:45.125ZMar 25 20:40:45.104 DEBG [sc] cr1 waiting for prompt
2202026-03-25T20:41:02.216ZMar 25 20:41:02.213 DEBG [sc] cr1: logging in
2212026-03-25T20:41:02.577ZMar 25 20:41:02.573 INFO cr1: mounting /opt/cargo-bay
2222026-03-25T20:41:02.577ZMar 25 20:41:02.573 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-03-25T20:41:02.601ZMar 25 20:41:02.596 DEBG [sc] cr1: executing command `cd`
2242026-03-25T20:41:02.625ZMar 25 20:41:02.607 INFO cr1: finished mounting /opt/cargo-bay
2252026-03-25T20:41:02.626ZMar 25 20:41:02.607 DEBG [sc] cr1: executing command `hostname cr1`
2262026-03-25T20:41:02.626ZMar 25 20:41:02.618 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-03-25T20:41:02.650ZMar 25 20:41:02.629 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-03-25T20:41:02.650ZMar 25 20:41:02.640 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-03-25T20:41:02.688ZMar 25 20:41:02.651 INFO cr1: logging out
2302026-03-25T20:41:04.720ZMar 25 20:41:04.716 INFO cr1: logged out
2312026-03-25T20:41:05.549ZMar 25 20:41:05.545 DEBG [sc] ox: logging in
2322026-03-25T20:41:05.759ZMar 25 20:41:05.756 INFO ox: mounting /opt/cargo-bay
2332026-03-25T20:41:05.759ZMar 25 20:41:05.756 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-03-25T20:41:06.734ZMar 25 20:41:06.731 DEBG [sc] cr2: logging in
2352026-03-25T20:41:06.772ZMar 25 20:41:06.768 DEBG [sc] ox: executing command `cd`
2362026-03-25T20:41:06.796ZMar 25 20:41:06.779 INFO ox: finished mounting /opt/cargo-bay
2372026-03-25T20:41:06.796ZMar 25 20:41:06.779 DEBG [sc] ox: executing command `hostname ox`
2382026-03-25T20:41:06.796ZMar 25 20:41:06.789 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2392026-03-25T20:41:06.820ZMar 25 20:41:06.800 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2402026-03-25T20:41:06.820ZMar 25 20:41:06.812 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2412026-03-25T20:41:06.844ZMar 25 20:41:06.823 INFO ox: logging out
2422026-03-25T20:41:06.937ZMar 25 20:41:06.933 INFO ox: logged out
2432026-03-25T20:41:07.147ZMar 25 20:41:07.142 INFO cr2: mounting /opt/cargo-bay
2442026-03-25T20:41:07.147ZMar 25 20:41:07.142 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-03-25T20:41:07.171ZMar 25 20:41:07.163 DEBG [sc] cr2: executing command `cd`
2462026-03-25T20:41:07.195ZMar 25 20:41:07.174 INFO cr2: finished mounting /opt/cargo-bay
2472026-03-25T20:41:07.195ZMar 25 20:41:07.174 DEBG [sc] cr2: executing command `hostname cr2`
2482026-03-25T20:41:07.195ZMar 25 20:41:07.185 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-03-25T20:41:07.219ZMar 25 20:41:07.196 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-03-25T20:41:07.219ZMar 25 20:41:07.207 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-03-25T20:41:07.243ZMar 25 20:41:07.218 INFO cr2: logging out
2522026-03-25T20:41:10.144ZMar 25 20:41:10.141 INFO cr2: logged out
2532026-03-25T20:41:10.171ZMar 25 20:41:10.141 DEBG [sc] ox: starting
2542026-03-25T20:41:10.171ZMar 25 20:41:10.141 DEBG [sc] ox: connecting to [::1]:61559
2552026-03-25T20:41:10.171ZMar 25 20:41:10.142 DEBG [sc] ox waiting for prompt
2562026-03-25T20:41:10.171ZMar 25 20:41:10.153 DEBG [sc] ox: logging in
2572026-03-25T20:41:10.245ZMar 25 20:41:10.241 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-03-25T20:41:15.803ZMar 25 20:41:15.799 DEBG [sc] ox: starting
2592026-03-25T20:41:15.803ZMar 25 20:41:15.799 DEBG [sc] ox: connecting to [::1]:61559
2602026-03-25T20:41:15.827ZMar 25 20:41:15.800 DEBG [sc] ox waiting for prompt
2612026-03-25T20:41:15.827ZMar 25 20:41:15.810 DEBG [sc] ox: logging in
2622026-03-25T20:41:15.902ZMar 25 20:41:15.898 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-03-25T20:41:15.990ZMar 25 20:41:15.987 DEBG [sc] ox: starting
2642026-03-25T20:41:15.991ZMar 25 20:41:15.987 DEBG [sc] ox: connecting to [::1]:61559
2652026-03-25T20:41:16.015ZMar 25 20:41:15.987 DEBG [sc] ox waiting for prompt
2662026-03-25T20:41:16.015ZMar 25 20:41:15.998 DEBG [sc] ox: logging in
2672026-03-25T20:41:16.090ZMar 25 20:41:16.086 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-03-25T20:41:16.156ZMar 25 20:41:16.152 INFO cr1: installing frr
2692026-03-25T20:41:16.156ZMar 25 20:41:16.152 INFO waiting for ceos to initialize
2702026-03-25T20:41:16.156ZMar 25 20:41:16.152 INFO ox: setting up npuvm
2712026-03-25T20:41:16.180ZMar 25 20:41:16.152 DEBG [sc] cr1: starting
2722026-03-25T20:41:16.180ZMar 25 20:41:16.152 DEBG [sc] cr1: connecting to [::1]:54340
2732026-03-25T20:41:16.180ZMar 25 20:41:16.152 DEBG [sc] ox: starting
2742026-03-25T20:41:16.180ZMar 25 20:41:16.152 DEBG [sc] ox: connecting to [::1]:61559
2752026-03-25T20:41:16.180ZMar 25 20:41:16.152 DEBG [sc] cr2: starting
2762026-03-25T20:41:16.180ZMar 25 20:41:16.152 DEBG [sc] cr2: connecting to [::1]:64901
2772026-03-25T20:41:16.180ZMar 25 20:41:16.153 DEBG [sc] ox waiting for prompt
2782026-03-25T20:41:16.180ZMar 25 20:41:16.153 DEBG [sc] cr1 waiting for prompt
2792026-03-25T20:41:16.180ZMar 25 20:41:16.153 DEBG [sc] cr2 waiting for prompt
2802026-03-25T20:41:16.180ZMar 25 20:41:16.163 DEBG [sc] ox: logging in
2812026-03-25T20:41:16.180ZMar 25 20:41:16.163 DEBG [sc] cr2: logging in
2822026-03-25T20:41:16.180ZMar 25 20:41:16.164 DEBG [sc] cr1: logging in
2832026-03-25T20:41:16.255ZMar 25 20:41:16.251 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-03-25T20:41:16.331ZMar 25 20:41:16.306 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-03-25T20:41:16.518ZMar 25 20:41:16.514 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-03-25T20:41:18.568ZMar 25 20:41:18.564 INFO cr2: executing eos script show version
2872026-03-25T20:41:18.568ZMar 25 20:41:18.565 DEBG [sc] cr2: starting
2882026-03-25T20:41:18.569ZMar 25 20:41:18.565 DEBG [sc] cr2: connecting to [::1]:64901
2892026-03-25T20:41:18.594ZMar 25 20:41:18.565 DEBG [sc] cr2 waiting for prompt
2902026-03-25T20:41:18.594ZMar 25 20:41:18.575 DEBG [sc] cr2: logging in
2912026-03-25T20:41:18.711ZMar 25 20:41:18.707 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-03-25T20:41:21.711ZMar 25 20:41:21.707 DEBG [sc] ox: starting
2932026-03-25T20:41:21.711ZMar 25 20:41:21.707 DEBG [sc] ox: connecting to [::1]:61559
2942026-03-25T20:41:21.735ZMar 25 20:41:21.707 DEBG [sc] ox waiting for prompt
2952026-03-25T20:41:21.735ZMar 25 20:41:21.718 DEBG [sc] ox: logging in
2962026-03-25T20:41:21.809ZMar 25 20:41:21.805 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-03-25T20:41:21.875ZMar 25 20:41:21.871 DEBG [sc] ox: starting
2982026-03-25T20:41:21.876ZMar 25 20:41:21.871 DEBG [sc] ox: connecting to [::1]:61559
2992026-03-25T20:41:21.876ZMar 25 20:41:21.871 DEBG [sc] ox waiting for prompt
3002026-03-25T20:41:21.900ZMar 25 20:41:21.883 DEBG [sc] ox: logging in
3012026-03-25T20:41:21.963ZMar 25 20:41:21.959 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-03-25T20:41:27.401ZMar 25 20:41:27.397 DEBG [sc] cr2: starting
3032026-03-25T20:41:27.401ZMar 25 20:41:27.397 DEBG [sc] cr2: connecting to [::1]:64901
3042026-03-25T20:41:27.426ZMar 25 20:41:27.398 DEBG [sc] cr2 waiting for prompt
3052026-03-25T20:41:27.426ZMar 25 20:41:27.408 DEBG [sc] cr2: logging in
3062026-03-25T20:41:27.544ZMar 25 20:41:27.540 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-03-25T20:41:29.669ZMar 25 20:41:29.663 INFO cr2: executing eos script show version
3082026-03-25T20:41:29.669ZMar 25 20:41:29.663 DEBG [sc] cr2: starting
3092026-03-25T20:41:29.669ZMar 25 20:41:29.663 DEBG [sc] cr2: connecting to [::1]:64901
3102026-03-25T20:41:29.693ZMar 25 20:41:29.663 DEBG [sc] cr2 waiting for prompt
3112026-03-25T20:41:29.693ZMar 25 20:41:29.675 DEBG [sc] cr2: logging in
3122026-03-25T20:41:29.822ZMar 25 20:41:29.818 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-03-25T20:41:32.157ZMar 25 20:41:32.152 DEBG [sc] cr2: starting
3142026-03-25T20:41:32.157ZMar 25 20:41:32.152 DEBG [sc] cr2: connecting to [::1]:64901
3152026-03-25T20:41:32.181ZMar 25 20:41:32.153 DEBG [sc] cr2 waiting for prompt
3162026-03-25T20:41:32.181ZMar 25 20:41:32.163 DEBG [sc] cr2: logging in
3172026-03-25T20:41:32.322ZMar 25 20:41:32.317 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-03-25T20:41:34.507ZMar 25 20:41:34.503 INFO cr2: executing eos script show version
3192026-03-25T20:41:34.507ZMar 25 20:41:34.503 DEBG [sc] cr2: starting
3202026-03-25T20:41:34.507ZMar 25 20:41:34.503 DEBG [sc] cr2: connecting to [::1]:64901
3212026-03-25T20:41:34.532ZMar 25 20:41:34.504 DEBG [sc] cr2 waiting for prompt
3222026-03-25T20:41:34.532ZMar 25 20:41:34.515 DEBG [sc] cr2: logging in
3232026-03-25T20:41:34.651ZMar 25 20:41:34.647 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-03-25T20:41:37.081ZMar 25 20:41:37.062 DEBG [sc] cr2: starting
3252026-03-25T20:41:37.081ZMar 25 20:41:37.062 DEBG [sc] cr2: connecting to [::1]:64901
3262026-03-25T20:41:37.081ZMar 25 20:41:37.063 DEBG [sc] cr2 waiting for prompt
3272026-03-25T20:41:37.081ZMar 25 20:41:37.074 DEBG [sc] cr2: logging in
3282026-03-25T20:41:37.232ZMar 25 20:41:37.228 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3292026-03-25T20:41:39.353ZMar 25 20:41:39.349 INFO cr2: executing eos script show version
3302026-03-25T20:41:39.353ZMar 25 20:41:39.349 DEBG [sc] cr2: starting
3312026-03-25T20:41:39.353ZMar 25 20:41:39.349 DEBG [sc] cr2: connecting to [::1]:64901
3322026-03-25T20:41:39.378ZMar 25 20:41:39.350 DEBG [sc] cr2 waiting for prompt
3332026-03-25T20:41:39.378ZMar 25 20:41:39.360 DEBG [sc] cr2: logging in
3342026-03-25T20:41:39.497ZMar 25 20:41:39.492 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3352026-03-25T20:41:41.812ZMar 25 20:41:41.808 INFO cr2: executing eos script
3362026-03-25T20:41:41.812Z enable
3372026-03-25T20:41:41.812Z configure
3382026-03-25T20:41:41.812Z ipv6 unicast-routing
3392026-03-25T20:41:41.812Z ip routing ipv6 interfaces
3402026-03-25T20:41:41.812Z ip routing
3412026-03-25T20:41:41.812Z ip route 1.2.3.0/24 null0
3422026-03-25T20:41:41.812Z ipv6 route fd99::/64 null0
3432026-03-25T20:41:41.812Z interface et1
3442026-03-25T20:41:41.812Z no switchport
3452026-03-25T20:41:41.812Z ipv6 enable
3462026-03-25T20:41:41.812Z
3472026-03-25T20:41:41.813Z router bgp 45
3482026-03-25T20:41:41.813Z router-id 1.2.3.1
3492026-03-25T20:41:41.813Z no bgp default ipv4-unicast
3502026-03-25T20:41:41.813Z timers bgp 2 6
3512026-03-25T20:41:41.813Z neighbor ebgp peer group
3522026-03-25T20:41:41.813Z neighbor ebgp remote-as 33
3532026-03-25T20:41:41.813Z neighbor interface Et1 peer-group ebgp
3542026-03-25T20:41:41.813Z address-family ipv4
3552026-03-25T20:41:41.813Z neighbor ebgp activate
3562026-03-25T20:41:41.813Z neighbor ebgp next-hop address-family ipv6 originate
3572026-03-25T20:41:41.813Z network 1.2.3.0/24
3582026-03-25T20:41:41.813Z exit
3592026-03-25T20:41:41.813Z address-family ipv6
3602026-03-25T20:41:41.813Z neighbor ebgp activate
3612026-03-25T20:41:41.813Z neighbor ebgp next-hop address-family ipv6 originate
3622026-03-25T20:41:41.813Z network fd99::/64
3632026-03-25T20:41:41.813Z exit
3642026-03-25T20:41:41.813Z exit
3652026-03-25T20:41:41.813Z
3662026-03-25T20:41:41.813ZMar 25 20:41:41.808 DEBG [sc] cr2: starting
3672026-03-25T20:41:41.813ZMar 25 20:41:41.808 DEBG [sc] cr2: connecting to [::1]:64901
3682026-03-25T20:41:41.838ZMar 25 20:41:41.808 DEBG [sc] cr2 waiting for prompt
3692026-03-25T20:41:41.838ZMar 25 20:41:41.819 DEBG [sc] cr2: logging in
3702026-03-25T20:41:41.976ZMar 25 20:41:41.972 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3712026-03-25T20:41:41.977Z enable
3722026-03-25T20:41:41.977Z configure
3732026-03-25T20:41:41.977Z ipv6 unicast-routing
3742026-03-25T20:41:41.977Z ip routing ipv6 interfaces
3752026-03-25T20:41:41.977Z ip routing
3762026-03-25T20:41:41.977Z ip route 1.2.3.0/24 null0
3772026-03-25T20:41:41.977Z ipv6 route fd99::/64 null0
3782026-03-25T20:41:41.977Z interface et1
3792026-03-25T20:41:41.977Z no switchport
3802026-03-25T20:41:41.977Z ipv6 enable
3812026-03-25T20:41:41.977Z
3822026-03-25T20:41:41.977Z router bgp 45
3832026-03-25T20:41:41.977Z router-id 1.2.3.1
3842026-03-25T20:41:41.977Z no bgp default ipv4-unicast
3852026-03-25T20:41:41.977Z timers bgp 2 6
3862026-03-25T20:41:41.977Z neighbor ebgp peer group
3872026-03-25T20:41:41.977Z neighbor ebgp remote-as 33
3882026-03-25T20:41:41.977Z neighbor interface Et1 peer-group ebgp
3892026-03-25T20:41:41.977Z address-family ipv4
3902026-03-25T20:41:41.977Z neighbor ebgp activate
3912026-03-25T20:41:41.977Z neighbor ebgp next-hop address-family ipv6 originate
3922026-03-25T20:41:41.977Z network 1.2.3.0/24
3932026-03-25T20:41:41.977Z exit
3942026-03-25T20:41:41.977Z address-family ipv6
3952026-03-25T20:41:41.977Z neighbor ebgp activate
3962026-03-25T20:41:41.977Z neighbor ebgp next-hop address-family ipv6 originate
3972026-03-25T20:41:41.977Z network fd99::/64
3982026-03-25T20:41:41.977Z exit
3992026-03-25T20:41:41.977Z exit
4002026-03-25T20:41:41.977Z '`
4012026-03-25T20:41:54.060ZMar 25 20:41:54.055 INFO cr1: enabling frr daemon bgpd
4022026-03-25T20:41:54.060ZMar 25 20:41:54.055 DEBG [sc] cr1: starting
4032026-03-25T20:41:54.060ZMar 25 20:41:54.055 DEBG [sc] cr1: connecting to [::1]:54340
4042026-03-25T20:41:54.084ZMar 25 20:41:54.056 DEBG [sc] cr1 waiting for prompt
4052026-03-25T20:41:54.085ZMar 25 20:41:54.066 DEBG [sc] cr1: logging in
4062026-03-25T20:41:54.237ZMar 25 20:41:54.232 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
4072026-03-25T20:41:56.314ZMar 25 20:41:56.309 DEBG [sc] cr1: starting
4082026-03-25T20:41:56.314ZMar 25 20:41:56.309 DEBG [sc] cr1: connecting to [::1]:54340
4092026-03-25T20:41:56.338ZMar 25 20:41:56.310 DEBG [sc] cr1 waiting for prompt
4102026-03-25T20:41:56.338ZMar 25 20:41:56.320 DEBG [sc] cr1: logging in
4112026-03-25T20:41:56.446ZMar 25 20:41:56.441 DEBG [sc] cr1: executing command `systemctl restart frr`
4122026-03-25T20:42:04.048ZMar 25 20:42:04.043 INFO cr1: executing frr script
4132026-03-25T20:42:04.048Z configure
4142026-03-25T20:42:04.048Z ip forwarding
4152026-03-25T20:42:04.048Z ipv6 forwarding
4162026-03-25T20:42:04.048Z ip route 1.2.3.0/24 null0
4172026-03-25T20:42:04.048Z ipv6 route fd99::/64 null0
4182026-03-25T20:42:04.048Z route-map PERMIT-ALL permit 10
4192026-03-25T20:42:04.048Z router bgp 44
4202026-03-25T20:42:04.048Z timers bgp 2 6
4212026-03-25T20:42:04.048Z neighbor enp0s8 interface remote-as external
4222026-03-25T20:42:04.048Z neighbor enp0s8 timers connect 1
4232026-03-25T20:42:04.049Z address-family ipv4 unicast
4242026-03-25T20:42:04.049Z network 1.2.3.0/24
4252026-03-25T20:42:04.049Z neighbor enp0s8 activate
4262026-03-25T20:42:04.049Z neighbor enp0s8 route-map PERMIT-ALL out
4272026-03-25T20:42:04.049Z neighbor enp0s8 route-map PERMIT-ALL in
4282026-03-25T20:42:04.049Z exit-address-family
4292026-03-25T20:42:04.049Z address-family ipv6 unicast
4302026-03-25T20:42:04.049Z network fd99::/64
4312026-03-25T20:42:04.049Z neighbor enp0s8 activate
4322026-03-25T20:42:04.049Z neighbor enp0s8 route-map PERMIT-ALL out
4332026-03-25T20:42:04.049Z neighbor enp0s8 route-map PERMIT-ALL in
4342026-03-25T20:42:04.049Z exit-address-family
4352026-03-25T20:42:04.049Z exit
4362026-03-25T20:42:04.049Z
4372026-03-25T20:42:04.073ZMar 25 20:42:04.044 DEBG [sc] cr1: starting
4382026-03-25T20:42:04.074ZMar 25 20:42:04.044 DEBG [sc] cr1: connecting to [::1]:54340
4392026-03-25T20:42:04.074ZMar 25 20:42:04.044 DEBG [sc] cr1 waiting for prompt
4402026-03-25T20:42:04.074ZMar 25 20:42:04.055 DEBG [sc] cr1: logging in
4412026-03-25T20:42:04.204ZMar 25 20:42:04.199 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 ' '`
4422026-03-25T20:42:57.151ZMar 25 20:42:57.144 DEBG [sc] ox: starting
4432026-03-25T20:42:57.151ZMar 25 20:42:57.144 DEBG [sc] ox: connecting to [::1]:61559
4442026-03-25T20:42:57.175ZMar 25 20:42:57.145 DEBG [sc] ox waiting for prompt
4452026-03-25T20:42:57.175ZMar 25 20:42:57.154 DEBG [sc] ox: logging in
4462026-03-25T20:42:57.238ZMar 25 20:42:57.231 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4472026-03-25T20:42:57.563ZMar 25 20:42:57.556 DEBG [sc] ox: starting
4482026-03-25T20:42:57.563ZMar 25 20:42:57.556 DEBG [sc] ox: connecting to [::1]:61559
4492026-03-25T20:42:57.587ZMar 25 20:42:57.557 DEBG [sc] ox waiting for prompt
4502026-03-25T20:42:57.587ZMar 25 20:42:57.568 DEBG [sc] ox: logging in
4512026-03-25T20:42:57.663ZMar 25 20:42:57.657 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4522026-03-25T20:42:58.687ZMar 25 20:42:58.681 DEBG [sc] ox: starting
4532026-03-25T20:42:58.687ZMar 25 20:42:58.681 DEBG [sc] ox: connecting to [::1]:61559
4542026-03-25T20:42:58.712ZMar 25 20:42:58.681 DEBG [sc] ox waiting for prompt
4552026-03-25T20:42:58.712ZMar 25 20:42:58.681 DEBG [sc] ox: logging in
4562026-03-25T20:42:58.765ZMar 25 20:42:58.758 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4572026-03-25T20:42:58.821ZMar 25 20:42:58.814 DEBG [sc] ox: starting
4582026-03-25T20:42:58.821ZMar 25 20:42:58.814 DEBG [sc] ox: connecting to [::1]:61559
4592026-03-25T20:42:58.845ZMar 25 20:42:58.815 DEBG [sc] ox waiting for prompt
4602026-03-25T20:42:58.845ZMar 25 20:42:58.826 DEBG [sc] ox: logging in
4612026-03-25T20:42:58.910ZMar 25 20:42:58.903 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4622026-03-25T20:42:58.964ZMar 25 20:42:58.958 DEBG [sc] ox: starting
4632026-03-25T20:42:58.964ZMar 25 20:42:58.958 DEBG [sc] ox: connecting to [::1]:61559
4642026-03-25T20:42:58.989ZMar 25 20:42:58.958 DEBG [sc] ox waiting for prompt
4652026-03-25T20:42:58.989ZMar 25 20:42:58.969 DEBG [sc] ox: logging in
4662026-03-25T20:42:59.052ZMar 25 20:42:59.045 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4672026-03-25T20:42:59.108ZMar 25 20:42:59.102 DEBG [sc] ox: starting
4682026-03-25T20:42:59.108ZMar 25 20:42:59.102 DEBG [sc] ox: connecting to [::1]:61559
4692026-03-25T20:42:59.133ZMar 25 20:42:59.102 DEBG [sc] ox waiting for prompt
4702026-03-25T20:42:59.133ZMar 25 20:42:59.112 DEBG [sc] ox: logging in
4712026-03-25T20:42:59.197ZMar 25 20:42:59.190 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4722026-03-25T20:42:59.253ZMar 25 20:42:59.246 DEBG [sc] ox: starting
4732026-03-25T20:42:59.253ZMar 25 20:42:59.246 DEBG [sc] ox: connecting to [::1]:61559
4742026-03-25T20:42:59.277ZMar 25 20:42:59.247 DEBG [sc] ox waiting for prompt
4752026-03-25T20:42:59.277ZMar 25 20:42:59.257 DEBG [sc] ox: logging in
4762026-03-25T20:42:59.341ZMar 25 20:42:59.335 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4772026-03-25T20:42:59.397ZMar 25 20:42:59.390 DEBG [sc] ox: starting
4782026-03-25T20:42:59.397ZMar 25 20:42:59.390 DEBG [sc] ox: connecting to [::1]:61559
4792026-03-25T20:42:59.421ZMar 25 20:42:59.390 DEBG [sc] ox waiting for prompt
4802026-03-25T20:42:59.421ZMar 25 20:42:59.401 DEBG [sc] ox: logging in
4812026-03-25T20:42:59.485ZMar 25 20:42:59.478 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4822026-03-25T20:42:59.540ZMar 25 20:42:59.534 DEBG [sc] ox: starting
4832026-03-25T20:42:59.540ZMar 25 20:42:59.534 DEBG [sc] ox: connecting to [::1]:61559
4842026-03-25T20:42:59.564ZMar 25 20:42:59.534 DEBG [sc] ox waiting for prompt
4852026-03-25T20:42:59.565ZMar 25 20:42:59.544 DEBG [sc] ox: logging in
4862026-03-25T20:42:59.628ZMar 25 20:42:59.622 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4872026-03-25T20:42:59.683ZMar 25 20:42:59.676 DEBG [sc] ox: starting
4882026-03-25T20:42:59.683ZMar 25 20:42:59.676 DEBG [sc] ox: connecting to [::1]:61559
4892026-03-25T20:42:59.707ZMar 25 20:42:59.677 DEBG [sc] ox waiting for prompt
4902026-03-25T20:42:59.707ZMar 25 20:42:59.687 DEBG [sc] ox: logging in
4912026-03-25T20:42:59.772ZMar 25 20:42:59.765 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4922026-03-25T20:42:59.842ZMar 25 20:42:59.835 INFO adding BGP router to mgd
4932026-03-25T20:43:01.859ZMar 25 20:43:01.853 INFO cr1: executing frr script show ip bgp json
4942026-03-25T20:43:01.859ZMar 25 20:43:01.853 DEBG [sc] cr1: starting
4952026-03-25T20:43:01.859ZMar 25 20:43:01.853 DEBG [sc] cr1: connecting to [::1]:54340
4962026-03-25T20:43:01.884ZMar 25 20:43:01.853 DEBG [sc] cr1 waiting for prompt
4972026-03-25T20:43:01.884ZMar 25 20:43:01.864 DEBG [sc] cr1: logging in
4982026-03-25T20:43:02.187ZMar 25 20:43:02.181 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4992026-03-25T20:43:04.318ZMar 25 20:43:04.311 INFO cr1: executing frr script show bgp json
5002026-03-25T20:43:04.318ZMar 25 20:43:04.311 DEBG [sc] cr1: starting
5012026-03-25T20:43:04.318ZMar 25 20:43:04.311 DEBG [sc] cr1: connecting to [::1]:54340
5022026-03-25T20:43:04.343ZMar 25 20:43:04.312 DEBG [sc] cr1 waiting for prompt
5032026-03-25T20:43:04.343ZMar 25 20:43:04.321 DEBG [sc] cr1: logging in
5042026-03-25T20:43:04.461ZMar 25 20:43:04.453 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
5052026-03-25T20:43:06.594ZMar 25 20:43:06.587 INFO cr2: executing eos script show ip bgp | json
5062026-03-25T20:43:06.619ZMar 25 20:43:06.587 DEBG [sc] cr2: starting
5072026-03-25T20:43:06.619ZMar 25 20:43:06.587 DEBG [sc] cr2: connecting to [::1]:64901
5082026-03-25T20:43:06.619ZMar 25 20:43:06.588 DEBG [sc] cr2 waiting for prompt
5092026-03-25T20:43:06.619ZMar 25 20:43:06.599 DEBG [sc] cr2: logging in
5102026-03-25T20:43:06.918ZMar 25 20:43:06.911 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5112026-03-25T20:43:09.292ZMar 25 20:43:09.285 INFO cr2: executing eos script show ipv6 bgp | json
5122026-03-25T20:43:09.316ZMar 25 20:43:09.285 DEBG [sc] cr2: starting
5132026-03-25T20:43:09.316ZMar 25 20:43:09.285 DEBG [sc] cr2: connecting to [::1]:64901
5142026-03-25T20:43:09.316ZMar 25 20:43:09.286 DEBG [sc] cr2 waiting for prompt
5152026-03-25T20:43:09.316ZMar 25 20:43:09.296 DEBG [sc] cr2: logging in
5162026-03-25T20:43:09.447ZMar 25 20:43:09.440 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5172026-03-25T20:43:11.817ZMar 25 20:43:11.809 INFO trio bgp unnumbered test passed 🎉
5182026-03-25T20:43:11.842ZMar 25 20:43:11.810 INFO destroying runner for deployment mgtriou
5192026-03-25T20:43:11.842ZMar 25 20:43:11.810 INFO destroying deployment mgtriou
5202026-03-25T20:43:11.842ZMar 25 20:43:11.810 INFO destroying nodes
5212026-03-25T20:43:11.906ZMar 25 20:43:11.899 INFO destroying links
5222026-03-25T20:43:11.906ZMar 25 20:43:11.899 INFO destroying link mgtriou_ox_sn_vnic0
5232026-03-25T20:43:11.931ZMar 25 20:43:11.901 INFO destroying link mgtriou_ox_sn_sim0
5242026-03-25T20:43:11.931ZMar 25 20:43:11.903 INFO destroying link mgtriou_cr1_vn_vnic0
5252026-03-25T20:43:12.913ZMar 25 20:43:12.906 INFO destroying link mgtriou_cr1_vn_sim0
5262026-03-25T20:43:12.937ZMar 25 20:43:12.907 INFO destroying link mgtriou_ox_sn_vnic1
5272026-03-25T20:43:12.937ZMar 25 20:43:12.908 INFO destroying link mgtriou_ox_sn_sim1
5282026-03-25T20:43:12.937ZMar 25 20:43:12.909 INFO destroying link mgtriou_cr2_vn_vnic0
5292026-03-25T20:43:12.937ZMar 25 20:43:12.910 INFO destroying link mgtriou_cr2_vn_sim0
5302026-03-25T20:43:12.937ZMar 25 20:43:12.911 INFO destroying external links
5312026-03-25T20:43:12.937ZMar 25 20:43:12.911 INFO destroying external link mgtriou_ox_vn_vnic2
5322026-03-25T20:43:12.937ZMar 25 20:43:12.913 INFO destroying external link mgtriou_cr1_vn_vnic1
5332026-03-25T20:43:12.937ZMar 25 20:43:12.914 INFO destroying external link mgtriou_cr2_vn_vnic1
5342026-03-25T20:43:12.937ZMar 25 20:43:12.915 INFO destroying images
5352026-03-25T20:43:13.387ZMar 25 20:43:13.380 INFO destroying workspace at .falcon
5362026-03-25T20:43:13.411Zprocess exited: duration 490132 ms, exit code 0
 
5372026-03-25T20:43:13.465Zfound 0 output files