01KNTPZQR0QG2T17NMZR8VV5GG: falcon

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

Buildomat Job: 01KNTQ03DGM8DQPKVSWBXW3ER3

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-04-10T03:42:59.261Zjob dependencies complete; ready to run (waiting for 13 m 28 s)
22026-04-10T03:46:13.516Zjob assigned to worker 01KNTQRS1D57SN4JAN2DQ4B3N1 [factory edgar, gimlet/BRM42220060/356] (queued for 3 m 14 s)
32026-04-10T03:46:14.660Zdownloading input: /input/build-interop/work/testbed.tar.gz
42026-04-10T03:46:23.031Zdownloaded input: /input/build-interop/work/testbed.tar.gz
52026-04-10T03:46:23.031Zdownloading input: /input/build-interop/work/dhcp-server
62026-04-10T03:46:25.370Zdownloaded input: /input/build-interop/work/dhcp-server
72026-04-10T03:46:25.395Zdownloading input: /input/build/work/debug/ddmadm
82026-04-10T03:46:48.678Zdownloaded input: /input/build/work/debug/ddmadm
92026-04-10T03:46:48.678Zdownloading input: /input/build/work/debug/ddmd
102026-04-10T03:47:16.512Zdownloaded input: /input/build/work/debug/ddmd
112026-04-10T03:47:16.512Zdownloading input: /input/build/work/debug/mgadm
122026-04-10T03:47:43.139Zdownloaded input: /input/build/work/debug/mgadm
132026-04-10T03:47:43.167Zdownloading input: /input/build/work/debug/mgd
142026-04-10T03:48:19.456Zdownloaded input: /input/build/work/debug/mgd
152026-04-10T03:48:19.480Zdownloading input: /input/build/work/release/ddmadm
162026-04-10T03:48:21.655Zdownloaded input: /input/build/work/release/ddmadm
172026-04-10T03:48:21.655Zdownloading input: /input/build/work/release/ddmd
182026-04-10T03:48:24.339Zdownloaded input: /input/build/work/release/ddmd
192026-04-10T03:48:24.340Zdownloading input: /input/build/work/release/falcon-lab
202026-04-10T03:48:25.968Zdownloaded input: /input/build/work/release/falcon-lab
212026-04-10T03:48:25.968Zdownloading input: /input/build/work/release/mgadm
222026-04-10T03:48:29.384Zdownloaded input: /input/build/work/release/mgadm
232026-04-10T03:48:29.384Zdownloading input: /input/build/work/release/mgd
242026-04-10T03:48:33.281Zdownloaded input: /input/build/work/release/mgd
 
252026-04-10T03:48:33.281Zstarting task 0: "setup"
262026-04-10T03:48:33.306Z++ uname -s
272026-04-10T03:48:33.306Z+ kern=SunOS
282026-04-10T03:48:33.306Z+ build_user=build
292026-04-10T03:48:33.306Z+ build_uid=12345
302026-04-10T03:48:33.306Z+ work_dir=/work
312026-04-10T03:48:33.306Z+ input_dir=/input
322026-04-10T03:48:33.306Z+ [[ 0 == 12345 ]]
332026-04-10T03:48:33.306Z+ case "$kern" in
342026-04-10T03:48:33.306Z+ groupadd -g 12345 build
352026-04-10T03:48:33.331Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-04-10T03:48:35.319Z+ zfs create -o mountpoint=/work rpool/work
372026-04-10T03:48:35.395Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-04-10T03:48:35.420Z+ home_fs=
392026-04-10T03:48:35.420Z+ [[ '' == autofs ]]
402026-04-10T03:48:35.420Z+ mkdir -p /home/build
412026-04-10T03:48:35.420Z+ chown build:build /home/build /work
422026-04-10T03:48:37.410Z+ chmod 0700 /home/build /work
432026-04-10T03:48:37.434Zprocess exited: duration 4131 ms, exit code 0
 
442026-04-10T03:48:37.483Zstarting task 1: "authentication"
452026-04-10T03:48:37.579Zprocess exited: duration 95 ms, exit code 0
 
462026-04-10T03:48:37.631Zstarting task 2: "build"
472026-04-10T03:48:37.656Z+ set -e
482026-04-10T03:48:37.656Z+ banner zpool
492026-04-10T03:48:37.656Z
502026-04-10T03:48:37.656Z ###### ##### #### #### #
512026-04-10T03:48:37.656Z # # # # # # # #
522026-04-10T03:48:37.656Z # # # # # # # #
532026-04-10T03:48:37.656Z # ##### # # # # #
542026-04-10T03:48:37.656Z # # # # # # #
552026-04-10T03:48:37.656Z ###### # #### #### ######
562026-04-10T03:48:37.656Z
572026-04-10T03:48:37.656Z++ pfexec diskinfo -pH
582026-04-10T03:48:37.680Z++ sort -k8 -n -r
592026-04-10T03:48:37.681Z++ head -1
602026-04-10T03:48:37.681Z++ awk '{print $2}'
612026-04-10T03:48:37.707Z+ DISK=c9t0014EE81000BC57Cd0
622026-04-10T03:48:37.708Z+ export DISK
632026-04-10T03:48:37.733Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-04-10T03:48:37.776Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-04-10T03:48:37.803Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-04-10T03:48:37.913Z+ [[ true =~ true ]]
672026-04-10T03:48:37.938Z+ pfexec zpool trim cpool
682026-04-10T03:48:37.938Z++ zpool status -t cpool
692026-04-10T03:48:37.963Z+ [[ ! pool: cpool
702026-04-10T03:48:37.963Z state: ONLINE
712026-04-10T03:48:37.963Z scan: none requested
722026-04-10T03:48:37.963Zconfig:
732026-04-10T03:48:37.963Z
742026-04-10T03:48:37.963Z NAME STATE READ WRITE CKSUM
752026-04-10T03:48:37.963Z cpool ONLINE 0 0 0
762026-04-10T03:48:37.963Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at April 10, 2026 at 03:48:37 AM UTC)
772026-04-10T03:48:37.963Z
782026-04-10T03:48:37.963Zerrors: No known data errors =~ 100% ]]
792026-04-10T03:48:37.963Z+ sleep 10
802026-04-10T03:48:47.946Z++ zpool status -t cpool
812026-04-10T03:48:47.972Z+ [[ ! pool: cpool
822026-04-10T03:48:47.972Z state: ONLINE
832026-04-10T03:48:47.973Z scan: none requested
842026-04-10T03:48:47.973Zconfig:
852026-04-10T03:48:47.973Z
862026-04-10T03:48:47.973Z NAME STATE READ WRITE CKSUM
872026-04-10T03:48:47.973Z cpool ONLINE 0 0 0
882026-04-10T03:48:47.973Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (69% trimmed, started at April 10, 2026 at 03:48:37 AM UTC)
892026-04-10T03:48:47.973Z
902026-04-10T03:48:47.973Zerrors: No known data errors =~ 100% ]]
912026-04-10T03:48:47.973Z+ sleep 10
922026-04-10T03:48:57.961Z++ zpool status -t cpool
932026-04-10T03:48:57.987Z+ [[ ! pool: cpool
942026-04-10T03:48:57.987Z state: ONLINE
952026-04-10T03:48:57.987Z scan: none requested
962026-04-10T03:48:57.987Zconfig:
972026-04-10T03:48:57.987Z
982026-04-10T03:48:57.987Z NAME STATE READ WRITE CKSUM
992026-04-10T03:48:57.988Z cpool ONLINE 0 0 0
1002026-04-10T03:48:57.988Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at April 10, 2026 at 03:48:52 AM UTC)
1012026-04-10T03:48:57.988Z
1022026-04-10T03:48:57.988Zerrors: No known data errors =~ 100% ]]
1032026-04-10T03:48:57.988Z+ pfexec chown 12345 /ci
1042026-04-10T03:48:57.988Z+ cd /ci
1052026-04-10T03:48:57.988Z+ export FALCON_DATASET=cpool/falcon
1062026-04-10T03:48:57.988Z+ FALCON_DATASET=cpool/falcon
1072026-04-10T03:48:57.988Z+ banner setup
1082026-04-10T03:48:57.988Z
1092026-04-10T03:48:57.988Z #### ###### ##### # # #####
1102026-04-10T03:48:57.988Z # # # # # # #
1112026-04-10T03:48:57.988Z #### ##### # # # # #
1122026-04-10T03:48:57.988Z # # # # # #####
1132026-04-10T03:48:57.988Z # # # # # # #
1142026-04-10T03:48:57.988Z #### ###### # #### #
1152026-04-10T03:48:57.988Z
1162026-04-10T03:48:57.988Z+ cp /input/build-interop/work/dhcp-server .
1172026-04-10T03:48:58.293Z+ cp /input/build/work/release/falcon-lab .
1182026-04-10T03:48:58.320Z+ cp /input/build/work/release/mgd .
1192026-04-10T03:48:58.390Z+ cp /input/build/work/release/ddmd .
1202026-04-10T03:48:58.450Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1212026-04-10T03:48:58.475Z+ mkdir -p cargo-bay
1222026-04-10T03:48:58.475Z+ mv mgd cargo-bay/
1232026-04-10T03:48:58.475Z+ mv ddmd cargo-bay/
1242026-04-10T03:48:58.475Z+ export EXT_INTERFACE=igb0
1252026-04-10T03:48:58.475Z+ EXT_INTERFACE=igb0
1262026-04-10T03:48:58.475Z++ bmat address ls -f extra -Ho first
1272026-04-10T03:48:58.500Z+ first=10.151.7.164
1282026-04-10T03:48:58.500Z++ bmat address ls -f extra -Ho last
1292026-04-10T03:48:58.525Z+ last=10.151.7.227
1302026-04-10T03:48:58.525Z++ bmat address ls -f extra -Ho gateway
1312026-04-10T03:48:58.550Z+ gw=10.151.7.1
1322026-04-10T03:48:58.550Z++ ipadm show-addr igb0/dhcp -po ADDR
1332026-04-10T03:48:58.552Z++ sed 's#/.*##g'
1342026-04-10T03:48:58.552Z+ server=10.151.7.100
1352026-04-10T03:48:58.577Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1362026-04-10T03:48:58.578Z++ pfexec dladm create-vnic -l igb0 dummy0
1372026-04-10T03:48:58.578Z+ error=
1382026-04-10T03:48:58.578Z+ RUST_LOG=debug
1392026-04-10T03:48:58.578Z+ pfexec ./falcon-lab run trio-unnumbered
1402026-04-10T03:48:58.602ZApr 10 03:48:58.589 DEBG using default route interface igb0
1412026-04-10T03:48:58.602ZApr 10 03:48:58.590 DEBG using default route interface igb0
1422026-04-10T03:48:58.603ZApr 10 03:48:58.590 DEBG using default route interface igb0
1432026-04-10T03:48:58.603ZApr 10 03:48:58.590 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1442026-04-10T03:48:58.603ZApr 10 03:48:58.590 INFO starting preflight for deployment mgtriou
1452026-04-10T03:48:58.603ZApr 10 03:48:58.590 INFO propolis-server binary not found
1462026-04-10T03:48:58.603ZApr 10 03:48:58.590 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1472026-04-10T03:49:02.454ZApr 10 03:49:02.462 INFO ovmf fd not found
1482026-04-10T03:49:02.454ZApr 10 03:49:02.462 INFO downloading ovmf
1492026-04-10T03:49:02.845ZApr 10 03:49:02.853 INFO base image for helios-2.9 does not exist, attempting to install
1502026-04-10T03:49:02.845ZApr 10 03:49:02.853 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1512026-04-10T03:49:31.106ZApr 10 03:49:31.111 INFO extracting image to /tmp/helios-2.9_0.raw
1522026-04-10T03:50:04.546ZApr 10 03:50:04.538 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1532026-04-10T03:50:04.598ZApr 10 03:50:04.592 INFO copying image data to zvol
1542026-04-10T03:50:14.886ZApr 10 03:50:14.880 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1552026-04-10T03:50:18.327ZApr 10 03:50:18.321 INFO base image for debian-13.2 does not exist, attempting to install
1562026-04-10T03:50:18.327ZApr 10 03:50:18.321 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1572026-04-10T03:50:38.991ZApr 10 03:50:38.747 INFO extracting image to /tmp/debian-13.2_0.raw
1582026-04-10T03:50:59.521ZApr 10 03:50:59.513 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1592026-04-10T03:50:59.546ZApr 10 03:50:59.538 INFO copying image data to zvol
1602026-04-10T03:51:05.791ZApr 10 03:51:05.783 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1612026-04-10T03:51:09.088ZApr 10 03:51:09.080 INFO base image for eos-4.35 does not exist, attempting to install
1622026-04-10T03:51:09.088ZApr 10 03:51:09.080 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1632026-04-10T03:51:53.361ZApr 10 03:51:53.351 INFO extracting image to /tmp/eos-4.35_0.raw
1642026-04-10T03:53:16.467ZApr 10 03:53:16.456 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1652026-04-10T03:53:16.493ZApr 10 03:53:16.482 INFO copying image data to zvol
1662026-04-10T03:54:06.159ZApr 10 03:54:06.146 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1672026-04-10T03:54:09.505ZApr 10 03:54:09.493 INFO creating links
1682026-04-10T03:54:09.505ZApr 10 03:54:09.493 DEBG destroying link mgtriou_ox_sn_vnic0
1692026-04-10T03:54:09.530ZApr 10 03:54:09.493 DEBG destroying link mgtriou_ox_sn_sim0
1702026-04-10T03:54:09.530ZApr 10 03:54:09.493 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1712026-04-10T03:54:09.530ZApr 10 03:54:09.496 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1722026-04-10T03:54:09.530ZApr 10 03:54:09.503 DEBG link pair created
1732026-04-10T03:54:09.530ZApr 10 03:54:09.503 DEBG destroying link mgtriou_cr1_vn_vnic0
1742026-04-10T03:54:09.530ZApr 10 03:54:09.503 DEBG destroying link mgtriou_cr1_vn_sim0
1752026-04-10T03:54:09.530ZApr 10 03:54:09.503 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1762026-04-10T03:54:09.531ZApr 10 03:54:09.505 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1772026-04-10T03:54:09.531ZApr 10 03:54:09.511 DEBG link pair created
1782026-04-10T03:54:09.531ZApr 10 03:54:09.512 DEBG destroying link mgtriou_ox_sn_vnic1
1792026-04-10T03:54:09.531ZApr 10 03:54:09.512 DEBG destroying link mgtriou_ox_sn_sim1
1802026-04-10T03:54:09.531ZApr 10 03:54:09.512 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1812026-04-10T03:54:09.531ZApr 10 03:54:09.513 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1822026-04-10T03:54:09.556ZApr 10 03:54:09.519 DEBG link pair created
1832026-04-10T03:54:09.556ZApr 10 03:54:09.519 DEBG destroying link mgtriou_cr2_vn_vnic0
1842026-04-10T03:54:09.556ZApr 10 03:54:09.519 DEBG destroying link mgtriou_cr2_vn_sim0
1852026-04-10T03:54:09.556ZApr 10 03:54:09.519 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1862026-04-10T03:54:09.556ZApr 10 03:54:09.521 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1872026-04-10T03:54:09.556ZApr 10 03:54:09.529 DEBG link pair created
1882026-04-10T03:54:09.556ZApr 10 03:54:09.529 INFO creating external links
1892026-04-10T03:54:09.556ZApr 10 03:54:09.529 DEBG destroying external link mgtriou_ox_vn_vnic2
1902026-04-10T03:54:09.556ZApr 10 03:54:09.529 INFO creating external link mgtriou_ox_vn_vnic2
1912026-04-10T03:54:09.556ZApr 10 03:54:09.530 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1922026-04-10T03:54:09.556ZApr 10 03:54:09.530 DEBG destroying external link mgtriou_cr1_vn_vnic1
1932026-04-10T03:54:09.556ZApr 10 03:54:09.530 INFO creating external link mgtriou_cr1_vn_vnic1
1942026-04-10T03:54:09.556ZApr 10 03:54:09.531 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1952026-04-10T03:54:09.556ZApr 10 03:54:09.532 DEBG destroying external link mgtriou_cr2_vn_vnic1
1962026-04-10T03:54:09.556ZApr 10 03:54:09.532 INFO creating external link mgtriou_cr2_vn_vnic1
1972026-04-10T03:54:09.556ZApr 10 03:54:09.533 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1982026-04-10T03:54:09.557ZApr 10 03:54:09.533 INFO creating nodes
1992026-04-10T03:54:09.557ZApr 10 03:54:09.533 INFO ox: launching node
2002026-04-10T03:54:09.557ZApr 10 03:54:09.536 INFO cr1: launching node
2012026-04-10T03:54:09.557ZApr 10 03:54:09.538 INFO cr2: launching node
2022026-04-10T03:54:09.582ZApr 10 03:54:09.552 INFO launched instance ox with pid 857 on port 56692
2032026-04-10T03:54:09.582ZApr 10 03:54:09.552 INFO ox: instance ensure
2042026-04-10T03:54:09.583ZApr 10 03:54:09.553 INFO launched instance cr1 with pid 858 on port 46368
2052026-04-10T03:54:09.583ZApr 10 03:54:09.553 INFO cr1: instance ensure
2062026-04-10T03:54:09.583ZApr 10 03:54:09.553 INFO launched instance cr2 with pid 859 on port 52721
2072026-04-10T03:54:09.583ZApr 10 03:54:09.553 INFO cr2: instance ensure
2082026-04-10T03:54:11.988ZApr 10 03:54:11.976 INFO ox: instance run
2092026-04-10T03:54:12.013ZApr 10 03:54:11.977 DEBG [sc] ox: starting
2102026-04-10T03:54:12.013ZApr 10 03:54:11.977 DEBG [sc] ox: connecting to [::1]:56692
2112026-04-10T03:54:12.013ZApr 10 03:54:11.978 DEBG [sc] ox waiting for prompt
2122026-04-10T03:54:12.038ZApr 10 03:54:12.023 INFO cr2: instance run
2132026-04-10T03:54:12.038ZApr 10 03:54:12.023 DEBG [sc] cr2: starting
2142026-04-10T03:54:12.038ZApr 10 03:54:12.023 DEBG [sc] cr2: connecting to [::1]:52721
2152026-04-10T03:54:12.038ZApr 10 03:54:12.024 DEBG [sc] cr2 waiting for prompt
2162026-04-10T03:54:12.062ZApr 10 03:54:12.046 INFO cr1: instance run
2172026-04-10T03:54:12.062ZApr 10 03:54:12.046 DEBG [sc] cr1: starting
2182026-04-10T03:54:12.064ZApr 10 03:54:12.046 DEBG [sc] cr1: connecting to [::1]:46368
2192026-04-10T03:54:12.064ZApr 10 03:54:12.047 DEBG [sc] cr1 waiting for prompt
2202026-04-10T03:54:29.170ZApr 10 03:54:29.158 DEBG [sc] cr1: logging in
2212026-04-10T03:54:29.542ZApr 10 03:54:29.530 INFO cr1: mounting /opt/cargo-bay
2222026-04-10T03:54:29.542ZApr 10 03:54:29.530 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2232026-04-10T03:54:29.567ZApr 10 03:54:29.541 DEBG [sc] cr1: executing command `cd`
2242026-04-10T03:54:29.567ZApr 10 03:54:29.552 INFO cr1: finished mounting /opt/cargo-bay
2252026-04-10T03:54:29.567ZApr 10 03:54:29.552 DEBG [sc] cr1: executing command `hostname cr1`
2262026-04-10T03:54:29.592ZApr 10 03:54:29.562 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
2272026-04-10T03:54:29.592ZApr 10 03:54:29.573 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
2282026-04-10T03:54:29.616ZApr 10 03:54:29.585 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
2292026-04-10T03:54:29.617ZApr 10 03:54:29.596 INFO cr1: logging out
2302026-04-10T03:54:31.665ZApr 10 03:54:31.653 INFO cr1: logged out
2312026-04-10T03:54:32.488ZApr 10 03:54:32.475 DEBG [sc] ox: logging in
2322026-04-10T03:54:32.648ZApr 10 03:54:32.635 INFO ox: mounting /opt/cargo-bay
2332026-04-10T03:54:32.648ZApr 10 03:54:32.635 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-04-10T03:54:33.592ZApr 10 03:54:33.579 DEBG [sc] cr2: logging in
2352026-04-10T03:54:33.884ZApr 10 03:54:33.872 DEBG [sc] ox: executing command `cd`
2362026-04-10T03:54:33.908ZApr 10 03:54:33.883 INFO ox: finished mounting /opt/cargo-bay
2372026-04-10T03:54:33.908ZApr 10 03:54:33.883 DEBG [sc] ox: executing command `hostname ox`
2382026-04-10T03:54:33.932ZApr 10 03:54:33.905 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2392026-04-10T03:54:33.933ZApr 10 03:54:33.916 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2402026-04-10T03:54:33.958ZApr 10 03:54:33.928 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2412026-04-10T03:54:33.958ZApr 10 03:54:33.939 INFO ox: logging out
2422026-04-10T03:54:34.007ZApr 10 03:54:33.994 INFO ox: logged out
2432026-04-10T03:54:34.031ZApr 10 03:54:34.011 INFO cr2: mounting /opt/cargo-bay
2442026-04-10T03:54:34.031ZApr 10 03:54:34.011 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2452026-04-10T03:54:34.056ZApr 10 03:54:34.023 DEBG [sc] cr2: executing command `cd`
2462026-04-10T03:54:34.056ZApr 10 03:54:34.034 INFO cr2: finished mounting /opt/cargo-bay
2472026-04-10T03:54:34.056ZApr 10 03:54:34.034 DEBG [sc] cr2: executing command `hostname cr2`
2482026-04-10T03:54:34.080ZApr 10 03:54:34.044 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2492026-04-10T03:54:34.080ZApr 10 03:54:34.055 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2502026-04-10T03:54:34.080ZApr 10 03:54:34.066 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2512026-04-10T03:54:34.104ZApr 10 03:54:34.077 INFO cr2: logging out
2522026-04-10T03:54:37.026ZApr 10 03:54:37.014 INFO cr2: logged out
2532026-04-10T03:54:37.050ZApr 10 03:54:37.014 DEBG [sc] ox: starting
2542026-04-10T03:54:37.050ZApr 10 03:54:37.014 DEBG [sc] ox: connecting to [::1]:56692
2552026-04-10T03:54:37.050ZApr 10 03:54:37.015 DEBG [sc] ox waiting for prompt
2562026-04-10T03:54:37.050ZApr 10 03:54:37.026 DEBG [sc] ox: logging in
2572026-04-10T03:54:37.126ZApr 10 03:54:37.114 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2582026-04-10T03:54:43.640ZApr 10 03:54:43.627 DEBG [sc] ox: starting
2592026-04-10T03:54:43.640ZApr 10 03:54:43.627 DEBG [sc] ox: connecting to [::1]:56692
2602026-04-10T03:54:43.664ZApr 10 03:54:43.627 DEBG [sc] ox waiting for prompt
2612026-04-10T03:54:43.664ZApr 10 03:54:43.638 DEBG [sc] ox: logging in
2622026-04-10T03:54:43.738ZApr 10 03:54:43.726 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2632026-04-10T03:54:43.815ZApr 10 03:54:43.803 DEBG [sc] ox: starting
2642026-04-10T03:54:43.815ZApr 10 03:54:43.803 DEBG [sc] ox: connecting to [::1]:56692
2652026-04-10T03:54:43.843ZApr 10 03:54:43.804 DEBG [sc] ox waiting for prompt
2662026-04-10T03:54:43.843ZApr 10 03:54:43.814 DEBG [sc] ox: logging in
2672026-04-10T03:54:43.913ZApr 10 03:54:43.902 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2682026-04-10T03:54:43.980ZApr 10 03:54:43.968 INFO cr1: installing frr
2692026-04-10T03:54:44.004ZApr 10 03:54:43.968 INFO waiting for ceos to initialize
2702026-04-10T03:54:44.004ZApr 10 03:54:43.968 INFO ox: setting up npuvm
2712026-04-10T03:54:44.004ZApr 10 03:54:43.968 DEBG [sc] cr1: starting
2722026-04-10T03:54:44.005ZApr 10 03:54:43.968 DEBG [sc] cr1: connecting to [::1]:46368
2732026-04-10T03:54:44.005ZApr 10 03:54:43.968 DEBG [sc] cr2: starting
2742026-04-10T03:54:44.005ZApr 10 03:54:43.968 DEBG [sc] cr2: connecting to [::1]:52721
2752026-04-10T03:54:44.005ZApr 10 03:54:43.968 DEBG [sc] ox: starting
2762026-04-10T03:54:44.005ZApr 10 03:54:43.968 DEBG [sc] ox: connecting to [::1]:56692
2772026-04-10T03:54:44.006ZApr 10 03:54:43.969 DEBG [sc] ox waiting for prompt
2782026-04-10T03:54:44.006ZApr 10 03:54:43.969 DEBG [sc] cr1 waiting for prompt
2792026-04-10T03:54:44.006ZApr 10 03:54:43.969 DEBG [sc] cr2 waiting for prompt
2802026-04-10T03:54:44.006ZApr 10 03:54:43.979 DEBG [sc] ox: logging in
2812026-04-10T03:54:44.006ZApr 10 03:54:43.979 DEBG [sc] cr1: logging in
2822026-04-10T03:54:44.006ZApr 10 03:54:43.980 DEBG [sc] cr2: logging in
2832026-04-10T03:54:44.079ZApr 10 03:54:44.067 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2842026-04-10T03:54:44.137ZApr 10 03:54:44.125 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2852026-04-10T03:54:44.359ZApr 10 03:54:44.347 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2862026-04-10T03:54:46.403ZApr 10 03:54:46.390 INFO cr2: executing eos script show version
2872026-04-10T03:54:46.403ZApr 10 03:54:46.390 DEBG [sc] cr2: starting
2882026-04-10T03:54:46.403ZApr 10 03:54:46.390 DEBG [sc] cr2: connecting to [::1]:52721
2892026-04-10T03:54:46.427ZApr 10 03:54:46.391 DEBG [sc] cr2 waiting for prompt
2902026-04-10T03:54:46.427ZApr 10 03:54:46.401 DEBG [sc] cr2: logging in
2912026-04-10T03:54:46.546ZApr 10 03:54:46.534 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2922026-04-10T03:54:49.485ZApr 10 03:54:49.472 DEBG [sc] ox: starting
2932026-04-10T03:54:49.485ZApr 10 03:54:49.473 DEBG [sc] ox: connecting to [::1]:56692
2942026-04-10T03:54:49.510ZApr 10 03:54:49.473 DEBG [sc] ox waiting for prompt
2952026-04-10T03:54:49.510ZApr 10 03:54:49.483 DEBG [sc] ox: logging in
2962026-04-10T03:54:49.584ZApr 10 03:54:49.572 DEBG [sc] ox: executing command `chmod +x npuvm`
2972026-04-10T03:54:49.665ZApr 10 03:54:49.639 DEBG [sc] ox: starting
2982026-04-10T03:54:49.665ZApr 10 03:54:49.639 DEBG [sc] ox: connecting to [::1]:56692
2992026-04-10T03:54:49.665ZApr 10 03:54:49.639 DEBG [sc] ox waiting for prompt
3002026-04-10T03:54:49.665ZApr 10 03:54:49.649 DEBG [sc] ox: logging in
3012026-04-10T03:54:49.739ZApr 10 03:54:49.727 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
3022026-04-10T03:54:54.369ZApr 10 03:54:54.354 DEBG [sc] cr2: starting
3032026-04-10T03:54:54.369ZApr 10 03:54:54.354 DEBG [sc] cr2: connecting to [::1]:52721
3042026-04-10T03:54:54.395ZApr 10 03:54:54.354 DEBG [sc] cr2 waiting for prompt
3052026-04-10T03:54:54.395ZApr 10 03:54:54.364 DEBG [sc] cr2: logging in
3062026-04-10T03:54:54.530ZApr 10 03:54:54.518 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3072026-04-10T03:54:56.630ZApr 10 03:54:56.618 INFO cr2: executing eos script show version
3082026-04-10T03:54:56.630ZApr 10 03:54:56.618 DEBG [sc] cr2: starting
3092026-04-10T03:54:56.630ZApr 10 03:54:56.618 DEBG [sc] cr2: connecting to [::1]:52721
3102026-04-10T03:54:56.654ZApr 10 03:54:56.619 DEBG [sc] cr2 waiting for prompt
3112026-04-10T03:54:56.654ZApr 10 03:54:56.629 DEBG [sc] cr2: logging in
3122026-04-10T03:54:56.807ZApr 10 03:54:56.795 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3132026-04-10T03:54:59.199ZApr 10 03:54:59.185 DEBG [sc] cr2: starting
3142026-04-10T03:54:59.199ZApr 10 03:54:59.185 DEBG [sc] cr2: connecting to [::1]:52721
3152026-04-10T03:54:59.224ZApr 10 03:54:59.186 DEBG [sc] cr2 waiting for prompt
3162026-04-10T03:54:59.224ZApr 10 03:54:59.196 DEBG [sc] cr2: logging in
3172026-04-10T03:54:59.351ZApr 10 03:54:59.339 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
3182026-04-10T03:55:01.494ZApr 10 03:55:01.481 INFO cr2: executing eos script show version
3192026-04-10T03:55:01.494ZApr 10 03:55:01.482 DEBG [sc] cr2: starting
3202026-04-10T03:55:01.494ZApr 10 03:55:01.482 DEBG [sc] cr2: connecting to [::1]:52721
3212026-04-10T03:55:01.519ZApr 10 03:55:01.482 DEBG [sc] cr2 waiting for prompt
3222026-04-10T03:55:01.519ZApr 10 03:55:01.492 DEBG [sc] cr2: logging in
3232026-04-10T03:55:01.659ZApr 10 03:55:01.647 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
3242026-04-10T03:55:04.158ZApr 10 03:55:04.146 INFO cr2: executing eos script
3252026-04-10T03:55:04.158Z enable
3262026-04-10T03:55:04.158Z configure
3272026-04-10T03:55:04.158Z ipv6 unicast-routing
3282026-04-10T03:55:04.158Z ip routing ipv6 interfaces
3292026-04-10T03:55:04.158Z ip routing
3302026-04-10T03:55:04.158Z ip route 1.2.3.0/24 null0
3312026-04-10T03:55:04.158Z ipv6 route fd99::/64 null0
3322026-04-10T03:55:04.158Z interface et1
3332026-04-10T03:55:04.158Z no switchport
3342026-04-10T03:55:04.158Z ipv6 enable
3352026-04-10T03:55:04.158Z
3362026-04-10T03:55:04.158Z router bgp 45
3372026-04-10T03:55:04.158Z router-id 1.2.3.1
3382026-04-10T03:55:04.158Z no bgp default ipv4-unicast
3392026-04-10T03:55:04.158Z timers bgp 2 6
3402026-04-10T03:55:04.158Z neighbor ebgp peer group
3412026-04-10T03:55:04.158Z neighbor ebgp remote-as 33
3422026-04-10T03:55:04.158Z neighbor interface Et1 peer-group ebgp
3432026-04-10T03:55:04.158Z address-family ipv4
3442026-04-10T03:55:04.158Z neighbor ebgp activate
3452026-04-10T03:55:04.158Z neighbor ebgp next-hop address-family ipv6 originate
3462026-04-10T03:55:04.158Z network 1.2.3.0/24
3472026-04-10T03:55:04.158Z exit
3482026-04-10T03:55:04.159Z address-family ipv6
3492026-04-10T03:55:04.159Z neighbor ebgp activate
3502026-04-10T03:55:04.159Z neighbor ebgp next-hop address-family ipv6 originate
3512026-04-10T03:55:04.159Z network fd99::/64
3522026-04-10T03:55:04.159Z exit
3532026-04-10T03:55:04.159Z exit
3542026-04-10T03:55:04.159Z
3552026-04-10T03:55:04.159ZApr 10 03:55:04.146 DEBG [sc] cr2: starting
3562026-04-10T03:55:04.159ZApr 10 03:55:04.146 DEBG [sc] cr2: connecting to [::1]:52721
3572026-04-10T03:55:04.184ZApr 10 03:55:04.146 DEBG [sc] cr2 waiting for prompt
3582026-04-10T03:55:04.184ZApr 10 03:55:04.156 DEBG [sc] cr2: logging in
3592026-04-10T03:55:04.311ZApr 10 03:55:04.299 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3602026-04-10T03:55:04.311Z enable
3612026-04-10T03:55:04.311Z configure
3622026-04-10T03:55:04.311Z ipv6 unicast-routing
3632026-04-10T03:55:04.311Z ip routing ipv6 interfaces
3642026-04-10T03:55:04.311Z ip routing
3652026-04-10T03:55:04.311Z ip route 1.2.3.0/24 null0
3662026-04-10T03:55:04.311Z ipv6 route fd99::/64 null0
3672026-04-10T03:55:04.311Z interface et1
3682026-04-10T03:55:04.311Z no switchport
3692026-04-10T03:55:04.311Z ipv6 enable
3702026-04-10T03:55:04.311Z
3712026-04-10T03:55:04.311Z router bgp 45
3722026-04-10T03:55:04.311Z router-id 1.2.3.1
3732026-04-10T03:55:04.311Z no bgp default ipv4-unicast
3742026-04-10T03:55:04.311Z timers bgp 2 6
3752026-04-10T03:55:04.311Z neighbor ebgp peer group
3762026-04-10T03:55:04.311Z neighbor ebgp remote-as 33
3772026-04-10T03:55:04.311Z neighbor interface Et1 peer-group ebgp
3782026-04-10T03:55:04.336Z address-family ipv4
3792026-04-10T03:55:04.336Z neighbor ebgp activate
3802026-04-10T03:55:04.336Z neighbor ebgp next-hop address-family ipv6 originate
3812026-04-10T03:55:04.336Z network 1.2.3.0/24
3822026-04-10T03:55:04.336Z exit
3832026-04-10T03:55:04.336Z address-family ipv6
3842026-04-10T03:55:04.336Z neighbor ebgp activate
3852026-04-10T03:55:04.336Z neighbor ebgp next-hop address-family ipv6 originate
3862026-04-10T03:55:04.336Z network fd99::/64
3872026-04-10T03:55:04.336Z exit
3882026-04-10T03:55:04.337Z exit
3892026-04-10T03:55:04.337Z '`
3902026-04-10T03:55:22.010ZApr 10 03:55:21.998 INFO cr1: enabling frr daemon bgpd
3912026-04-10T03:55:22.011ZApr 10 03:55:21.998 DEBG [sc] cr1: starting
3922026-04-10T03:55:22.011ZApr 10 03:55:21.998 DEBG [sc] cr1: connecting to [::1]:46368
3932026-04-10T03:55:22.035ZApr 10 03:55:21.999 DEBG [sc] cr1 waiting for prompt
3942026-04-10T03:55:22.035ZApr 10 03:55:22.009 DEBG [sc] cr1: logging in
3952026-04-10T03:55:22.165ZApr 10 03:55:22.153 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3962026-04-10T03:55:24.252ZApr 10 03:55:24.240 DEBG [sc] cr1: starting
3972026-04-10T03:55:24.252ZApr 10 03:55:24.240 DEBG [sc] cr1: connecting to [::1]:46368
3982026-04-10T03:55:24.276ZApr 10 03:55:24.241 DEBG [sc] cr1 waiting for prompt
3992026-04-10T03:55:24.276ZApr 10 03:55:24.251 DEBG [sc] cr1: logging in
4002026-04-10T03:55:24.417ZApr 10 03:55:24.405 DEBG [sc] cr1: executing command `systemctl restart frr`
4012026-04-10T03:55:32.061ZApr 10 03:55:32.048 INFO cr1: executing frr script
4022026-04-10T03:55:32.061Z configure
4032026-04-10T03:55:32.061Z ip forwarding
4042026-04-10T03:55:32.061Z ipv6 forwarding
4052026-04-10T03:55:32.061Z ip route 1.2.3.0/24 null0
4062026-04-10T03:55:32.062Z ipv6 route fd99::/64 null0
4072026-04-10T03:55:32.062Z route-map PERMIT-ALL permit 10
4082026-04-10T03:55:32.062Z router bgp 44
4092026-04-10T03:55:32.062Z timers bgp 2 6
4102026-04-10T03:55:32.062Z neighbor enp0s8 interface remote-as external
4112026-04-10T03:55:32.062Z neighbor enp0s8 timers connect 1
4122026-04-10T03:55:32.062Z address-family ipv4 unicast
4132026-04-10T03:55:32.062Z network 1.2.3.0/24
4142026-04-10T03:55:32.062Z neighbor enp0s8 activate
4152026-04-10T03:55:32.062Z neighbor enp0s8 route-map PERMIT-ALL out
4162026-04-10T03:55:32.062Z neighbor enp0s8 route-map PERMIT-ALL in
4172026-04-10T03:55:32.062Z exit-address-family
4182026-04-10T03:55:32.062Z address-family ipv6 unicast
4192026-04-10T03:55:32.063Z network fd99::/64
4202026-04-10T03:55:32.063Z neighbor enp0s8 activate
4212026-04-10T03:55:32.063Z neighbor enp0s8 route-map PERMIT-ALL out
4222026-04-10T03:55:32.063Z neighbor enp0s8 route-map PERMIT-ALL in
4232026-04-10T03:55:32.063Z exit-address-family
4242026-04-10T03:55:32.063Z exit
4252026-04-10T03:55:32.063Z
4262026-04-10T03:55:32.063ZApr 10 03:55:32.049 DEBG [sc] cr1: starting
4272026-04-10T03:55:32.063ZApr 10 03:55:32.049 DEBG [sc] cr1: connecting to [::1]:46368
4282026-04-10T03:55:32.087ZApr 10 03:55:32.049 DEBG [sc] cr1 waiting for prompt
4292026-04-10T03:55:32.087ZApr 10 03:55:32.061 DEBG [sc] cr1: logging in
4302026-04-10T03:55:32.193ZApr 10 03:55:32.181 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-10T03:56:30.471ZApr 10 03:56:30.459 DEBG [sc] ox: starting
4322026-04-10T03:56:30.471ZApr 10 03:56:30.459 DEBG [sc] ox: connecting to [::1]:56692
4332026-04-10T03:56:30.495ZApr 10 03:56:30.459 DEBG [sc] ox waiting for prompt
4342026-04-10T03:56:30.496ZApr 10 03:56:30.471 DEBG [sc] ox: logging in
4352026-04-10T03:56:30.559ZApr 10 03:56:30.547 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4362026-04-10T03:56:31.068ZApr 10 03:56:31.056 DEBG [sc] ox: starting
4372026-04-10T03:56:31.068ZApr 10 03:56:31.056 DEBG [sc] ox: connecting to [::1]:56692
4382026-04-10T03:56:31.093ZApr 10 03:56:31.057 DEBG [sc] ox waiting for prompt
4392026-04-10T03:56:31.093ZApr 10 03:56:31.068 DEBG [sc] ox: logging in
4402026-04-10T03:56:31.158ZApr 10 03:56:31.146 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4412026-04-10T03:56:31.213ZApr 10 03:56:31.201 DEBG [sc] ox: starting
4422026-04-10T03:56:31.213ZApr 10 03:56:31.201 DEBG [sc] ox: connecting to [::1]:56692
4432026-04-10T03:56:31.237ZApr 10 03:56:31.202 DEBG [sc] ox waiting for prompt
4442026-04-10T03:56:31.237ZApr 10 03:56:31.212 DEBG [sc] ox: logging in
4452026-04-10T03:56:31.301ZApr 10 03:56:31.289 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4462026-04-10T03:56:31.356ZApr 10 03:56:31.344 DEBG [sc] ox: starting
4472026-04-10T03:56:31.357ZApr 10 03:56:31.344 DEBG [sc] ox: connecting to [::1]:56692
4482026-04-10T03:56:31.381ZApr 10 03:56:31.345 DEBG [sc] ox waiting for prompt
4492026-04-10T03:56:31.381ZApr 10 03:56:31.355 DEBG [sc] ox: logging in
4502026-04-10T03:56:31.444ZApr 10 03:56:31.432 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4512026-04-10T03:56:31.499ZApr 10 03:56:31.488 DEBG [sc] ox: starting
4522026-04-10T03:56:31.500ZApr 10 03:56:31.488 DEBG [sc] ox: connecting to [::1]:56692
4532026-04-10T03:56:31.524ZApr 10 03:56:31.488 DEBG [sc] ox waiting for prompt
4542026-04-10T03:56:31.524ZApr 10 03:56:31.498 DEBG [sc] ox: logging in
4552026-04-10T03:56:31.588ZApr 10 03:56:31.576 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4562026-04-10T03:56:32.612ZApr 10 03:56:32.600 DEBG [sc] ox: starting
4572026-04-10T03:56:32.612ZApr 10 03:56:32.600 DEBG [sc] ox: connecting to [::1]:56692
4582026-04-10T03:56:32.636ZApr 10 03:56:32.601 DEBG [sc] ox waiting for prompt
4592026-04-10T03:56:32.636ZApr 10 03:56:32.601 DEBG [sc] ox: logging in
4602026-04-10T03:56:32.689ZApr 10 03:56:32.677 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4612026-04-10T03:56:32.744ZApr 10 03:56:32.732 DEBG [sc] ox: starting
4622026-04-10T03:56:32.744ZApr 10 03:56:32.732 DEBG [sc] ox: connecting to [::1]:56692
4632026-04-10T03:56:32.768ZApr 10 03:56:32.733 DEBG [sc] ox waiting for prompt
4642026-04-10T03:56:32.768ZApr 10 03:56:32.743 DEBG [sc] ox: logging in
4652026-04-10T03:56:32.832ZApr 10 03:56:32.820 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4662026-04-10T03:56:32.887ZApr 10 03:56:32.875 DEBG [sc] ox: starting
4672026-04-10T03:56:32.887ZApr 10 03:56:32.875 DEBG [sc] ox: connecting to [::1]:56692
4682026-04-10T03:56:32.911ZApr 10 03:56:32.876 DEBG [sc] ox waiting for prompt
4692026-04-10T03:56:32.911ZApr 10 03:56:32.887 DEBG [sc] ox: logging in
4702026-04-10T03:56:32.975ZApr 10 03:56:32.963 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4712026-04-10T03:56:33.041ZApr 10 03:56:33.029 DEBG [sc] ox: starting
4722026-04-10T03:56:33.041ZApr 10 03:56:33.029 DEBG [sc] ox: connecting to [::1]:56692
4732026-04-10T03:56:33.065ZApr 10 03:56:33.030 DEBG [sc] ox waiting for prompt
4742026-04-10T03:56:33.065ZApr 10 03:56:33.040 DEBG [sc] ox: logging in
4752026-04-10T03:56:33.129ZApr 10 03:56:33.117 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4762026-04-10T03:56:33.185ZApr 10 03:56:33.173 DEBG [sc] ox: starting
4772026-04-10T03:56:33.185ZApr 10 03:56:33.173 DEBG [sc] ox: connecting to [::1]:56692
4782026-04-10T03:56:33.209ZApr 10 03:56:33.174 DEBG [sc] ox waiting for prompt
4792026-04-10T03:56:33.209ZApr 10 03:56:33.184 DEBG [sc] ox: logging in
4802026-04-10T03:56:33.273ZApr 10 03:56:33.261 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4812026-04-10T03:56:33.331ZApr 10 03:56:33.319 INFO adding BGP router to mgd
4822026-04-10T03:56:35.349ZApr 10 03:56:35.338 INFO cr1: executing frr script show ip bgp json
4832026-04-10T03:56:35.350ZApr 10 03:56:35.338 DEBG [sc] cr1: starting
4842026-04-10T03:56:35.350ZApr 10 03:56:35.338 DEBG [sc] cr1: connecting to [::1]:46368
4852026-04-10T03:56:35.374ZApr 10 03:56:35.338 DEBG [sc] cr1 waiting for prompt
4862026-04-10T03:56:35.374ZApr 10 03:56:35.349 DEBG [sc] cr1: logging in
4872026-04-10T03:56:35.676ZApr 10 03:56:35.664 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4882026-04-10T03:56:37.803ZApr 10 03:56:37.791 INFO cr1: executing frr script show bgp json
4892026-04-10T03:56:37.803ZApr 10 03:56:37.791 DEBG [sc] cr1: starting
4902026-04-10T03:56:37.803ZApr 10 03:56:37.791 DEBG [sc] cr1: connecting to [::1]:46368
4912026-04-10T03:56:37.827ZApr 10 03:56:37.792 DEBG [sc] cr1 waiting for prompt
4922026-04-10T03:56:37.827ZApr 10 03:56:37.802 DEBG [sc] cr1: logging in
4932026-04-10T03:56:37.947ZApr 10 03:56:37.936 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4942026-04-10T03:56:40.074ZApr 10 03:56:40.063 INFO cr2: executing eos script show ip bgp | json
4952026-04-10T03:56:40.074ZApr 10 03:56:40.063 DEBG [sc] cr2: starting
4962026-04-10T03:56:40.074ZApr 10 03:56:40.063 DEBG [sc] cr2: connecting to [::1]:52721
4972026-04-10T03:56:40.098ZApr 10 03:56:40.063 DEBG [sc] cr2 waiting for prompt
4982026-04-10T03:56:40.098ZApr 10 03:56:40.075 DEBG [sc] cr2: logging in
4992026-04-10T03:56:40.415ZApr 10 03:56:40.403 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
5002026-04-10T03:56:42.783ZApr 10 03:56:42.771 INFO cr2: executing eos script show ipv6 bgp | json
5012026-04-10T03:56:42.783ZApr 10 03:56:42.771 DEBG [sc] cr2: starting
5022026-04-10T03:56:42.783ZApr 10 03:56:42.771 DEBG [sc] cr2: connecting to [::1]:52721
5032026-04-10T03:56:42.807ZApr 10 03:56:42.771 DEBG [sc] cr2 waiting for prompt
5042026-04-10T03:56:42.807ZApr 10 03:56:42.782 DEBG [sc] cr2: logging in
5052026-04-10T03:56:42.926ZApr 10 03:56:42.914 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
5062026-04-10T03:56:45.331ZApr 10 03:56:45.319 INFO trio bgp unnumbered test passed 🎉
5072026-04-10T03:56:45.331ZApr 10 03:56:45.319 INFO destroying runner for deployment mgtriou
5082026-04-10T03:56:45.331ZApr 10 03:56:45.319 INFO destroying deployment mgtriou
5092026-04-10T03:56:45.331ZApr 10 03:56:45.319 INFO destroying nodes
5102026-04-10T03:56:45.434ZApr 10 03:56:45.423 INFO destroying links
5112026-04-10T03:56:45.434ZApr 10 03:56:45.423 INFO destroying link mgtriou_ox_sn_vnic0
5122026-04-10T03:56:45.459ZApr 10 03:56:45.425 INFO destroying link mgtriou_ox_sn_sim0
5132026-04-10T03:56:45.459ZApr 10 03:56:45.427 INFO destroying link mgtriou_cr1_vn_vnic0
5142026-04-10T03:56:46.441ZApr 10 03:56:46.429 INFO destroying link mgtriou_cr1_vn_sim0
5152026-04-10T03:56:46.466ZApr 10 03:56:46.431 INFO destroying link mgtriou_ox_sn_vnic1
5162026-04-10T03:56:46.466ZApr 10 03:56:46.432 INFO destroying link mgtriou_ox_sn_sim1
5172026-04-10T03:56:46.466ZApr 10 03:56:46.433 INFO destroying link mgtriou_cr2_vn_vnic0
5182026-04-10T03:56:46.466ZApr 10 03:56:46.434 INFO destroying link mgtriou_cr2_vn_sim0
5192026-04-10T03:56:46.466ZApr 10 03:56:46.435 INFO destroying external links
5202026-04-10T03:56:46.466ZApr 10 03:56:46.435 INFO destroying external link mgtriou_ox_vn_vnic2
5212026-04-10T03:56:46.466ZApr 10 03:56:46.437 INFO destroying external link mgtriou_cr1_vn_vnic1
5222026-04-10T03:56:46.466ZApr 10 03:56:46.438 INFO destroying external link mgtriou_cr2_vn_vnic1
5232026-04-10T03:56:46.466ZApr 10 03:56:46.439 INFO destroying images
5242026-04-10T03:56:46.932ZApr 10 03:56:46.920 INFO destroying workspace at .falcon
5252026-04-10T03:56:46.957Zprocess exited: duration 489298 ms, exit code 0
 
5262026-04-10T03:56:47.008Zfound 0 output files