01KMZY063CSBDKX0N254MW86RK: falcon

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

Buildomat Job: 01KMZY0P49ZV0EBZCCD9KZEDXD

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-03-30T18:08:06.027Zjob dependencies complete; ready to run (waiting for 15 m 25 s)
22026-03-30T18:11:17.950Zjob assigned to worker 01KMZYWYBE00G2P8452Q8J464W [factory edgar, gimlet/BRM42220060/293] (queued for 3 m 11 s)
32026-03-30T18:11:18.436Zdownloading input: /input/build/work/debug/ddmadm
42026-03-30T18:11:41.142Zdownloaded input: /input/build/work/debug/ddmadm
52026-03-30T18:11:41.142Zdownloading input: /input/build/work/debug/ddmd
62026-03-30T18:12:08.073Zdownloaded input: /input/build/work/debug/ddmd
72026-03-30T18:12:08.100Zdownloading input: /input/build/work/debug/mgadm
82026-03-30T18:12:40.840Zdownloaded input: /input/build/work/debug/mgadm
92026-03-30T18:12:40.864Zdownloading input: /input/build/work/debug/mgd
102026-03-30T18:13:14.542Zdownloaded input: /input/build/work/debug/mgd
112026-03-30T18:13:14.566Zdownloading input: /input/build/work/release/ddmadm
122026-03-30T18:13:16.820Zdownloaded input: /input/build/work/release/ddmadm
132026-03-30T18:13:16.821Zdownloading input: /input/build/work/release/ddmd
142026-03-30T18:13:19.132Zdownloaded input: /input/build/work/release/ddmd
152026-03-30T18:13:19.156Zdownloading input: /input/build/work/release/falcon-lab
162026-03-30T18:13:20.866Zdownloaded input: /input/build/work/release/falcon-lab
172026-03-30T18:13:20.866Zdownloading input: /input/build/work/release/mgadm
182026-03-30T18:13:23.355Zdownloaded input: /input/build/work/release/mgadm
192026-03-30T18:13:23.355Zdownloading input: /input/build/work/release/mgd
202026-03-30T18:13:26.769Zdownloaded input: /input/build/work/release/mgd
212026-03-30T18:13:26.794Zdownloading input: /input/build-interop/work/testbed.tar.gz
222026-03-30T18:13:35.062Zdownloaded input: /input/build-interop/work/testbed.tar.gz
232026-03-30T18:13:35.062Zdownloading input: /input/build-interop/work/dhcp-server
242026-03-30T18:13:37.291Zdownloaded input: /input/build-interop/work/dhcp-server
 
252026-03-30T18:13:37.291Zstarting task 0: "setup"
262026-03-30T18:13:37.316Z++ uname -s
272026-03-30T18:13:37.316Z+ kern=SunOS
282026-03-30T18:13:37.316Z+ build_user=build
292026-03-30T18:13:37.316Z+ build_uid=12345
302026-03-30T18:13:37.316Z+ work_dir=/work
312026-03-30T18:13:37.316Z+ input_dir=/input
322026-03-30T18:13:37.316Z+ [[ 0 == 12345 ]]
332026-03-30T18:13:37.316Z+ case "$kern" in
342026-03-30T18:13:37.316Z+ groupadd -g 12345 build
352026-03-30T18:13:37.341Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-03-30T18:13:39.330Z+ zfs create -o mountpoint=/work rpool/work
372026-03-30T18:13:39.369Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-03-30T18:13:39.394Z+ home_fs=
392026-03-30T18:13:39.395Z+ [[ '' == autofs ]]
402026-03-30T18:13:39.395Z+ mkdir -p /home/build
412026-03-30T18:13:39.395Z+ chown build:build /home/build /work
422026-03-30T18:13:41.382Z+ chmod 0700 /home/build /work
432026-03-30T18:13:41.407Zprocess exited: duration 4093 ms, exit code 0
 
442026-03-30T18:13:41.458Zstarting task 1: "authentication"
452026-03-30T18:13:41.554Zprocess exited: duration 96 ms, exit code 0
 
462026-03-30T18:13:41.603Zstarting task 2: "build"
472026-03-30T18:13:41.629Z+ set -e
482026-03-30T18:13:41.629Z+ banner zpool
492026-03-30T18:13:41.629Z
502026-03-30T18:13:41.629Z ###### ##### #### #### #
512026-03-30T18:13:41.629Z # # # # # # # #
522026-03-30T18:13:41.629Z # # # # # # # #
532026-03-30T18:13:41.629Z # ##### # # # # #
542026-03-30T18:13:41.629Z # # # # # # #
552026-03-30T18:13:41.629Z ###### # #### #### ######
562026-03-30T18:13:41.629Z
572026-03-30T18:13:41.629Z++ pfexec diskinfo -pH
582026-03-30T18:13:41.630Z++ sort -k8 -n -r
592026-03-30T18:13:41.655Z++ head -1
602026-03-30T18:13:41.655Z++ awk '{print $2}'
612026-03-30T18:13:41.680Z+ DISK=c9t0014EE81000BC57Cd0
622026-03-30T18:13:41.680Z+ export DISK
632026-03-30T18:13:41.680Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-03-30T18:13:41.747Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-03-30T18:13:41.772Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-03-30T18:13:41.906Z+ [[ true =~ true ]]
672026-03-30T18:13:41.906Z+ pfexec zpool trim cpool
682026-03-30T18:13:41.931Z++ zpool status -t cpool
692026-03-30T18:13:41.956Z+ [[ ! pool: cpool
702026-03-30T18:13:41.956Z state: ONLINE
712026-03-30T18:13:41.956Z scan: none requested
722026-03-30T18:13:41.956Zconfig:
732026-03-30T18:13:41.956Z
742026-03-30T18:13:41.956Z NAME STATE READ WRITE CKSUM
752026-03-30T18:13:41.956Z cpool ONLINE 0 0 0
762026-03-30T18:13:41.956Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at March 30, 2026 at 06:13:41 PM UTC)
772026-03-30T18:13:41.956Z
782026-03-30T18:13:41.956Zerrors: No known data errors =~ 100% ]]
792026-03-30T18:13:41.956Z+ sleep 10
802026-03-30T18:13:51.941Z++ zpool status -t cpool
812026-03-30T18:13:51.966Z+ [[ ! pool: cpool
822026-03-30T18:13:51.966Z state: ONLINE
832026-03-30T18:13:51.966Z scan: none requested
842026-03-30T18:13:51.966Zconfig:
852026-03-30T18:13:51.966Z
862026-03-30T18:13:51.966Z NAME STATE READ WRITE CKSUM
872026-03-30T18:13:51.966Z cpool ONLINE 0 0 0
882026-03-30T18:13:51.966Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (69% trimmed, started at March 30, 2026 at 06:13:41 PM UTC)
892026-03-30T18:13:51.966Z
902026-03-30T18:13:51.966Zerrors: No known data errors =~ 100% ]]
912026-03-30T18:13:51.966Z+ sleep 10
922026-03-30T18:14:01.955Z++ zpool status -t cpool
932026-03-30T18:14:01.980Z+ [[ ! pool: cpool
942026-03-30T18:14:01.980Z state: ONLINE
952026-03-30T18:14:01.980Z scan: none requested
962026-03-30T18:14:01.980Zconfig:
972026-03-30T18:14:01.980Z
982026-03-30T18:14:01.980Z NAME STATE READ WRITE CKSUM
992026-03-30T18:14:01.980Z cpool ONLINE 0 0 0
1002026-03-30T18:14:01.980Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at March 30, 2026 at 06:13:56 PM UTC)
1012026-03-30T18:14:01.980Z
1022026-03-30T18:14:01.980Zerrors: No known data errors =~ 100% ]]
1032026-03-30T18:14:01.980Z+ pfexec chown 12345 /ci
1042026-03-30T18:14:01.980Z+ cd /ci
1052026-03-30T18:14:01.980Z+ export FALCON_DATASET=cpool/falcon
1062026-03-30T18:14:01.980Z+ FALCON_DATASET=cpool/falcon
1072026-03-30T18:14:01.980Z+ banner setup
1082026-03-30T18:14:01.980Z
1092026-03-30T18:14:01.980Z #### ###### ##### # # #####
1102026-03-30T18:14:01.980Z # # # # # # #
1112026-03-30T18:14:01.980Z #### ##### # # # # #
1122026-03-30T18:14:01.981Z # # # # # #####
1132026-03-30T18:14:01.981Z # # # # # # #
1142026-03-30T18:14:01.981Z #### ###### # #### #
1152026-03-30T18:14:01.981Z
1162026-03-30T18:14:01.981Z+ cp /input/build-interop/work/dhcp-server .
1172026-03-30T18:14:02.007Z+ cp /input/build/work/release/falcon-lab .
1182026-03-30T18:14:02.055Z+ cp /input/build/work/release/mgd .
1192026-03-30T18:14:02.134Z+ cp /input/build/work/release/ddmd .
1202026-03-30T18:14:02.203Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-03-30T18:14:02.227Z+ mkdir -p cargo-bay
1222026-03-30T18:14:02.227Z+ mv mgd cargo-bay/
1232026-03-30T18:14:02.227Z+ mv ddmd cargo-bay/
1242026-03-30T18:14:02.227Z+ export EXT_INTERFACE=igb0
1252026-03-30T18:14:02.227Z+ EXT_INTERFACE=igb0
1262026-03-30T18:14:02.227Z++ bmat address ls -f extra -Ho first
1272026-03-30T18:14:02.251Z+ first=10.151.7.164
1282026-03-30T18:14:02.275Z++ bmat address ls -f extra -Ho last
1292026-03-30T18:14:02.275Z+ last=10.151.7.227
1302026-03-30T18:14:02.275Z++ bmat address ls -f extra -Ho gateway
1312026-03-30T18:14:02.299Z+ gw=10.151.7.1
1322026-03-30T18:14:02.299Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-03-30T18:14:02.323Z++ sed 's#/.*##g'
1342026-03-30T18:14:02.323Z+ server=10.151.7.100
1352026-03-30T18:14:02.324Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1362026-03-30T18:14:02.324Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-03-30T18:14:02.324Z+ error=
1382026-03-30T18:14:02.324Z+ RUST_LOG=debug
1392026-03-30T18:14:02.324Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-03-30T18:14:02.348ZMar 30 18:14:02.323 DEBG using default route interface igb0
1412026-03-30T18:14:02.348ZMar 30 18:14:02.324 DEBG using default route interface igb0
1422026-03-30T18:14:02.348ZMar 30 18:14:02.324 DEBG using default route interface igb0
1432026-03-30T18:14:02.348ZMar 30 18:14:02.324 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-03-30T18:14:02.348ZMar 30 18:14:02.324 INFO starting preflight for deployment mgtriou
1452026-03-30T18:14:02.348ZMar 30 18:14:02.324 INFO propolis-server binary not found
1462026-03-30T18:14:02.348ZMar 30 18:14:02.324 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-03-30T18:14:06.104ZMar 30 18:14:06.091 INFO ovmf fd not found
1482026-03-30T18:14:06.104ZMar 30 18:14:06.091 INFO downloading ovmf
1492026-03-30T18:14:06.483ZMar 30 18:14:06.469 INFO base image for helios-2.9 does not exist, attempting to install
1502026-03-30T18:14:06.483ZMar 30 18:14:06.469 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-03-30T18:14:38.925ZMar 30 18:14:38.911 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-03-30T18:15:12.395ZMar 30 18:15:12.380 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-03-30T18:15:12.446ZMar 30 18:15:12.432 INFO copying image data to zvol
1542026-03-30T18:15:22.977ZMar 30 18:15:22.963 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-03-30T18:15:26.294ZMar 30 18:15:26.279 INFO base image for debian-13.2 does not exist, attempting to install
1562026-03-30T18:15:26.294ZMar 30 18:15:26.279 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-03-30T18:15:47.230ZMar 30 18:15:47.216 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-03-30T18:16:08.046ZMar 30 18:16:08.031 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-03-30T18:16:08.073ZMar 30 18:16:08.058 INFO copying image data to zvol
1602026-03-30T18:16:14.593ZMar 30 18:16:14.579 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-03-30T18:16:18.161ZMar 30 18:16:18.146 INFO base image for eos-4.35 does not exist, attempting to install
1622026-03-30T18:16:18.161ZMar 30 18:16:18.146 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-03-30T18:17:00.579ZMar 30 18:17:00.564 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-03-30T18:18:23.804ZMar 30 18:18:23.789 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-03-30T18:18:23.828ZMar 30 18:18:23.814 INFO copying image data to zvol
1662026-03-30T18:19:12.731ZMar 30 18:19:12.717 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-03-30T18:19:16.123ZMar 30 18:19:16.109 INFO creating links
1682026-03-30T18:19:16.123ZMar 30 18:19:16.109 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-03-30T18:19:16.147ZMar 30 18:19:16.109 DEBG destroying link mgtriou_ox_sn_sim0
1702026-03-30T18:19:16.147ZMar 30 18:19:16.109 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-03-30T18:19:16.147ZMar 30 18:19:16.111 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-03-30T18:19:16.147ZMar 30 18:19:16.118 DEBG link pair created
1732026-03-30T18:19:16.147ZMar 30 18:19:16.118 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-03-30T18:19:16.147ZMar 30 18:19:16.118 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-03-30T18:19:16.148ZMar 30 18:19:16.118 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-03-30T18:19:16.148ZMar 30 18:19:16.120 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-03-30T18:19:16.148ZMar 30 18:19:16.127 DEBG link pair created
1782026-03-30T18:19:16.148ZMar 30 18:19:16.127 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-03-30T18:19:16.148ZMar 30 18:19:16.127 DEBG destroying link mgtriou_ox_sn_sim1
1802026-03-30T18:19:16.148ZMar 30 18:19:16.127 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-03-30T18:19:16.148ZMar 30 18:19:16.129 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-03-30T18:19:16.172ZMar 30 18:19:16.136 DEBG link pair created
1832026-03-30T18:19:16.172ZMar 30 18:19:16.136 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-03-30T18:19:16.172ZMar 30 18:19:16.136 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-03-30T18:19:16.172ZMar 30 18:19:16.136 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-03-30T18:19:16.172ZMar 30 18:19:16.137 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-03-30T18:19:16.172ZMar 30 18:19:16.144 DEBG link pair created
1882026-03-30T18:19:16.172ZMar 30 18:19:16.144 INFO creating external links
1892026-03-30T18:19:16.172ZMar 30 18:19:16.144 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-03-30T18:19:16.172ZMar 30 18:19:16.144 INFO creating external link mgtriou_ox_vn_vnic2
1912026-03-30T18:19:16.172ZMar 30 18:19:16.146 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-03-30T18:19:16.172ZMar 30 18:19:16.146 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-03-30T18:19:16.172ZMar 30 18:19:16.146 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-03-30T18:19:16.173ZMar 30 18:19:16.147 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-03-30T18:19:16.173ZMar 30 18:19:16.147 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-03-30T18:19:16.173ZMar 30 18:19:16.147 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-03-30T18:19:16.173ZMar 30 18:19:16.148 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-03-30T18:19:16.173ZMar 30 18:19:16.148 INFO creating nodes
1992026-03-30T18:19:16.173ZMar 30 18:19:16.148 INFO ox: launching node
2002026-03-30T18:19:16.173ZMar 30 18:19:16.151 INFO cr1: launching node
2012026-03-30T18:19:16.173ZMar 30 18:19:16.153 INFO cr2: launching node
2022026-03-30T18:19:16.198ZMar 30 18:19:16.167 INFO launched instance ox with pid 841 on port 61611
2032026-03-30T18:19:16.198ZMar 30 18:19:16.167 INFO ox: instance ensure
2042026-03-30T18:19:16.198ZMar 30 18:19:16.167 INFO launched instance cr1 with pid 842 on port 39641
2052026-03-30T18:19:16.198ZMar 30 18:19:16.167 INFO cr1: instance ensure
2062026-03-30T18:19:16.198ZMar 30 18:19:16.168 INFO launched instance cr2 with pid 843 on port 42012
2072026-03-30T18:19:16.198ZMar 30 18:19:16.168 INFO cr2: instance ensure
2082026-03-30T18:19:18.501ZMar 30 18:19:18.487 INFO cr2: instance run
2092026-03-30T18:19:18.525ZMar 30 18:19:18.487 DEBG [sc] cr2: starting
2102026-03-30T18:19:18.525ZMar 30 18:19:18.487 DEBG [sc] cr2: connecting to [::1]:42012
2112026-03-30T18:19:18.525ZMar 30 18:19:18.488 DEBG [sc] cr2 waiting for prompt
2122026-03-30T18:19:18.549ZMar 30 18:19:18.520 INFO ox: instance run
2132026-03-30T18:19:18.549ZMar 30 18:19:18.520 DEBG [sc] ox: starting
2142026-03-30T18:19:18.549ZMar 30 18:19:18.520 DEBG [sc] ox: connecting to [::1]:61611
2152026-03-30T18:19:18.549ZMar 30 18:19:18.521 DEBG [sc] ox waiting for prompt
2162026-03-30T18:19:18.549ZMar 30 18:19:18.526 INFO cr1: instance run
2172026-03-30T18:19:18.549ZMar 30 18:19:18.526 DEBG [sc] cr1: starting
2182026-03-30T18:19:18.550ZMar 30 18:19:18.526 DEBG [sc] cr1: connecting to [::1]:39641
2192026-03-30T18:19:18.550ZMar 30 18:19:18.527 DEBG [sc] cr1 waiting for prompt
2202026-03-30T18:19:35.687ZMar 30 18:19:35.673 DEBG [sc] cr1: logging in
2212026-03-30T18:19:36.084ZMar 30 18:19:36.069 INFO cr1: mounting /opt/cargo-bay
2222026-03-30T18:19:36.084ZMar 30 18:19:36.069 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-03-30T18:19:36.117ZMar 30 18:19:36.080 DEBG [sc] cr1: executing command `cd`
2242026-03-30T18:19:36.117ZMar 30 18:19:36.091 INFO cr1: finished mounting /opt/cargo-bay
2252026-03-30T18:19:36.117ZMar 30 18:19:36.091 DEBG [sc] cr1: executing command `hostname cr1`
2262026-03-30T18:19:36.161ZMar 30 18:19:36.102 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-03-30T18:19:36.161ZMar 30 18:19:36.114 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-03-30T18:19:36.161ZMar 30 18:19:36.124 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-03-30T18:19:36.161ZMar 30 18:19:36.135 INFO cr1: logging out
2302026-03-30T18:19:38.214ZMar 30 18:19:38.199 INFO cr1: logged out
2312026-03-30T18:19:39.264ZMar 30 18:19:39.249 DEBG [sc] ox: logging in
2322026-03-30T18:19:39.479ZMar 30 18:19:39.461 INFO ox: mounting /opt/cargo-bay
2332026-03-30T18:19:39.479ZMar 30 18:19:39.461 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-03-30T18:19:40.080ZMar 30 18:19:40.064 DEBG [sc] cr2: logging in
2352026-03-30T18:19:40.508ZMar 30 18:19:40.492 INFO cr2: mounting /opt/cargo-bay
2362026-03-30T18:19:40.508ZMar 30 18:19:40.492 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2372026-03-30T18:19:40.538ZMar 30 18:19:40.499 DEBG [sc] ox: executing command `cd`
2382026-03-30T18:19:40.538ZMar 30 18:19:40.511 INFO ox: finished mounting /opt/cargo-bay
2392026-03-30T18:19:40.538ZMar 30 18:19:40.511 DEBG [sc] ox: executing command `hostname ox`
2402026-03-30T18:19:40.538ZMar 30 18:19:40.514 DEBG [sc] cr2: executing command `cd`
2412026-03-30T18:19:40.563ZMar 30 18:19:40.522 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2422026-03-30T18:19:40.563ZMar 30 18:19:40.526 INFO cr2: finished mounting /opt/cargo-bay
2432026-03-30T18:19:40.563ZMar 30 18:19:40.526 DEBG [sc] cr2: executing command `hostname cr2`
2442026-03-30T18:19:40.563ZMar 30 18:19:40.533 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2452026-03-30T18:19:40.563ZMar 30 18:19:40.537 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2462026-03-30T18:19:40.563ZMar 30 18:19:40.544 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2472026-03-30T18:19:40.563ZMar 30 18:19:40.548 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2482026-03-30T18:19:40.589ZMar 30 18:19:40.555 INFO ox: logging out
2492026-03-30T18:19:40.589ZMar 30 18:19:40.558 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2502026-03-30T18:19:40.589ZMar 30 18:19:40.569 INFO cr2: logging out
2512026-03-30T18:19:40.636ZMar 30 18:19:40.621 INFO ox: logged out
2522026-03-30T18:19:43.589ZMar 30 18:19:43.566 INFO cr2: logged out
2532026-03-30T18:19:43.639ZMar 30 18:19:43.566 DEBG [sc] ox: starting
2542026-03-30T18:19:43.639ZMar 30 18:19:43.566 DEBG [sc] ox: connecting to [::1]:61611
2552026-03-30T18:19:43.639ZMar 30 18:19:43.567 DEBG [sc] ox waiting for prompt
2562026-03-30T18:19:43.639ZMar 30 18:19:43.578 DEBG [sc] ox: logging in
2572026-03-30T18:19:43.689ZMar 30 18:19:43.666 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-03-30T18:19:49.167ZMar 30 18:19:49.151 DEBG [sc] ox: starting
2592026-03-30T18:19:49.167ZMar 30 18:19:49.151 DEBG [sc] ox: connecting to [::1]:61611
2602026-03-30T18:19:49.192ZMar 30 18:19:49.152 DEBG [sc] ox waiting for prompt
2612026-03-30T18:19:49.192ZMar 30 18:19:49.162 DEBG [sc] ox: logging in
2622026-03-30T18:19:49.268ZMar 30 18:19:49.251 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-03-30T18:19:49.344ZMar 30 18:19:49.329 DEBG [sc] ox: starting
2642026-03-30T18:19:49.344ZMar 30 18:19:49.329 DEBG [sc] ox: connecting to [::1]:61611
2652026-03-30T18:19:49.372ZMar 30 18:19:49.330 DEBG [sc] ox waiting for prompt
2662026-03-30T18:19:49.372ZMar 30 18:19:49.340 DEBG [sc] ox: logging in
2672026-03-30T18:19:49.446ZMar 30 18:19:49.428 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-03-30T18:19:49.513ZMar 30 18:19:49.495 INFO cr1: installing frr
2692026-03-30T18:19:49.513ZMar 30 18:19:49.495 INFO waiting for ceos to initialize
2702026-03-30T18:19:49.513ZMar 30 18:19:49.495 INFO ox: setting up npuvm
2712026-03-30T18:19:49.549ZMar 30 18:19:49.495 DEBG [sc] cr1: starting
2722026-03-30T18:19:49.549ZMar 30 18:19:49.495 DEBG [sc] cr1: connecting to [::1]:39641
2732026-03-30T18:19:49.549ZMar 30 18:19:49.495 DEBG [sc] cr2: starting
2742026-03-30T18:19:49.549ZMar 30 18:19:49.495 DEBG [sc] cr2: connecting to [::1]:42012
2752026-03-30T18:19:49.550ZMar 30 18:19:49.495 DEBG [sc] ox: starting
2762026-03-30T18:19:49.550ZMar 30 18:19:49.495 DEBG [sc] ox: connecting to [::1]:61611
2772026-03-30T18:19:49.550ZMar 30 18:19:49.496 DEBG [sc] cr1 waiting for prompt
2782026-03-30T18:19:49.550ZMar 30 18:19:49.496 DEBG [sc] cr2 waiting for prompt
2792026-03-30T18:19:49.551ZMar 30 18:19:49.496 DEBG [sc] ox waiting for prompt
2802026-03-30T18:19:49.551ZMar 30 18:19:49.505 DEBG [sc] ox: logging in
2812026-03-30T18:19:49.551ZMar 30 18:19:49.507 DEBG [sc] cr1: logging in
2822026-03-30T18:19:49.551ZMar 30 18:19:49.507 DEBG [sc] cr2: logging in
2832026-03-30T18:19:49.609ZMar 30 18:19:49.593 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-03-30T18:19:49.656ZMar 30 18:19:49.640 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-03-30T18:19:49.866ZMar 30 18:19:49.849 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-03-30T18:19:51.835ZMar 30 18:19:51.819 INFO cr2: executing eos script show version
2872026-03-30T18:19:51.835ZMar 30 18:19:51.819 DEBG [sc] cr2: starting
2882026-03-30T18:19:51.835ZMar 30 18:19:51.820 DEBG [sc] cr2: connecting to [::1]:42012
2892026-03-30T18:19:51.861ZMar 30 18:19:51.820 DEBG [sc] cr2 waiting for prompt
2902026-03-30T18:19:51.861ZMar 30 18:19:51.830 DEBG [sc] cr2: logging in
2912026-03-30T18:19:52.011ZMar 30 18:19:51.995 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-03-30T18:19:55.208ZMar 30 18:19:55.193 DEBG [sc] ox: starting
2932026-03-30T18:19:55.208ZMar 30 18:19:55.193 DEBG [sc] ox: connecting to [::1]:61611
2942026-03-30T18:19:55.278ZMar 30 18:19:55.194 DEBG [sc] ox waiting for prompt
2952026-03-30T18:19:55.278ZMar 30 18:19:55.204 DEBG [sc] ox: logging in
2962026-03-30T18:19:55.319ZMar 30 18:19:55.292 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-03-30T18:19:55.373ZMar 30 18:19:55.358 DEBG [sc] ox: starting
2982026-03-30T18:19:55.373ZMar 30 18:19:55.358 DEBG [sc] ox: connecting to [::1]:61611
2992026-03-30T18:19:55.403ZMar 30 18:19:55.358 DEBG [sc] ox waiting for prompt
3002026-03-30T18:19:55.403ZMar 30 18:19:55.368 DEBG [sc] ox: logging in
3012026-03-30T18:19:55.745ZMar 30 18:19:55.445 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-03-30T18:20:00.553ZMar 30 18:20:00.538 DEBG [sc] cr2: starting
3032026-03-30T18:20:00.553ZMar 30 18:20:00.538 DEBG [sc] cr2: connecting to [::1]:42012
3042026-03-30T18:20:00.578ZMar 30 18:20:00.539 DEBG [sc] cr2 waiting for prompt
3052026-03-30T18:20:00.578ZMar 30 18:20:00.549 DEBG [sc] cr2: logging in
3062026-03-30T18:20:00.827ZMar 30 18:20:00.692 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-03-30T18:20:03.196ZMar 30 18:20:02.809 INFO cr2: executing eos script show version
3082026-03-30T18:20:03.196ZMar 30 18:20:02.809 DEBG [sc] cr2: starting
3092026-03-30T18:20:03.196ZMar 30 18:20:02.809 DEBG [sc] cr2: connecting to [::1]:42012
3102026-03-30T18:20:03.315ZMar 30 18:20:02.809 DEBG [sc] cr2 waiting for prompt
3112026-03-30T18:20:03.315ZMar 30 18:20:02.819 DEBG [sc] cr2: logging in
3122026-03-30T18:20:03.315ZMar 30 18:20:02.973 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-03-30T18:20:05.268ZMar 30 18:20:05.254 DEBG [sc] cr2: starting
3142026-03-30T18:20:05.268ZMar 30 18:20:05.254 DEBG [sc] cr2: connecting to [::1]:42012
3152026-03-30T18:20:05.294ZMar 30 18:20:05.255 DEBG [sc] cr2 waiting for prompt
3162026-03-30T18:20:05.294ZMar 30 18:20:05.265 DEBG [sc] cr2: logging in
3172026-03-30T18:20:05.433ZMar 30 18:20:05.419 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-03-30T18:20:07.576ZMar 30 18:20:07.556 INFO cr2: executing eos script show version
3192026-03-30T18:20:07.576ZMar 30 18:20:07.556 DEBG [sc] cr2: starting
3202026-03-30T18:20:07.576ZMar 30 18:20:07.556 DEBG [sc] cr2: connecting to [::1]:42012
3212026-03-30T18:20:07.604ZMar 30 18:20:07.557 DEBG [sc] cr2 waiting for prompt
3222026-03-30T18:20:07.604ZMar 30 18:20:07.567 DEBG [sc] cr2: logging in
3232026-03-30T18:20:07.736ZMar 30 18:20:07.721 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-03-30T18:20:10.116ZMar 30 18:20:10.102 DEBG [sc] cr2: starting
3252026-03-30T18:20:10.116ZMar 30 18:20:10.102 DEBG [sc] cr2: connecting to [::1]:42012
3262026-03-30T18:20:10.141ZMar 30 18:20:10.102 DEBG [sc] cr2 waiting for prompt
3272026-03-30T18:20:10.141ZMar 30 18:20:10.113 DEBG [sc] cr2: logging in
3282026-03-30T18:20:10.261ZMar 30 18:20:10.247 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3292026-03-30T18:20:12.383ZMar 30 18:20:12.368 INFO cr2: executing eos script show version
3302026-03-30T18:20:12.383ZMar 30 18:20:12.368 DEBG [sc] cr2: starting
3312026-03-30T18:20:12.383ZMar 30 18:20:12.368 DEBG [sc] cr2: connecting to [::1]:42012
3322026-03-30T18:20:12.407ZMar 30 18:20:12.369 DEBG [sc] cr2 waiting for prompt
3332026-03-30T18:20:12.407ZMar 30 18:20:12.379 DEBG [sc] cr2: logging in
3342026-03-30T18:20:12.548ZMar 30 18:20:12.534 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3352026-03-30T18:20:14.874ZMar 30 18:20:14.859 INFO cr2: executing eos script
3362026-03-30T18:20:14.874Z enable
3372026-03-30T18:20:14.874Z configure
3382026-03-30T18:20:14.874Z ipv6 unicast-routing
3392026-03-30T18:20:14.874Z ip routing ipv6 interfaces
3402026-03-30T18:20:14.874Z ip routing
3412026-03-30T18:20:14.875Z ip route 1.2.3.0/24 null0
3422026-03-30T18:20:14.875Z ipv6 route fd99::/64 null0
3432026-03-30T18:20:14.876Z interface et1
3442026-03-30T18:20:14.876Z no switchport
3452026-03-30T18:20:14.876Z ipv6 enable
3462026-03-30T18:20:14.876Z
3472026-03-30T18:20:14.876Z router bgp 45
3482026-03-30T18:20:14.876Z router-id 1.2.3.1
3492026-03-30T18:20:14.876Z no bgp default ipv4-unicast
3502026-03-30T18:20:14.876Z timers bgp 2 6
3512026-03-30T18:20:14.876Z neighbor ebgp peer group
3522026-03-30T18:20:14.876Z neighbor ebgp remote-as 33
3532026-03-30T18:20:14.876Z neighbor interface Et1 peer-group ebgp
3542026-03-30T18:20:14.877Z address-family ipv4
3552026-03-30T18:20:14.877Z neighbor ebgp activate
3562026-03-30T18:20:14.877Z neighbor ebgp next-hop address-family ipv6 originate
3572026-03-30T18:20:14.877Z network 1.2.3.0/24
3582026-03-30T18:20:14.877Z exit
3592026-03-30T18:20:14.877Z address-family ipv6
3602026-03-30T18:20:14.877Z neighbor ebgp activate
3612026-03-30T18:20:14.877Z neighbor ebgp next-hop address-family ipv6 originate
3622026-03-30T18:20:14.877Z network fd99::/64
3632026-03-30T18:20:14.877Z exit
3642026-03-30T18:20:14.877Z exit
3652026-03-30T18:20:14.877Z
3662026-03-30T18:20:14.877ZMar 30 18:20:14.859 DEBG [sc] cr2: starting
3672026-03-30T18:20:14.878ZMar 30 18:20:14.859 DEBG [sc] cr2: connecting to [::1]:42012
3682026-03-30T18:20:14.904ZMar 30 18:20:14.859 DEBG [sc] cr2 waiting for prompt
3692026-03-30T18:20:14.904ZMar 30 18:20:14.869 DEBG [sc] cr2: logging in
3702026-03-30T18:20:15.028ZMar 30 18:20:15.013 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3712026-03-30T18:20:15.028Z enable
3722026-03-30T18:20:15.028Z configure
3732026-03-30T18:20:15.028Z ipv6 unicast-routing
3742026-03-30T18:20:15.028Z ip routing ipv6 interfaces
3752026-03-30T18:20:15.028Z ip routing
3762026-03-30T18:20:15.028Z ip route 1.2.3.0/24 null0
3772026-03-30T18:20:15.029Z ipv6 route fd99::/64 null0
3782026-03-30T18:20:15.029Z interface et1
3792026-03-30T18:20:15.029Z no switchport
3802026-03-30T18:20:15.029Z ipv6 enable
3812026-03-30T18:20:15.029Z
3822026-03-30T18:20:15.029Z router bgp 45
3832026-03-30T18:20:15.029Z router-id 1.2.3.1
3842026-03-30T18:20:15.029Z no bgp default ipv4-unicast
3852026-03-30T18:20:15.029Z timers bgp 2 6
3862026-03-30T18:20:15.029Z neighbor ebgp peer group
3872026-03-30T18:20:15.029Z neighbor ebgp remote-as 33
3882026-03-30T18:20:15.029Z neighbor interface Et1 peer-group ebgp
3892026-03-30T18:20:15.029Z address-family ipv4
3902026-03-30T18:20:15.029Z neighbor ebgp activate
3912026-03-30T18:20:15.029Z neighbor ebgp next-hop address-family ipv6 originate
3922026-03-30T18:20:15.029Z network 1.2.3.0/24
3932026-03-30T18:20:15.029Z exit
3942026-03-30T18:20:15.029Z address-family ipv6
3952026-03-30T18:20:15.029Z neighbor ebgp activate
3962026-03-30T18:20:15.029Z neighbor ebgp next-hop address-family ipv6 originate
3972026-03-30T18:20:15.030Z network fd99::/64
3982026-03-30T18:20:15.030Z exit
3992026-03-30T18:20:15.030Z exit
4002026-03-30T18:20:15.030Z '`
4012026-03-30T18:20:26.914ZMar 30 18:20:26.900 INFO cr1: enabling frr daemon bgpd
4022026-03-30T18:20:26.915ZMar 30 18:20:26.900 DEBG [sc] cr1: starting
4032026-03-30T18:20:26.915ZMar 30 18:20:26.900 DEBG [sc] cr1: connecting to [::1]:39641
4042026-03-30T18:20:26.939ZMar 30 18:20:26.900 DEBG [sc] cr1 waiting for prompt
4052026-03-30T18:20:26.940ZMar 30 18:20:26.911 DEBG [sc] cr1: logging in
4062026-03-30T18:20:27.068ZMar 30 18:20:27.054 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
4072026-03-30T18:20:29.140ZMar 30 18:20:29.125 DEBG [sc] cr1: starting
4082026-03-30T18:20:29.140ZMar 30 18:20:29.125 DEBG [sc] cr1: connecting to [::1]:39641
4092026-03-30T18:20:29.165ZMar 30 18:20:29.126 DEBG [sc] cr1 waiting for prompt
4102026-03-30T18:20:29.165ZMar 30 18:20:29.137 DEBG [sc] cr1: logging in
4112026-03-30T18:20:29.285ZMar 30 18:20:29.270 DEBG [sc] cr1: executing command `systemctl restart frr`
4122026-03-30T18:20:36.882ZMar 30 18:20:36.867 INFO cr1: executing frr script
4132026-03-30T18:20:36.882Z configure
4142026-03-30T18:20:36.882Z ip forwarding
4152026-03-30T18:20:36.882Z ipv6 forwarding
4162026-03-30T18:20:36.882Z ip route 1.2.3.0/24 null0
4172026-03-30T18:20:36.882Z ipv6 route fd99::/64 null0
4182026-03-30T18:20:36.882Z route-map PERMIT-ALL permit 10
4192026-03-30T18:20:36.882Z router bgp 44
4202026-03-30T18:20:36.882Z timers bgp 2 6
4212026-03-30T18:20:36.882Z neighbor enp0s8 interface remote-as external
4222026-03-30T18:20:36.882Z neighbor enp0s8 timers connect 1
4232026-03-30T18:20:36.882Z address-family ipv4 unicast
4242026-03-30T18:20:36.882Z network 1.2.3.0/24
4252026-03-30T18:20:36.882Z neighbor enp0s8 activate
4262026-03-30T18:20:36.882Z neighbor enp0s8 route-map PERMIT-ALL out
4272026-03-30T18:20:36.882Z neighbor enp0s8 route-map PERMIT-ALL in
4282026-03-30T18:20:36.882Z exit-address-family
4292026-03-30T18:20:36.882Z address-family ipv6 unicast
4302026-03-30T18:20:36.882Z network fd99::/64
4312026-03-30T18:20:36.882Z neighbor enp0s8 activate
4322026-03-30T18:20:36.882Z neighbor enp0s8 route-map PERMIT-ALL out
4332026-03-30T18:20:36.882Z neighbor enp0s8 route-map PERMIT-ALL in
4342026-03-30T18:20:36.882Z exit-address-family
4352026-03-30T18:20:36.882Z exit
4362026-03-30T18:20:36.883Z
4372026-03-30T18:20:36.883ZMar 30 18:20:36.867 DEBG [sc] cr1: starting
4382026-03-30T18:20:36.883ZMar 30 18:20:36.867 DEBG [sc] cr1: connecting to [::1]:39641
4392026-03-30T18:20:36.907ZMar 30 18:20:36.868 DEBG [sc] cr1 waiting for prompt
4402026-03-30T18:20:36.907ZMar 30 18:20:36.879 DEBG [sc] cr1: logging in
4412026-03-30T18:20:37.015ZMar 30 18:20:37.000 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-30T18:21:38.108ZMar 30 18:21:38.089 DEBG [sc] ox: starting
4432026-03-30T18:21:38.108ZMar 30 18:21:38.089 DEBG [sc] ox: connecting to [::1]:61611
4442026-03-30T18:21:38.139ZMar 30 18:21:38.090 DEBG [sc] ox waiting for prompt
4452026-03-30T18:21:38.139ZMar 30 18:21:38.100 DEBG [sc] ox: logging in
4462026-03-30T18:21:38.200ZMar 30 18:21:38.177 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4472026-03-30T18:21:38.511ZMar 30 18:21:38.497 DEBG [sc] ox: starting
4482026-03-30T18:21:38.511ZMar 30 18:21:38.497 DEBG [sc] ox: connecting to [::1]:61611
4492026-03-30T18:21:38.536ZMar 30 18:21:38.497 DEBG [sc] ox waiting for prompt
4502026-03-30T18:21:38.536ZMar 30 18:21:38.508 DEBG [sc] ox: logging in
4512026-03-30T18:21:38.600ZMar 30 18:21:38.585 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4522026-03-30T18:21:39.624ZMar 30 18:21:39.609 DEBG [sc] ox: starting
4532026-03-30T18:21:39.624ZMar 30 18:21:39.609 DEBG [sc] ox: connecting to [::1]:61611
4542026-03-30T18:21:39.651ZMar 30 18:21:39.610 DEBG [sc] ox waiting for prompt
4552026-03-30T18:21:39.651ZMar 30 18:21:39.610 DEBG [sc] ox: logging in
4562026-03-30T18:21:39.702ZMar 30 18:21:39.686 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4572026-03-30T18:21:39.757ZMar 30 18:21:39.742 DEBG [sc] ox: starting
4582026-03-30T18:21:39.757ZMar 30 18:21:39.742 DEBG [sc] ox: connecting to [::1]:61611
4592026-03-30T18:21:39.786ZMar 30 18:21:39.743 DEBG [sc] ox waiting for prompt
4602026-03-30T18:21:39.786ZMar 30 18:21:39.753 DEBG [sc] ox: logging in
4612026-03-30T18:21:39.846ZMar 30 18:21:39.831 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4622026-03-30T18:21:39.901ZMar 30 18:21:39.886 DEBG [sc] ox: starting
4632026-03-30T18:21:39.901ZMar 30 18:21:39.886 DEBG [sc] ox: connecting to [::1]:61611
4642026-03-30T18:21:39.928ZMar 30 18:21:39.887 DEBG [sc] ox waiting for prompt
4652026-03-30T18:21:39.928ZMar 30 18:21:39.897 DEBG [sc] ox: logging in
4662026-03-30T18:21:39.989ZMar 30 18:21:39.975 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4672026-03-30T18:21:40.045ZMar 30 18:21:40.030 DEBG [sc] ox: starting
4682026-03-30T18:21:40.045ZMar 30 18:21:40.030 DEBG [sc] ox: connecting to [::1]:61611
4692026-03-30T18:21:40.071ZMar 30 18:21:40.031 DEBG [sc] ox waiting for prompt
4702026-03-30T18:21:40.071ZMar 30 18:21:40.041 DEBG [sc] ox: logging in
4712026-03-30T18:21:40.132ZMar 30 18:21:40.117 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4722026-03-30T18:21:40.188ZMar 30 18:21:40.173 DEBG [sc] ox: starting
4732026-03-30T18:21:40.188ZMar 30 18:21:40.173 DEBG [sc] ox: connecting to [::1]:61611
4742026-03-30T18:21:40.214ZMar 30 18:21:40.174 DEBG [sc] ox waiting for prompt
4752026-03-30T18:21:40.214ZMar 30 18:21:40.184 DEBG [sc] ox: logging in
4762026-03-30T18:21:40.276ZMar 30 18:21:40.261 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4772026-03-30T18:21:40.342ZMar 30 18:21:40.327 DEBG [sc] ox: starting
4782026-03-30T18:21:40.342ZMar 30 18:21:40.327 DEBG [sc] ox: connecting to [::1]:61611
4792026-03-30T18:21:40.366ZMar 30 18:21:40.328 DEBG [sc] ox waiting for prompt
4802026-03-30T18:21:40.366ZMar 30 18:21:40.338 DEBG [sc] ox: logging in
4812026-03-30T18:21:40.429ZMar 30 18:21:40.415 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4822026-03-30T18:21:40.497ZMar 30 18:21:40.482 DEBG [sc] ox: starting
4832026-03-30T18:21:40.497ZMar 30 18:21:40.482 DEBG [sc] ox: connecting to [::1]:61611
4842026-03-30T18:21:40.522ZMar 30 18:21:40.483 DEBG [sc] ox waiting for prompt
4852026-03-30T18:21:40.522ZMar 30 18:21:40.493 DEBG [sc] ox: logging in
4862026-03-30T18:21:40.584ZMar 30 18:21:40.569 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4872026-03-30T18:21:40.650ZMar 30 18:21:40.636 DEBG [sc] ox: starting
4882026-03-30T18:21:40.650ZMar 30 18:21:40.636 DEBG [sc] ox: connecting to [::1]:61611
4892026-03-30T18:21:40.678ZMar 30 18:21:40.637 DEBG [sc] ox waiting for prompt
4902026-03-30T18:21:40.678ZMar 30 18:21:40.646 DEBG [sc] ox: logging in
4912026-03-30T18:21:40.738ZMar 30 18:21:40.724 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4922026-03-30T18:21:40.796ZMar 30 18:21:40.780 INFO adding BGP router to mgd
4932026-03-30T18:21:43.423ZMar 30 18:21:42.798 INFO cr1: executing frr script show ip bgp json
4942026-03-30T18:21:43.423ZMar 30 18:21:42.798 DEBG [sc] cr1: starting
4952026-03-30T18:21:43.423ZMar 30 18:21:42.798 DEBG [sc] cr1: connecting to [::1]:39641
4962026-03-30T18:21:43.496ZMar 30 18:21:42.799 DEBG [sc] cr1 waiting for prompt
4972026-03-30T18:21:43.496ZMar 30 18:21:42.810 DEBG [sc] cr1: logging in
4982026-03-30T18:21:43.496ZMar 30 18:21:43.204 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4992026-03-30T18:21:45.344ZMar 30 18:21:45.328 INFO cr1: executing frr script show bgp json
5002026-03-30T18:21:45.345ZMar 30 18:21:45.328 DEBG [sc] cr1: starting
5012026-03-30T18:21:45.345ZMar 30 18:21:45.329 DEBG [sc] cr1: connecting to [::1]:39641
5022026-03-30T18:21:45.375ZMar 30 18:21:45.329 DEBG [sc] cr1 waiting for prompt
5032026-03-30T18:21:45.375ZMar 30 18:21:45.339 DEBG [sc] cr1: logging in
5042026-03-30T18:21:45.485ZMar 30 18:21:45.471 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
5052026-03-30T18:21:47.604ZMar 30 18:21:47.589 INFO cr2: executing eos script show ip bgp | json
5062026-03-30T18:21:47.604ZMar 30 18:21:47.590 DEBG [sc] cr2: starting
5072026-03-30T18:21:47.605ZMar 30 18:21:47.590 DEBG [sc] cr2: connecting to [::1]:42012
5082026-03-30T18:21:47.632ZMar 30 18:21:47.590 DEBG [sc] cr2 waiting for prompt
5092026-03-30T18:21:47.632ZMar 30 18:21:47.602 DEBG [sc] cr2: logging in
5102026-03-30T18:21:47.997ZMar 30 18:21:47.982 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5112026-03-30T18:21:50.445ZMar 30 18:21:50.349 INFO cr2: executing eos script show ipv6 bgp | json
5122026-03-30T18:21:50.445ZMar 30 18:21:50.349 DEBG [sc] cr2: starting
5132026-03-30T18:21:50.445ZMar 30 18:21:50.349 DEBG [sc] cr2: connecting to [::1]:42012
5142026-03-30T18:21:51.350ZMar 30 18:21:50.350 DEBG [sc] cr2 waiting for prompt
5152026-03-30T18:21:51.350ZMar 30 18:21:50.360 DEBG [sc] cr2: logging in
5162026-03-30T18:21:51.350ZMar 30 18:21:50.504 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5172026-03-30T18:21:52.908ZMar 30 18:21:52.891 INFO trio bgp unnumbered test passed 🎉
5182026-03-30T18:21:52.908ZMar 30 18:21:52.891 INFO destroying runner for deployment mgtriou
5192026-03-30T18:21:52.908ZMar 30 18:21:52.891 INFO destroying deployment mgtriou
5202026-03-30T18:21:52.909ZMar 30 18:21:52.891 INFO destroying nodes
5212026-03-30T18:21:53.004ZMar 30 18:21:52.988 INFO destroying links
5222026-03-30T18:21:53.005ZMar 30 18:21:52.988 INFO destroying link mgtriou_ox_sn_vnic0
5232026-03-30T18:21:53.033ZMar 30 18:21:52.991 INFO destroying link mgtriou_ox_sn_sim0
5242026-03-30T18:21:53.033ZMar 30 18:21:52.993 INFO destroying link mgtriou_cr1_vn_vnic0
5252026-03-30T18:21:54.011ZMar 30 18:21:53.997 INFO destroying link mgtriou_cr1_vn_sim0
5262026-03-30T18:21:54.103ZMar 30 18:21:53.998 INFO destroying link mgtriou_ox_sn_vnic1
5272026-03-30T18:21:54.103ZMar 30 18:21:53.999 INFO destroying link mgtriou_ox_sn_sim1
5282026-03-30T18:21:54.103ZMar 30 18:21:54.000 INFO destroying link mgtriou_cr2_vn_vnic0
5292026-03-30T18:21:54.103ZMar 30 18:21:54.001 INFO destroying link mgtriou_cr2_vn_sim0
5302026-03-30T18:21:54.103ZMar 30 18:21:54.003 INFO destroying external links
5312026-03-30T18:21:54.103ZMar 30 18:21:54.003 INFO destroying external link mgtriou_ox_vn_vnic2
5322026-03-30T18:21:54.103ZMar 30 18:21:54.004 INFO destroying external link mgtriou_cr1_vn_vnic1
5332026-03-30T18:21:54.103ZMar 30 18:21:54.005 INFO destroying external link mgtriou_cr2_vn_vnic1
5342026-03-30T18:21:54.103ZMar 30 18:21:54.006 INFO destroying images
5352026-03-30T18:21:54.499ZMar 30 18:21:54.485 INFO destroying workspace at .falcon
5362026-03-30T18:21:54.523Zprocess exited: duration 492896 ms, exit code 0
 
5372026-03-30T18:21:54.575Zfound 0 output files