01KHQB42PZCH0WZ3HBBWK2ABPG: falcon

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

Buildomat Job: 01KHQB4HYZMBJPK22RN6BSA9CW

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-02-18T03:29:20.986Zjob dependencies complete; ready to run (waiting for 27 m 37 s)
22026-02-18T03:32:31.334Zjob assigned to worker 01KHQCQ4WGJC5SM07RZA10VK45 [factory edgar, gimlet/BRM42220060/132] (queued for 3 m 10 s)
32026-02-18T03:32:31.813Zdownloading input: /input/build/work/debug/ddmadm
42026-02-18T03:32:52.841Zdownloaded input: /input/build/work/debug/ddmadm
52026-02-18T03:32:52.842Zdownloading input: /input/build/work/debug/ddmd
62026-02-18T03:33:16.884Zdownloaded input: /input/build/work/debug/ddmd
72026-02-18T03:33:16.907Zdownloading input: /input/build-interop/work/testbed.tar.gz
82026-02-18T03:33:24.999Zdownloaded input: /input/build-interop/work/testbed.tar.gz
92026-02-18T03:33:24.999Zdownloading input: /input/build-interop/work/dhcp-server
102026-02-18T03:33:25.675Zdownloaded input: /input/build-interop/work/dhcp-server
112026-02-18T03:33:25.675Zdownloading input: /input/build/work/debug/mgadm
122026-02-18T03:33:50.816Zdownloaded input: /input/build/work/debug/mgadm
132026-02-18T03:33:50.816Zdownloading input: /input/build/work/debug/mgd
142026-02-18T03:34:18.970Zdownloaded input: /input/build/work/debug/mgd
152026-02-18T03:34:18.995Zdownloading input: /input/build/work/release/ddmadm
162026-02-18T03:34:20.441Zdownloaded input: /input/build/work/release/ddmadm
172026-02-18T03:34:20.441Zdownloading input: /input/build/work/release/ddmd
182026-02-18T03:34:22.620Zdownloaded input: /input/build/work/release/ddmd
192026-02-18T03:34:22.620Zdownloading input: /input/build/work/release/falcon-lab
202026-02-18T03:34:24.235Zdownloaded input: /input/build/work/release/falcon-lab
212026-02-18T03:34:24.235Zdownloading input: /input/build/work/release/mgadm
222026-02-18T03:34:26.738Zdownloaded input: /input/build/work/release/mgadm
232026-02-18T03:34:26.738Zdownloading input: /input/build/work/release/mgd
242026-02-18T03:34:29.671Zdownloaded input: /input/build/work/release/mgd
 
252026-02-18T03:34:29.671Zstarting task 0: "setup"
262026-02-18T03:34:29.695Z++ uname -s
272026-02-18T03:34:29.695Z+ kern=SunOS
282026-02-18T03:34:29.695Z+ build_user=build
292026-02-18T03:34:29.695Z+ build_uid=12345
302026-02-18T03:34:29.695Z+ work_dir=/work
312026-02-18T03:34:29.695Z+ input_dir=/input
322026-02-18T03:34:29.695Z+ [[ 0 == 12345 ]]
332026-02-18T03:34:29.695Z+ case "$kern" in
342026-02-18T03:34:29.695Z+ groupadd -g 12345 build
352026-02-18T03:34:29.723Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-02-18T03:34:31.710Z+ zfs create -o mountpoint=/work rpool/work
372026-02-18T03:34:32.341Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-02-18T03:34:32.364Z+ home_fs=
392026-02-18T03:34:32.364Z+ [[ '' == autofs ]]
402026-02-18T03:34:32.364Z+ mkdir -p /home/build
412026-02-18T03:34:32.365Z+ chown build:build /home/build /work
422026-02-18T03:34:33.355Z+ chmod 0700 /home/build /work
432026-02-18T03:34:33.378Zprocess exited: duration 3687 ms, exit code 0
 
442026-02-18T03:34:33.426Zstarting task 1: "authentication"
452026-02-18T03:34:33.522Zprocess exited: duration 96 ms, exit code 0
 
462026-02-18T03:34:33.570Zstarting task 2: "build"
472026-02-18T03:34:33.594Z+ set -e
482026-02-18T03:34:33.594Z+ banner zpool
492026-02-18T03:34:33.594Z
502026-02-18T03:34:33.594Z ###### ##### #### #### #
512026-02-18T03:34:33.594Z # # # # # # # #
522026-02-18T03:34:33.594Z # # # # # # # #
532026-02-18T03:34:33.594Z # ##### # # # # #
542026-02-18T03:34:33.594Z # # # # # # #
552026-02-18T03:34:33.594Z ###### # #### #### ######
562026-02-18T03:34:33.594Z
572026-02-18T03:34:33.594Z++ pfexec diskinfo -pH
582026-02-18T03:34:33.594Z++ sort -k8 -n -r
592026-02-18T03:34:33.618Z++ head -1
602026-02-18T03:34:33.618Z++ awk '{print $2}'
612026-02-18T03:34:33.643Z+ DISK=c9t0014EE81000BC57Cd0
622026-02-18T03:34:33.643Z+ export DISK
632026-02-18T03:34:33.643Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-02-18T03:34:33.710Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-02-18T03:34:33.736Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-02-18T03:37:48.527Z+ [[ '' =~ true ]]
672026-02-18T03:37:48.527Z+ pfexec chown 12345 /ci
682026-02-18T03:37:48.550Z+ cd /ci
692026-02-18T03:37:48.551Z+ export FALCON_DATASET=cpool/falcon
702026-02-18T03:37:48.551Z+ FALCON_DATASET=cpool/falcon
712026-02-18T03:37:48.551Z+ banner setup
722026-02-18T03:37:48.551Z
732026-02-18T03:37:48.551Z #### ###### ##### # # #####
742026-02-18T03:37:48.551Z # # # # # # #
752026-02-18T03:37:48.551Z #### ##### # # # # #
762026-02-18T03:37:48.551Z # # # # # #####
772026-02-18T03:37:48.551Z # # # # # # #
782026-02-18T03:37:48.551Z #### ###### # #### #
792026-02-18T03:37:48.551Z
802026-02-18T03:37:48.551Z+ cp /input/build-interop/work/dhcp-server .
812026-02-18T03:37:48.630Z+ cp /input/build/work/release/falcon-lab .
822026-02-18T03:37:48.654Z+ cp /input/build/work/release/mgd .
832026-02-18T03:37:48.710Z+ cp /input/build/work/release/ddmd .
842026-02-18T03:37:48.755Z+ chmod +x dhcp-server falcon-lab mgd ddmd
852026-02-18T03:37:48.778Z+ mkdir -p cargo-bay
862026-02-18T03:37:48.778Z+ mv mgd cargo-bay/
872026-02-18T03:37:48.778Z+ mv ddmd cargo-bay/
882026-02-18T03:37:48.778Z+ export EXT_INTERFACE=igb0
892026-02-18T03:37:48.778Z+ EXT_INTERFACE=igb0
902026-02-18T03:37:48.778Z++ bmat address ls -f extra -Ho first
912026-02-18T03:37:48.802Z+ first=10.151.7.164
922026-02-18T03:37:48.802Z++ bmat address ls -f extra -Ho last
932026-02-18T03:37:48.826Z+ last=10.151.7.227
942026-02-18T03:37:48.826Z++ bmat address ls -f extra -Ho gateway
952026-02-18T03:37:48.850Z+ gw=10.151.7.1
962026-02-18T03:37:48.850Z++ ipadm show-addr igb0/dhcp -po ADDR
972026-02-18T03:37:48.873Z++ sed 's#/.*##g'
982026-02-18T03:37:48.874Z+ server=10.151.7.100
992026-02-18T03:37:48.874Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1002026-02-18T03:37:48.874Z++ pfexec dladm create-vnic -l igb0 dummy0
1012026-02-18T03:37:48.874Z+ error=
1022026-02-18T03:37:48.874Z+ RUST_LOG=debug
1032026-02-18T03:37:48.874Z+ pfexec ./falcon-lab run trio-unnumbered
1042026-02-18T03:37:48.898ZFeb 18 03:37:48.879 DEBG using default route interface igb0
1052026-02-18T03:37:48.898ZFeb 18 03:37:48.879 DEBG using default route interface igb0
1062026-02-18T03:37:48.898ZFeb 18 03:37:48.879 DEBG using default route interface igb0
1072026-02-18T03:37:48.898ZFeb 18 03:37:48.879 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1082026-02-18T03:37:48.898ZFeb 18 03:37:48.879 INFO starting preflight for deployment mgtriou
1092026-02-18T03:37:48.898ZFeb 18 03:37:48.879 INFO propolis-server binary not found
1102026-02-18T03:37:48.898ZFeb 18 03:37:48.879 INFO downloading propolis server rev ff31c527515d65886e599fc07eb41240aeb767c6, writing to .falcon/bin/propolis-server
1112026-02-18T03:37:52.551ZFeb 18 03:37:52.540 INFO ovmf fd not found
1122026-02-18T03:37:52.551ZFeb 18 03:37:52.540 INFO downloading ovmf
1132026-02-18T03:37:52.927ZFeb 18 03:37:52.916 INFO base image for helios-2.9 does not exist, attempting to install
1142026-02-18T03:37:52.927ZFeb 18 03:37:52.916 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1152026-02-18T03:38:21.332ZFeb 18 03:38:21.321 INFO extracting image to /tmp/helios-2.9_0.raw
1162026-02-18T03:38:54.768ZFeb 18 03:38:54.756 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1172026-02-18T03:38:54.815ZFeb 18 03:38:54.803 INFO copying image data to zvol
1182026-02-18T03:39:05.342ZFeb 18 03:39:05.331 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1192026-02-18T03:39:08.728ZFeb 18 03:39:08.717 INFO base image for debian-13.2 does not exist, attempting to install
1202026-02-18T03:39:08.728ZFeb 18 03:39:08.717 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1212026-02-18T03:39:28.510ZFeb 18 03:39:28.499 INFO extracting image to /tmp/debian-13.2_0.raw
1222026-02-18T03:39:49.353ZFeb 18 03:39:49.342 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1232026-02-18T03:39:49.377ZFeb 18 03:39:49.366 INFO copying image data to zvol
1242026-02-18T03:39:55.504ZFeb 18 03:39:55.493 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1252026-02-18T03:39:59.298ZFeb 18 03:39:59.287 INFO base image for eos-4.35 does not exist, attempting to install
1262026-02-18T03:39:59.298ZFeb 18 03:39:59.287 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
1272026-02-18T03:40:46.457ZFeb 18 03:40:46.446 INFO extracting image to /tmp/eos-4.35_0.raw
1282026-02-18T03:42:10.139ZFeb 18 03:42:10.127 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
1292026-02-18T03:42:10.163ZFeb 18 03:42:10.152 INFO copying image data to zvol
1302026-02-18T03:42:58.621ZFeb 18 03:42:58.609 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
1312026-02-18T03:43:02.015ZFeb 18 03:43:02.003 INFO creating links
1322026-02-18T03:43:02.015ZFeb 18 03:43:02.003 DEBG destroying link mgtriou_ox_sn_vnic0
1332026-02-18T03:43:02.039ZFeb 18 03:43:02.004 DEBG destroying link mgtriou_ox_sn_sim0
1342026-02-18T03:43:02.039ZFeb 18 03:43:02.004 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1352026-02-18T03:43:02.039ZFeb 18 03:43:02.006 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1362026-02-18T03:43:02.039ZFeb 18 03:43:02.013 DEBG link pair created
1372026-02-18T03:43:02.039ZFeb 18 03:43:02.013 DEBG destroying link mgtriou_cr1_vn_vnic0
1382026-02-18T03:43:02.039ZFeb 18 03:43:02.013 DEBG destroying link mgtriou_cr1_vn_sim0
1392026-02-18T03:43:02.039ZFeb 18 03:43:02.013 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1402026-02-18T03:43:02.039ZFeb 18 03:43:02.015 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1412026-02-18T03:43:02.039ZFeb 18 03:43:02.021 DEBG link pair created
1422026-02-18T03:43:02.039ZFeb 18 03:43:02.021 DEBG destroying link mgtriou_ox_sn_vnic1
1432026-02-18T03:43:02.039ZFeb 18 03:43:02.021 DEBG destroying link mgtriou_ox_sn_sim1
1442026-02-18T03:43:02.039ZFeb 18 03:43:02.021 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1452026-02-18T03:43:02.039ZFeb 18 03:43:02.023 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1462026-02-18T03:43:02.063ZFeb 18 03:43:02.029 DEBG link pair created
1472026-02-18T03:43:02.063ZFeb 18 03:43:02.029 DEBG destroying link mgtriou_cr2_vn_vnic0
1482026-02-18T03:43:02.063ZFeb 18 03:43:02.029 DEBG destroying link mgtriou_cr2_vn_sim0
1492026-02-18T03:43:02.063ZFeb 18 03:43:02.029 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1502026-02-18T03:43:02.063ZFeb 18 03:43:02.031 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1512026-02-18T03:43:02.063ZFeb 18 03:43:02.037 DEBG link pair created
1522026-02-18T03:43:02.063ZFeb 18 03:43:02.037 INFO creating external links
1532026-02-18T03:43:02.063ZFeb 18 03:43:02.037 DEBG destroying external link mgtriou_ox_vn_vnic2
1542026-02-18T03:43:02.063ZFeb 18 03:43:02.037 INFO creating external link mgtriou_ox_vn_vnic2
1552026-02-18T03:43:02.063ZFeb 18 03:43:02.039 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1562026-02-18T03:43:02.063ZFeb 18 03:43:02.039 DEBG destroying external link mgtriou_cr1_vn_vnic1
1572026-02-18T03:43:02.063ZFeb 18 03:43:02.039 INFO creating external link mgtriou_cr1_vn_vnic1
1582026-02-18T03:43:02.063ZFeb 18 03:43:02.041 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1592026-02-18T03:43:02.063ZFeb 18 03:43:02.041 DEBG destroying external link mgtriou_cr2_vn_vnic1
1602026-02-18T03:43:02.063ZFeb 18 03:43:02.041 INFO creating external link mgtriou_cr2_vn_vnic1
1612026-02-18T03:43:02.063ZFeb 18 03:43:02.042 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1622026-02-18T03:43:02.063ZFeb 18 03:43:02.042 INFO creating nodes
1632026-02-18T03:43:02.063ZFeb 18 03:43:02.042 INFO ox: launching node
1642026-02-18T03:43:02.063ZFeb 18 03:43:02.045 INFO cr1: launching node
1652026-02-18T03:43:02.063ZFeb 18 03:43:02.047 INFO cr2: launching node
1662026-02-18T03:43:02.087ZFeb 18 03:43:02.060 INFO launched instance ox with pid 849 on port 34450
1672026-02-18T03:43:02.087ZFeb 18 03:43:02.061 INFO ox: instance ensure
1682026-02-18T03:43:02.087ZFeb 18 03:43:02.061 INFO launched instance cr1 with pid 850 on port 64918
1692026-02-18T03:43:02.087ZFeb 18 03:43:02.061 INFO cr1: instance ensure
1702026-02-18T03:43:02.087ZFeb 18 03:43:02.061 INFO launched instance cr2 with pid 851 on port 63577
1712026-02-18T03:43:02.087ZFeb 18 03:43:02.061 INFO cr2: instance ensure
1722026-02-18T03:43:04.239ZFeb 18 03:43:04.227 INFO ox: instance run
1732026-02-18T03:43:04.262ZFeb 18 03:43:04.227 DEBG [sc] ox: starting
1742026-02-18T03:43:04.262ZFeb 18 03:43:04.227 DEBG [sc] ox: connecting to [::1]:34450
1752026-02-18T03:43:04.262ZFeb 18 03:43:04.229 DEBG [sc] ox waiting for prompt
1762026-02-18T03:43:04.336ZFeb 18 03:43:04.324 INFO cr2: instance run
1772026-02-18T03:43:04.359ZFeb 18 03:43:04.325 DEBG [sc] cr2: starting
1782026-02-18T03:43:04.359ZFeb 18 03:43:04.325 DEBG [sc] cr2: connecting to [::1]:63577
1792026-02-18T03:43:04.359ZFeb 18 03:43:04.325 DEBG [sc] cr2 waiting for prompt
1802026-02-18T03:43:04.360ZFeb 18 03:43:04.345 INFO cr1: instance run
1812026-02-18T03:43:04.360ZFeb 18 03:43:04.346 DEBG [sc] cr1: starting
1822026-02-18T03:43:04.360ZFeb 18 03:43:04.346 DEBG [sc] cr1: connecting to [::1]:64918
1832026-02-18T03:43:04.360ZFeb 18 03:43:04.346 DEBG [sc] cr1 waiting for prompt
1842026-02-18T03:43:21.539ZFeb 18 03:43:21.528 DEBG [sc] cr1: logging in
1852026-02-18T03:43:21.884ZFeb 18 03:43:21.872 INFO cr1: mounting /opt/cargo-bay
1862026-02-18T03:43:21.884ZFeb 18 03:43:21.872 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
1872026-02-18T03:43:21.908ZFeb 18 03:43:21.884 DEBG [sc] cr1: executing command `cd`
1882026-02-18T03:43:21.908ZFeb 18 03:43:21.895 INFO cr1: finished mounting /opt/cargo-bay
1892026-02-18T03:43:21.908ZFeb 18 03:43:21.895 DEBG [sc] cr1: executing command `hostname cr1`
1902026-02-18T03:43:21.931ZFeb 18 03:43:21.906 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
1912026-02-18T03:43:21.932ZFeb 18 03:43:21.917 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
1922026-02-18T03:43:21.955ZFeb 18 03:43:21.928 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
1932026-02-18T03:43:21.955ZFeb 18 03:43:21.940 INFO cr1: logging out
1942026-02-18T03:43:24.016ZFeb 18 03:43:24.004 INFO cr1: logged out
1952026-02-18T03:43:24.943ZFeb 18 03:43:24.931 DEBG [sc] ox: logging in
1962026-02-18T03:43:25.107ZFeb 18 03:43:25.096 INFO ox: mounting /opt/cargo-bay
1972026-02-18T03:43:25.107ZFeb 18 03:43:25.096 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
1982026-02-18T03:43:25.975ZFeb 18 03:43:25.963 DEBG [sc] ox: executing command `cd`
1992026-02-18T03:43:25.999ZFeb 18 03:43:25.974 INFO ox: finished mounting /opt/cargo-bay
2002026-02-18T03:43:25.999ZFeb 18 03:43:25.974 DEBG [sc] ox: executing command `hostname ox`
2012026-02-18T03:43:25.999ZFeb 18 03:43:25.984 DEBG [sc] cr2: logging in
2022026-02-18T03:43:25.999ZFeb 18 03:43:25.985 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2032026-02-18T03:43:26.023ZFeb 18 03:43:25.996 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2042026-02-18T03:43:26.023ZFeb 18 03:43:26.007 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2052026-02-18T03:43:26.047ZFeb 18 03:43:26.019 INFO ox: logging out
2062026-02-18T03:43:26.095ZFeb 18 03:43:26.084 INFO ox: logged out
2072026-02-18T03:43:26.422ZFeb 18 03:43:26.410 INFO cr2: mounting /opt/cargo-bay
2082026-02-18T03:43:26.422ZFeb 18 03:43:26.410 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2092026-02-18T03:43:26.445ZFeb 18 03:43:26.432 DEBG [sc] cr2: executing command `cd`
2102026-02-18T03:43:26.469ZFeb 18 03:43:26.443 INFO cr2: finished mounting /opt/cargo-bay
2112026-02-18T03:43:26.469ZFeb 18 03:43:26.443 DEBG [sc] cr2: executing command `hostname cr2`
2122026-02-18T03:43:26.469ZFeb 18 03:43:26.454 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
2132026-02-18T03:43:26.492ZFeb 18 03:43:26.465 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
2142026-02-18T03:43:26.492ZFeb 18 03:43:26.475 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
2152026-02-18T03:43:26.516ZFeb 18 03:43:26.487 INFO cr2: logging out
2162026-02-18T03:43:29.373ZFeb 18 03:43:29.361 INFO cr2: logged out
2172026-02-18T03:43:29.396ZFeb 18 03:43:29.361 DEBG [sc] ox: starting
2182026-02-18T03:43:29.396ZFeb 18 03:43:29.361 DEBG [sc] ox: connecting to [::1]:34450
2192026-02-18T03:43:29.396ZFeb 18 03:43:29.362 DEBG [sc] ox waiting for prompt
2202026-02-18T03:43:29.397ZFeb 18 03:43:29.373 DEBG [sc] ox: logging in
2212026-02-18T03:43:29.473ZFeb 18 03:43:29.461 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2222026-02-18T03:43:36.029ZFeb 18 03:43:36.017 DEBG [sc] ox: starting
2232026-02-18T03:43:36.029ZFeb 18 03:43:36.017 DEBG [sc] ox: connecting to [::1]:34450
2242026-02-18T03:43:36.053ZFeb 18 03:43:36.018 DEBG [sc] ox waiting for prompt
2252026-02-18T03:43:36.053ZFeb 18 03:43:36.028 DEBG [sc] ox: logging in
2262026-02-18T03:43:36.117ZFeb 18 03:43:36.106 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2272026-02-18T03:43:36.205ZFeb 18 03:43:36.194 DEBG [sc] ox: starting
2282026-02-18T03:43:36.205ZFeb 18 03:43:36.194 DEBG [sc] ox: connecting to [::1]:34450
2292026-02-18T03:43:36.229ZFeb 18 03:43:36.194 DEBG [sc] ox waiting for prompt
2302026-02-18T03:43:36.229ZFeb 18 03:43:36.205 DEBG [sc] ox: logging in
2312026-02-18T03:43:36.304ZFeb 18 03:43:36.292 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2322026-02-18T03:43:36.371ZFeb 18 03:43:36.359 INFO cr1: installing frr
2332026-02-18T03:43:36.371ZFeb 18 03:43:36.359 INFO waiting for ceos to initialize
2342026-02-18T03:43:36.371ZFeb 18 03:43:36.359 INFO ox: setting up npuvm
2352026-02-18T03:43:36.395ZFeb 18 03:43:36.359 DEBG [sc] cr1: starting
2362026-02-18T03:43:36.395ZFeb 18 03:43:36.359 DEBG [sc] cr1: connecting to [::1]:64918
2372026-02-18T03:43:36.395ZFeb 18 03:43:36.359 DEBG [sc] cr2: starting
2382026-02-18T03:43:36.395ZFeb 18 03:43:36.359 DEBG [sc] cr2: connecting to [::1]:63577
2392026-02-18T03:43:36.395ZFeb 18 03:43:36.359 DEBG [sc] ox: starting
2402026-02-18T03:43:36.395ZFeb 18 03:43:36.359 DEBG [sc] ox: connecting to [::1]:34450
2412026-02-18T03:43:36.395ZFeb 18 03:43:36.360 DEBG [sc] cr1 waiting for prompt
2422026-02-18T03:43:36.395ZFeb 18 03:43:36.360 DEBG [sc] cr2 waiting for prompt
2432026-02-18T03:43:36.395ZFeb 18 03:43:36.360 DEBG [sc] ox waiting for prompt
2442026-02-18T03:43:36.395ZFeb 18 03:43:36.369 DEBG [sc] ox: logging in
2452026-02-18T03:43:36.395ZFeb 18 03:43:36.371 DEBG [sc] cr1: logging in
2462026-02-18T03:43:36.395ZFeb 18 03:43:36.371 DEBG [sc] cr2: logging in
2472026-02-18T03:43:36.458ZFeb 18 03:43:36.447 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2482026-02-18T03:43:36.515ZFeb 18 03:43:36.504 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2492026-02-18T03:43:36.790ZFeb 18 03:43:36.778 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2502026-02-18T03:43:38.761ZFeb 18 03:43:38.749 INFO cr2: executing eos script show version
2512026-02-18T03:43:38.761ZFeb 18 03:43:38.750 DEBG [sc] cr2: starting
2522026-02-18T03:43:38.761ZFeb 18 03:43:38.750 DEBG [sc] cr2: connecting to [::1]:63577
2532026-02-18T03:43:38.785ZFeb 18 03:43:38.750 DEBG [sc] cr2 waiting for prompt
2542026-02-18T03:43:38.785ZFeb 18 03:43:38.760 DEBG [sc] cr2: logging in
2552026-02-18T03:43:38.915ZFeb 18 03:43:38.903 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2562026-02-18T03:43:41.909ZFeb 18 03:43:41.898 DEBG [sc] ox: starting
2572026-02-18T03:43:41.909ZFeb 18 03:43:41.898 DEBG [sc] ox: connecting to [::1]:34450
2582026-02-18T03:43:41.934ZFeb 18 03:43:41.898 DEBG [sc] ox waiting for prompt
2592026-02-18T03:43:41.934ZFeb 18 03:43:41.908 DEBG [sc] ox: logging in
2602026-02-18T03:43:41.997ZFeb 18 03:43:41.985 DEBG [sc] ox: executing command `chmod +x npuvm`
2612026-02-18T03:43:42.064ZFeb 18 03:43:42.052 DEBG [sc] ox: starting
2622026-02-18T03:43:42.064ZFeb 18 03:43:42.052 DEBG [sc] ox: connecting to [::1]:34450
2632026-02-18T03:43:42.087ZFeb 18 03:43:42.052 DEBG [sc] ox waiting for prompt
2642026-02-18T03:43:42.087ZFeb 18 03:43:42.063 DEBG [sc] ox: logging in
2652026-02-18T03:43:42.152ZFeb 18 03:43:42.140 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 `
2662026-02-18T03:43:46.430ZFeb 18 03:43:46.418 DEBG [sc] cr2: starting
2672026-02-18T03:43:46.430ZFeb 18 03:43:46.418 DEBG [sc] cr2: connecting to [::1]:63577
2682026-02-18T03:43:46.455ZFeb 18 03:43:46.419 DEBG [sc] cr2 waiting for prompt
2692026-02-18T03:43:46.455ZFeb 18 03:43:46.429 DEBG [sc] cr2: logging in
2702026-02-18T03:43:46.584ZFeb 18 03:43:46.573 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2712026-02-18T03:43:48.689ZFeb 18 03:43:48.678 INFO cr2: executing eos script show version
2722026-02-18T03:43:48.689ZFeb 18 03:43:48.678 DEBG [sc] cr2: starting
2732026-02-18T03:43:48.689ZFeb 18 03:43:48.678 DEBG [sc] cr2: connecting to [::1]:63577
2742026-02-18T03:43:48.713ZFeb 18 03:43:48.678 DEBG [sc] cr2 waiting for prompt
2752026-02-18T03:43:48.713ZFeb 18 03:43:48.688 DEBG [sc] cr2: logging in
2762026-02-18T03:43:48.866ZFeb 18 03:43:48.854 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2772026-02-18T03:43:51.241ZFeb 18 03:43:51.229 DEBG [sc] cr2: starting
2782026-02-18T03:43:51.241ZFeb 18 03:43:51.229 DEBG [sc] cr2: connecting to [::1]:63577
2792026-02-18T03:43:51.265ZFeb 18 03:43:51.230 DEBG [sc] cr2 waiting for prompt
2802026-02-18T03:43:51.265ZFeb 18 03:43:51.240 DEBG [sc] cr2: logging in
2812026-02-18T03:43:51.395ZFeb 18 03:43:51.383 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
2822026-02-18T03:43:53.550ZFeb 18 03:43:53.538 INFO cr2: executing eos script show version
2832026-02-18T03:43:53.550ZFeb 18 03:43:53.538 DEBG [sc] cr2: starting
2842026-02-18T03:43:53.550ZFeb 18 03:43:53.538 DEBG [sc] cr2: connecting to [::1]:63577
2852026-02-18T03:43:53.573ZFeb 18 03:43:53.539 DEBG [sc] cr2 waiting for prompt
2862026-02-18T03:43:53.573ZFeb 18 03:43:53.549 DEBG [sc] cr2: logging in
2872026-02-18T03:43:53.705ZFeb 18 03:43:53.693 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
2882026-02-18T03:43:56.210ZFeb 18 03:43:56.198 INFO cr2: executing eos script
2892026-02-18T03:43:56.210Z enable
2902026-02-18T03:43:56.210Z configure
2912026-02-18T03:43:56.210Z ipv6 unicast-routing
2922026-02-18T03:43:56.210Z ip routing ipv6 interfaces
2932026-02-18T03:43:56.210Z ip routing
2942026-02-18T03:43:56.210Z ip route 1.2.3.0/24 null0
2952026-02-18T03:43:56.210Z ipv6 route fd99::/64 null0
2962026-02-18T03:43:56.210Z interface et1
2972026-02-18T03:43:56.210Z no switchport
2982026-02-18T03:43:56.210Z ipv6 enable
2992026-02-18T03:43:56.210Z
3002026-02-18T03:43:56.210Z router bgp 45
3012026-02-18T03:43:56.210Z router-id 1.2.3.1
3022026-02-18T03:43:56.210Z no bgp default ipv4-unicast
3032026-02-18T03:43:56.210Z timers bgp 2 6
3042026-02-18T03:43:56.210Z neighbor ebgp peer group
3052026-02-18T03:43:56.210Z neighbor ebgp remote-as 33
3062026-02-18T03:43:56.210Z neighbor interface Et1 peer-group ebgp
3072026-02-18T03:43:56.210Z address-family ipv4
3082026-02-18T03:43:56.210Z neighbor ebgp activate
3092026-02-18T03:43:56.210Z neighbor ebgp next-hop address-family ipv6 originate
3102026-02-18T03:43:56.210Z network 1.2.3.0/24
3112026-02-18T03:43:56.210Z exit
3122026-02-18T03:43:56.210Z address-family ipv6
3132026-02-18T03:43:56.210Z neighbor ebgp activate
3142026-02-18T03:43:56.210Z neighbor ebgp next-hop address-family ipv6 originate
3152026-02-18T03:43:56.210Z network fd99::/64
3162026-02-18T03:43:56.210Z exit
3172026-02-18T03:43:56.210Z exit
3182026-02-18T03:43:56.210Z
3192026-02-18T03:43:56.211ZFeb 18 03:43:56.198 DEBG [sc] cr2: starting
3202026-02-18T03:43:56.211ZFeb 18 03:43:56.198 DEBG [sc] cr2: connecting to [::1]:63577
3212026-02-18T03:43:56.234ZFeb 18 03:43:56.198 DEBG [sc] cr2 waiting for prompt
3222026-02-18T03:43:56.234ZFeb 18 03:43:56.208 DEBG [sc] cr2: logging in
3232026-02-18T03:43:56.386ZFeb 18 03:43:56.374 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
3242026-02-18T03:43:56.386Z enable
3252026-02-18T03:43:56.386Z configure
3262026-02-18T03:43:56.386Z ipv6 unicast-routing
3272026-02-18T03:43:56.386Z ip routing ipv6 interfaces
3282026-02-18T03:43:56.386Z ip routing
3292026-02-18T03:43:56.386Z ip route 1.2.3.0/24 null0
3302026-02-18T03:43:56.386Z ipv6 route fd99::/64 null0
3312026-02-18T03:43:56.386Z interface et1
3322026-02-18T03:43:56.386Z no switchport
3332026-02-18T03:43:56.386Z ipv6 enable
3342026-02-18T03:43:56.386Z
3352026-02-18T03:43:56.386Z router bgp 45
3362026-02-18T03:43:56.386Z router-id 1.2.3.1
3372026-02-18T03:43:56.386Z no bgp default ipv4-unicast
3382026-02-18T03:43:56.386Z timers bgp 2 6
3392026-02-18T03:43:56.386Z neighbor ebgp peer group
3402026-02-18T03:43:56.386Z neighbor ebgp remote-as 33
3412026-02-18T03:43:56.386Z neighbor interface Et1 peer-group ebgp
3422026-02-18T03:43:56.386Z address-family ipv4
3432026-02-18T03:43:56.386Z neighbor ebgp activate
3442026-02-18T03:43:56.387Z neighbor ebgp next-hop address-family ipv6 originate
3452026-02-18T03:43:56.387Z network 1.2.3.0/24
3462026-02-18T03:43:56.387Z exit
3472026-02-18T03:43:56.387Z address-family ipv6
3482026-02-18T03:43:56.387Z neighbor ebgp activate
3492026-02-18T03:43:56.387Z neighbor ebgp next-hop address-family ipv6 originate
3502026-02-18T03:43:56.387Z network fd99::/64
3512026-02-18T03:43:56.387Z exit
3522026-02-18T03:43:56.387Z exit
3532026-02-18T03:43:56.387Z '`
3542026-02-18T03:44:14.540ZFeb 18 03:44:14.528 INFO cr1: enabling frr daemon bgpd
3552026-02-18T03:44:14.540ZFeb 18 03:44:14.528 DEBG [sc] cr1: starting
3562026-02-18T03:44:14.540ZFeb 18 03:44:14.528 DEBG [sc] cr1: connecting to [::1]:64918
3572026-02-18T03:44:14.564ZFeb 18 03:44:14.528 DEBG [sc] cr1 waiting for prompt
3582026-02-18T03:44:14.564ZFeb 18 03:44:14.539 DEBG [sc] cr1: logging in
3592026-02-18T03:44:14.705ZFeb 18 03:44:14.693 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
3602026-02-18T03:44:16.779ZFeb 18 03:44:16.767 DEBG [sc] cr1: starting
3612026-02-18T03:44:16.779ZFeb 18 03:44:16.767 DEBG [sc] cr1: connecting to [::1]:64918
3622026-02-18T03:44:16.805ZFeb 18 03:44:16.768 DEBG [sc] cr1 waiting for prompt
3632026-02-18T03:44:16.805ZFeb 18 03:44:16.778 DEBG [sc] cr1: logging in
3642026-02-18T03:44:16.933ZFeb 18 03:44:16.921 DEBG [sc] cr1: executing command `systemctl restart frr`
3652026-02-18T03:44:24.580ZFeb 18 03:44:24.567 INFO cr1: executing frr script
3662026-02-18T03:44:24.580Z configure
3672026-02-18T03:44:24.580Z ip forwarding
3682026-02-18T03:44:24.580Z ipv6 forwarding
3692026-02-18T03:44:24.580Z ip route 1.2.3.0/24 null0
3702026-02-18T03:44:24.580Z ipv6 route fd99::/64 null0
3712026-02-18T03:44:24.580Z route-map PERMIT-ALL permit 10
3722026-02-18T03:44:24.580Z router bgp 44
3732026-02-18T03:44:24.580Z timers bgp 2 6
3742026-02-18T03:44:24.580Z neighbor enp0s8 interface remote-as external
3752026-02-18T03:44:24.580Z neighbor enp0s8 timers connect 1
3762026-02-18T03:44:24.580Z address-family ipv4 unicast
3772026-02-18T03:44:24.580Z network 1.2.3.0/24
3782026-02-18T03:44:24.580Z neighbor enp0s8 activate
3792026-02-18T03:44:24.580Z neighbor enp0s8 route-map PERMIT-ALL out
3802026-02-18T03:44:24.580Z neighbor enp0s8 route-map PERMIT-ALL in
3812026-02-18T03:44:24.580Z exit-address-family
3822026-02-18T03:44:24.580Z address-family ipv6 unicast
3832026-02-18T03:44:24.581Z network fd99::/64
3842026-02-18T03:44:24.581Z neighbor enp0s8 activate
3852026-02-18T03:44:24.581Z neighbor enp0s8 route-map PERMIT-ALL out
3862026-02-18T03:44:24.581Z neighbor enp0s8 route-map PERMIT-ALL in
3872026-02-18T03:44:24.581Z exit-address-family
3882026-02-18T03:44:24.581Z exit
3892026-02-18T03:44:24.581Z
3902026-02-18T03:44:24.581ZFeb 18 03:44:24.567 DEBG [sc] cr1: starting
3912026-02-18T03:44:24.581ZFeb 18 03:44:24.567 DEBG [sc] cr1: connecting to [::1]:64918
3922026-02-18T03:44:24.604ZFeb 18 03:44:24.568 DEBG [sc] cr1 waiting for prompt
3932026-02-18T03:44:24.604ZFeb 18 03:44:24.580 DEBG [sc] cr1: logging in
3942026-02-18T03:44:24.724ZFeb 18 03:44:24.713 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 ' '`
3952026-02-18T03:45:21.186ZFeb 18 03:45:21.174 DEBG [sc] ox: starting
3962026-02-18T03:45:21.186ZFeb 18 03:45:21.174 DEBG [sc] ox: connecting to [::1]:34450
3972026-02-18T03:45:21.210ZFeb 18 03:45:21.174 DEBG [sc] ox waiting for prompt
3982026-02-18T03:45:21.210ZFeb 18 03:45:21.185 DEBG [sc] ox: logging in
3992026-02-18T03:45:21.276ZFeb 18 03:45:21.264 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
4002026-02-18T03:45:21.513ZFeb 18 03:45:21.501 DEBG [sc] ox: starting
4012026-02-18T03:45:21.513ZFeb 18 03:45:21.501 DEBG [sc] ox: connecting to [::1]:34450
4022026-02-18T03:45:21.537ZFeb 18 03:45:21.502 DEBG [sc] ox waiting for prompt
4032026-02-18T03:45:21.537ZFeb 18 03:45:21.513 DEBG [sc] ox: logging in
4042026-02-18T03:45:21.603ZFeb 18 03:45:21.591 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4052026-02-18T03:45:22.627ZFeb 18 03:45:22.615 DEBG [sc] ox: starting
4062026-02-18T03:45:22.627ZFeb 18 03:45:22.615 DEBG [sc] ox: connecting to [::1]:34450
4072026-02-18T03:45:22.651ZFeb 18 03:45:22.616 DEBG [sc] ox waiting for prompt
4082026-02-18T03:45:22.651ZFeb 18 03:45:22.616 DEBG [sc] ox: logging in
4092026-02-18T03:45:22.705ZFeb 18 03:45:22.693 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
4102026-02-18T03:45:22.772ZFeb 18 03:45:22.760 DEBG [sc] ox: starting
4112026-02-18T03:45:22.772ZFeb 18 03:45:22.760 DEBG [sc] ox: connecting to [::1]:34450
4122026-02-18T03:45:22.795ZFeb 18 03:45:22.761 DEBG [sc] ox waiting for prompt
4132026-02-18T03:45:22.795ZFeb 18 03:45:22.770 DEBG [sc] ox: logging in
4142026-02-18T03:45:22.859ZFeb 18 03:45:22.847 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
4152026-02-18T03:45:22.915ZFeb 18 03:45:22.903 DEBG [sc] ox: starting
4162026-02-18T03:45:22.915ZFeb 18 03:45:22.903 DEBG [sc] ox: connecting to [::1]:34450
4172026-02-18T03:45:22.939ZFeb 18 03:45:22.904 DEBG [sc] ox waiting for prompt
4182026-02-18T03:45:22.939ZFeb 18 03:45:22.914 DEBG [sc] ox: logging in
4192026-02-18T03:45:23.003ZFeb 18 03:45:22.991 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
4202026-02-18T03:45:23.059ZFeb 18 03:45:23.047 DEBG [sc] ox: starting
4212026-02-18T03:45:23.059ZFeb 18 03:45:23.047 DEBG [sc] ox: connecting to [::1]:34450
4222026-02-18T03:45:23.082ZFeb 18 03:45:23.048 DEBG [sc] ox waiting for prompt
4232026-02-18T03:45:23.082ZFeb 18 03:45:23.058 DEBG [sc] ox: logging in
4242026-02-18T03:45:23.147ZFeb 18 03:45:23.135 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
4252026-02-18T03:45:23.202ZFeb 18 03:45:23.191 DEBG [sc] ox: starting
4262026-02-18T03:45:23.203ZFeb 18 03:45:23.191 DEBG [sc] ox: connecting to [::1]:34450
4272026-02-18T03:45:23.226ZFeb 18 03:45:23.191 DEBG [sc] ox waiting for prompt
4282026-02-18T03:45:23.226ZFeb 18 03:45:23.202 DEBG [sc] ox: logging in
4292026-02-18T03:45:23.291ZFeb 18 03:45:23.279 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
4302026-02-18T03:45:23.346ZFeb 18 03:45:23.335 DEBG [sc] ox: starting
4312026-02-18T03:45:23.346ZFeb 18 03:45:23.335 DEBG [sc] ox: connecting to [::1]:34450
4322026-02-18T03:45:23.370ZFeb 18 03:45:23.335 DEBG [sc] ox waiting for prompt
4332026-02-18T03:45:23.370ZFeb 18 03:45:23.345 DEBG [sc] ox: logging in
4342026-02-18T03:45:23.434ZFeb 18 03:45:23.422 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
4352026-02-18T03:45:23.490ZFeb 18 03:45:23.478 DEBG [sc] ox: starting
4362026-02-18T03:45:23.490ZFeb 18 03:45:23.478 DEBG [sc] ox: connecting to [::1]:34450
4372026-02-18T03:45:23.513ZFeb 18 03:45:23.479 DEBG [sc] ox waiting for prompt
4382026-02-18T03:45:23.513ZFeb 18 03:45:23.488 DEBG [sc] ox: logging in
4392026-02-18T03:45:23.578ZFeb 18 03:45:23.566 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
4402026-02-18T03:45:23.634ZFeb 18 03:45:23.622 DEBG [sc] ox: starting
4412026-02-18T03:45:23.634ZFeb 18 03:45:23.622 DEBG [sc] ox: connecting to [::1]:34450
4422026-02-18T03:45:23.657ZFeb 18 03:45:23.623 DEBG [sc] ox waiting for prompt
4432026-02-18T03:45:23.657ZFeb 18 03:45:23.632 DEBG [sc] ox: logging in
4442026-02-18T03:45:23.721ZFeb 18 03:45:23.709 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
4452026-02-18T03:45:23.780ZFeb 18 03:45:23.768 INFO adding BGP router to mgd
4462026-02-18T03:45:25.799ZFeb 18 03:45:25.787 INFO cr1: executing frr script show ip bgp json
4472026-02-18T03:45:25.799ZFeb 18 03:45:25.787 DEBG [sc] cr1: starting
4482026-02-18T03:45:25.799ZFeb 18 03:45:25.787 DEBG [sc] cr1: connecting to [::1]:64918
4492026-02-18T03:45:25.822ZFeb 18 03:45:25.788 DEBG [sc] cr1 waiting for prompt
4502026-02-18T03:45:25.823ZFeb 18 03:45:25.799 DEBG [sc] cr1: logging in
4512026-02-18T03:45:26.159ZFeb 18 03:45:26.147 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'`
4522026-02-18T03:45:28.278ZFeb 18 03:45:28.266 INFO cr1: executing frr script show bgp json
4532026-02-18T03:45:28.278ZFeb 18 03:45:28.266 DEBG [sc] cr1: starting
4542026-02-18T03:45:28.278ZFeb 18 03:45:28.266 DEBG [sc] cr1: connecting to [::1]:64918
4552026-02-18T03:45:28.302ZFeb 18 03:45:28.267 DEBG [sc] cr1 waiting for prompt
4562026-02-18T03:45:28.302ZFeb 18 03:45:28.277 DEBG [sc] cr1: logging in
4572026-02-18T03:45:28.444ZFeb 18 03:45:28.432 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'`
4582026-02-18T03:45:30.575ZFeb 18 03:45:30.563 INFO cr2: executing eos script show ip bgp | json
4592026-02-18T03:45:30.575ZFeb 18 03:45:30.563 DEBG [sc] cr2: starting
4602026-02-18T03:45:30.575ZFeb 18 03:45:30.563 DEBG [sc] cr2: connecting to [::1]:63577
4612026-02-18T03:45:30.599ZFeb 18 03:45:30.564 DEBG [sc] cr2 waiting for prompt
4622026-02-18T03:45:30.599ZFeb 18 03:45:30.575 DEBG [sc] cr2: logging in
4632026-02-18T03:45:30.915ZFeb 18 03:45:30.903 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
4642026-02-18T03:45:33.273ZFeb 18 03:45:33.261 INFO cr2: executing eos script show ipv6 bgp | json
4652026-02-18T03:45:33.273ZFeb 18 03:45:33.261 DEBG [sc] cr2: starting
4662026-02-18T03:45:33.273ZFeb 18 03:45:33.261 DEBG [sc] cr2: connecting to [::1]:63577
4672026-02-18T03:45:33.297ZFeb 18 03:45:33.262 DEBG [sc] cr2 waiting for prompt
4682026-02-18T03:45:33.297ZFeb 18 03:45:33.271 DEBG [sc] cr2: logging in
4692026-02-18T03:45:33.426ZFeb 18 03:45:33.414 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
4702026-02-18T03:45:35.850ZFeb 18 03:45:35.838 INFO trio bgp unnumbered test passed 🎉
4712026-02-18T03:45:35.850ZFeb 18 03:45:35.838 INFO destroying runner for deployment mgtriou
4722026-02-18T03:45:35.850ZFeb 18 03:45:35.838 INFO destroying deployment mgtriou
4732026-02-18T03:45:35.850ZFeb 18 03:45:35.838 INFO destroying nodes
4742026-02-18T03:45:35.982ZFeb 18 03:45:35.971 INFO destroying links
4752026-02-18T03:45:35.982ZFeb 18 03:45:35.971 INFO destroying link mgtriou_ox_sn_vnic0
4762026-02-18T03:45:36.006ZFeb 18 03:45:35.973 INFO destroying link mgtriou_ox_sn_sim0
4772026-02-18T03:45:36.006ZFeb 18 03:45:35.975 INFO destroying link mgtriou_cr1_vn_vnic0
4782026-02-18T03:45:36.990ZFeb 18 03:45:36.979 INFO destroying link mgtriou_cr1_vn_sim0
4792026-02-18T03:45:37.014ZFeb 18 03:45:36.980 INFO destroying link mgtriou_ox_sn_vnic1
4802026-02-18T03:45:37.014ZFeb 18 03:45:36.981 INFO destroying link mgtriou_ox_sn_sim1
4812026-02-18T03:45:37.014ZFeb 18 03:45:36.982 INFO destroying link mgtriou_cr2_vn_vnic0
4822026-02-18T03:45:37.014ZFeb 18 03:45:36.984 INFO destroying link mgtriou_cr2_vn_sim0
4832026-02-18T03:45:37.014ZFeb 18 03:45:36.985 INFO destroying external links
4842026-02-18T03:45:37.014ZFeb 18 03:45:36.985 INFO destroying external link mgtriou_ox_vn_vnic2
4852026-02-18T03:45:37.014ZFeb 18 03:45:36.986 INFO destroying external link mgtriou_cr1_vn_vnic1
4862026-02-18T03:45:37.014ZFeb 18 03:45:36.987 INFO destroying external link mgtriou_cr2_vn_vnic1
4872026-02-18T03:45:37.014ZFeb 18 03:45:36.989 INFO destroying images
4882026-02-18T03:45:37.475ZFeb 18 03:45:37.464 INFO destroying workspace at .falcon
4892026-02-18T03:45:37.500Zprocess exited: duration 663901 ms, exit code 0
 
4902026-02-18T03:45:37.550Zfound 0 output files