01KXFGS9CA059NPXST6XZEVD1B: falcon

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

Buildomat Job: 01KXFGSQHG7RG0JEHRYKJ0A1AW

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-07-14T05:28:42.103Zjob dependencies complete; ready to run (waiting for 13 m 35 s)
22026-07-14T05:32:05.717Zjob assigned to worker 01KXFHJMNX2V107ZE7E2VJANGR [factory edgar, gimlet/BRM42220060/886] (queued for 3 m 23 s)
32026-07-14T05:32:06.269Zdownloading input: /input/build-dhcp-server/work/dhcp-server
42026-07-14T05:32:08.569Zdownloaded input: /input/build-dhcp-server/work/dhcp-server
52026-07-14T05:32:08.594Zdownloading input: /input/build/work/debug/ddmadm
62026-07-14T05:32:31.679Zdownloaded input: /input/build/work/debug/ddmadm
72026-07-14T05:32:31.679Zdownloading input: /input/build/work/debug/ddmd
82026-07-14T05:32:57.819Zdownloaded input: /input/build/work/debug/ddmd
92026-07-14T05:32:57.819Zdownloading input: /input/build/work/debug/mgadm
102026-07-14T05:33:24.647Zdownloaded input: /input/build/work/debug/mgadm
112026-07-14T05:33:24.671Zdownloading input: /input/build/work/debug/mgd
122026-07-14T05:33:55.009Zdownloaded input: /input/build/work/debug/mgd
132026-07-14T05:33:55.034Zdownloading input: /input/build/work/release/ddmadm
142026-07-14T05:33:57.046Zdownloaded input: /input/build/work/release/ddmadm
152026-07-14T05:33:57.070Zdownloading input: /input/build/work/release/ddmd
162026-07-14T05:33:59.732Zdownloaded input: /input/build/work/release/ddmd
172026-07-14T05:33:59.732Zdownloading input: /input/build/work/release/falcon-lab
182026-07-14T05:34:01.605Zdownloaded input: /input/build/work/release/falcon-lab
192026-07-14T05:34:01.629Zdownloading input: /input/build/work/release/mgadm
202026-07-14T05:34:04.267Zdownloaded input: /input/build/work/release/mgadm
212026-07-14T05:34:04.268Zdownloading input: /input/build/work/release/mgd
222026-07-14T05:34:07.713Zdownloaded input: /input/build/work/release/mgd
 
232026-07-14T05:34:07.713Zstarting task 0: "setup"
242026-07-14T05:34:07.737Z++ uname -s
252026-07-14T05:34:07.737Z+ kern=SunOS
262026-07-14T05:34:07.737Z+ build_user=build
272026-07-14T05:34:07.737Z+ build_uid=12345
282026-07-14T05:34:07.737Z+ work_dir=/work
292026-07-14T05:34:07.737Z+ input_dir=/input
302026-07-14T05:34:07.737Z+ [[ 0 == 12345 ]]
312026-07-14T05:34:07.737Z+ case "$kern" in
322026-07-14T05:34:07.737Z+ groupadd -g 12345 build
332026-07-14T05:34:07.761Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
342026-07-14T05:34:09.748Z+ zfs create -o mountpoint=/work rpool/work
352026-07-14T05:34:10.700Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
362026-07-14T05:34:10.725Z+ home_fs=
372026-07-14T05:34:10.725Z+ [[ '' == autofs ]]
382026-07-14T05:34:10.725Z+ mkdir -p /home/build
392026-07-14T05:34:10.725Z+ chown build:build /home/build /work
402026-07-14T05:34:11.713Z+ chmod 0700 /home/build /work
412026-07-14T05:34:11.738Zprocess exited: duration 4004 ms, exit code 0
 
422026-07-14T05:34:11.787Zstarting task 1: "authentication"
432026-07-14T05:34:11.889Zprocess exited: duration 101 ms, exit code 0
 
442026-07-14T05:34:11.939Zstarting task 2: "build"
452026-07-14T05:34:11.964Z+ set -e
462026-07-14T05:34:11.964Z+ set -o pipefail
472026-07-14T05:34:11.964Z+ banner zpool
482026-07-14T05:34:11.964Z
492026-07-14T05:34:11.964Z ###### ##### #### #### #
502026-07-14T05:34:11.964Z # # # # # # # #
512026-07-14T05:34:11.964Z # # # # # # # #
522026-07-14T05:34:11.964Z # ##### # # # # #
532026-07-14T05:34:11.964Z # # # # # # #
542026-07-14T05:34:11.964Z ###### # #### #### ######
552026-07-14T05:34:11.964Z
562026-07-14T05:34:11.964Z++ pfexec diskinfo -pH
572026-07-14T05:34:11.964Z++ sort -k8 -n -r
582026-07-14T05:34:11.989Z++ head -1
592026-07-14T05:34:11.989Z++ awk '{print $2}'
602026-07-14T05:34:12.024Z+ DISK=c9t0014EE81000BC57Cd0
612026-07-14T05:34:12.024Z+ export DISK
622026-07-14T05:34:12.050Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
632026-07-14T05:34:12.357Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
642026-07-14T05:34:12.384Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
652026-07-14T05:34:12.450Z+ trim_response=true
662026-07-14T05:34:12.475Z+ [[ true =~ true ]]
672026-07-14T05:34:12.475Z+ pfexec zpool trim cpool
682026-07-14T05:34:12.475Z+ true
692026-07-14T05:34:12.475Z++ zpool status -t cpool
702026-07-14T05:34:12.500Z+ trim_status=' pool: cpool
712026-07-14T05:34:12.500Z state: ONLINE
722026-07-14T05:34:12.500Z scan: none requested
732026-07-14T05:34:12.500Zconfig:
742026-07-14T05:34:12.500Z
752026-07-14T05:34:12.500Z NAME STATE READ WRITE CKSUM
762026-07-14T05:34:12.500Z cpool ONLINE 0 0 0
772026-07-14T05:34:12.500Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at July 14, 2026 at 05:34:12 AM UTC)
782026-07-14T05:34:12.500Z
792026-07-14T05:34:12.500Zerrors: No known data errors'
802026-07-14T05:34:12.500Z+ [[ pool: cpool
812026-07-14T05:34:12.500Z state: ONLINE
822026-07-14T05:34:12.500Z scan: none requested
832026-07-14T05:34:12.500Zconfig:
842026-07-14T05:34:12.501Z
852026-07-14T05:34:12.501Z NAME STATE READ WRITE CKSUM
862026-07-14T05:34:12.501Z cpool ONLINE 0 0 0
872026-07-14T05:34:12.501Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at July 14, 2026 at 05:34:12 AM UTC)
882026-07-14T05:34:12.501Z
892026-07-14T05:34:12.501Zerrors: No known data errors =~ 100% ]]
902026-07-14T05:34:12.501Z+ sleep 10
912026-07-14T05:34:22.483Z+ true
922026-07-14T05:34:22.508Z++ zpool status -t cpool
932026-07-14T05:34:22.508Z+ trim_status=' pool: cpool
942026-07-14T05:34:22.508Z state: ONLINE
952026-07-14T05:34:22.508Z scan: none requested
962026-07-14T05:34:22.508Zconfig:
972026-07-14T05:34:22.508Z
982026-07-14T05:34:22.508Z NAME STATE READ WRITE CKSUM
992026-07-14T05:34:22.508Z cpool ONLINE 0 0 0
1002026-07-14T05:34:22.508Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (69% trimmed, started at July 14, 2026 at 05:34:12 AM UTC)
1012026-07-14T05:34:22.508Z
1022026-07-14T05:34:22.508Zerrors: No known data errors'
1032026-07-14T05:34:22.508Z+ [[ pool: cpool
1042026-07-14T05:34:22.508Z state: ONLINE
1052026-07-14T05:34:22.508Z scan: none requested
1062026-07-14T05:34:22.508Zconfig:
1072026-07-14T05:34:22.508Z
1082026-07-14T05:34:22.508Z NAME STATE READ WRITE CKSUM
1092026-07-14T05:34:22.508Z cpool ONLINE 0 0 0
1102026-07-14T05:34:22.508Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (69% trimmed, started at July 14, 2026 at 05:34:12 AM UTC)
1112026-07-14T05:34:22.508Z
1122026-07-14T05:34:22.508Zerrors: No known data errors =~ 100% ]]
1132026-07-14T05:34:22.508Z+ sleep 10
1142026-07-14T05:34:32.497Z+ true
1152026-07-14T05:34:32.521Z++ zpool status -t cpool
1162026-07-14T05:34:32.521Z+ trim_status=' pool: cpool
1172026-07-14T05:34:32.521Z state: ONLINE
1182026-07-14T05:34:32.521Z scan: none requested
1192026-07-14T05:34:32.521Zconfig:
1202026-07-14T05:34:32.521Z
1212026-07-14T05:34:32.521Z NAME STATE READ WRITE CKSUM
1222026-07-14T05:34:32.521Z cpool ONLINE 0 0 0
1232026-07-14T05:34:32.521Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at July 14, 2026 at 05:34:27 AM UTC)
1242026-07-14T05:34:32.521Z
1252026-07-14T05:34:32.521Zerrors: No known data errors'
1262026-07-14T05:34:32.521Z+ [[ pool: cpool
1272026-07-14T05:34:32.521Z state: ONLINE
1282026-07-14T05:34:32.521Z scan: none requested
1292026-07-14T05:34:32.521Zconfig:
1302026-07-14T05:34:32.522Z
1312026-07-14T05:34:32.522Z NAME STATE READ WRITE CKSUM
1322026-07-14T05:34:32.522Z cpool ONLINE 0 0 0
1332026-07-14T05:34:32.522Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at July 14, 2026 at 05:34:27 AM UTC)
1342026-07-14T05:34:32.522Z
1352026-07-14T05:34:32.522Zerrors: No known data errors =~ 100% ]]
1362026-07-14T05:34:32.522Z+ break
1372026-07-14T05:34:32.522Z+ pfexec chown 12345 /ci
1382026-07-14T05:34:32.522Z+ cd /ci
1392026-07-14T05:34:32.522Z+ export FALCON_DATASET=cpool/falcon
1402026-07-14T05:34:32.522Z+ FALCON_DATASET=cpool/falcon
1412026-07-14T05:34:32.522Z+ banner setup
1422026-07-14T05:34:32.522Z
1432026-07-14T05:34:32.522Z #### ###### ##### # # #####
1442026-07-14T05:34:32.522Z # # # # # # #
1452026-07-14T05:34:32.522Z #### ##### # # # # #
1462026-07-14T05:34:32.522Z # # # # # #####
1472026-07-14T05:34:32.522Z # # # # # # #
1482026-07-14T05:34:32.522Z #### ###### # #### #
1492026-07-14T05:34:32.522Z
1502026-07-14T05:34:32.522Z+ cp /input/build-dhcp-server/work/dhcp-server .
1512026-07-14T05:34:32.832Z+ cp /input/build/work/release/falcon-lab .
1522026-07-14T05:34:32.869Z+ cp /input/build/work/release/mgd .
1532026-07-14T05:34:32.939Z+ cp /input/build/work/release/ddmd .
1542026-07-14T05:34:33.001Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1552026-07-14T05:34:33.026Z+ mkdir -p cargo-bay
1562026-07-14T05:34:33.026Z+ mv mgd cargo-bay/
1572026-07-14T05:34:33.026Z+ mv ddmd cargo-bay/
1582026-07-14T05:34:33.026Z+ curl -sSfL --retry 10 --retry-all-errors -o cargo-bay/falcon-juniper-license.key http://catacomb.eng.oxide.computer:12346/falcon/jl
1592026-07-14T05:34:33.055Z+ chmod 0600 cargo-bay/falcon-juniper-license.key
1602026-07-14T05:34:33.079Z+ export EXT_INTERFACE=igb0
1612026-07-14T05:34:33.079Z+ EXT_INTERFACE=igb0
1622026-07-14T05:34:33.079Z++ bmat address ls -f extra -Ho first
1632026-07-14T05:34:33.103Z+ first=10.151.7.164
1642026-07-14T05:34:33.103Z++ bmat address ls -f extra -Ho last
1652026-07-14T05:34:33.127Z+ last=10.151.7.227
1662026-07-14T05:34:33.127Z++ bmat address ls -f extra -Ho gateway
1672026-07-14T05:34:33.152Z+ gw=10.151.7.1
1682026-07-14T05:34:33.152Z++ ipadm show-addr igb0/dhcp -po ADDR
1692026-07-14T05:34:33.152Z++ sed 's#/.*##g'
1702026-07-14T05:34:33.152Z+ server=10.151.7.100
1712026-07-14T05:34:33.152Z+ run_test mgd-unnumbered
1722026-07-14T05:34:33.152Z+ + local test_name=mgd-unnumbered
1732026-07-14T05:34:33.152Zpfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1742026-07-14T05:34:33.152Z+ local status=0
1752026-07-14T05:34:33.152Z+ clear_falcon_workspace_files
1762026-07-14T05:34:33.152Z+ [[ -d .falcon ]]
1772026-07-14T05:34:33.152Z+ RUST_LOG=debug
1782026-07-14T05:34:33.152Z+ pfexec ./falcon-lab run --no-cleanup mgd-unnumbered
1792026-07-14T05:34:33.176ZJul 14 05:34:33.156 DEBG using default route interface igb0
1802026-07-14T05:34:33.176ZJul 14 05:34:33.157 DEBG using default route interface igb0
1812026-07-14T05:34:33.176ZJul 14 05:34:33.157 INFO launching runner: deployment(mgduou) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1822026-07-14T05:34:33.176ZJul 14 05:34:33.157 INFO starting preflight for deployment mgduou
1832026-07-14T05:34:33.176ZJul 14 05:34:33.157 INFO propolis-server binary not found
1842026-07-14T05:34:33.176ZJul 14 05:34:33.157 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1852026-07-14T05:34:36.961ZJul 14 05:34:36.948 INFO ovmf fd not found
1862026-07-14T05:34:36.961ZJul 14 05:34:36.948 INFO downloading ovmf
1872026-07-14T05:34:37.297ZJul 14 05:34:37.284 INFO base image for helios-3.0 does not exist, attempting to install
1882026-07-14T05:34:37.298ZJul 14 05:34:37.284 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-3.0_0.raw.xz
1892026-07-14T05:35:05.635ZJul 14 05:35:05.621 INFO extracting image to /tmp/helios-3.0_0.raw
1902026-07-14T05:35:28.008ZJul 14 05:35:27.994 INFO creating zvol cpool/falcon/img/helios-3.0 of size 4508880896
1912026-07-14T05:35:28.064ZJul 14 05:35:28.042 INFO copying image data to zvol
1922026-07-14T05:35:42.325ZJul 14 05:35:38.370 INFO creating zfs snapshot cpool/falcon/img/helios-3.0@base
1932026-07-14T05:35:42.392ZJul 14 05:35:41.766 INFO creating links
1942026-07-14T05:35:42.392ZJul 14 05:35:41.766 DEBG destroying link mgduou_ox1_vn_vnic0
1952026-07-14T05:35:42.392ZJul 14 05:35:41.766 DEBG destroying link mgduou_ox1_vn_sim0
1962026-07-14T05:35:42.392ZJul 14 05:35:41.766 INFO creating simnet link 'mgduou_ox1_vn_sim0'
1972026-07-14T05:35:42.392ZJul 14 05:35:41.768 INFO creating vnic link 'mgduou_ox1_vn_vnic0'
1982026-07-14T05:35:42.392ZJul 14 05:35:41.776 DEBG link pair created
1992026-07-14T05:35:42.392ZJul 14 05:35:41.776 DEBG destroying link mgduou_ox2_vn_vnic0
2002026-07-14T05:35:42.392ZJul 14 05:35:41.776 DEBG destroying link mgduou_ox2_vn_sim0
2012026-07-14T05:35:42.392ZJul 14 05:35:41.776 INFO creating simnet link 'mgduou_ox2_vn_sim0'
2022026-07-14T05:35:42.392ZJul 14 05:35:41.778 INFO creating vnic link 'mgduou_ox2_vn_vnic0'
2032026-07-14T05:35:42.392ZJul 14 05:35:41.784 DEBG link pair created
2042026-07-14T05:35:42.392ZJul 14 05:35:41.784 INFO creating external links
2052026-07-14T05:35:42.392ZJul 14 05:35:41.785 DEBG destroying external link mgduou_ox1_vn_vnic1
2062026-07-14T05:35:42.392ZJul 14 05:35:41.785 INFO creating external link mgduou_ox1_vn_vnic1
2072026-07-14T05:35:42.392ZJul 14 05:35:41.787 DEBG external link mgduou_ox1_vn_vnic1@igb0 created
2082026-07-14T05:35:42.392ZJul 14 05:35:41.787 DEBG destroying external link mgduou_ox2_vn_vnic1
2092026-07-14T05:35:42.392ZJul 14 05:35:41.787 INFO creating external link mgduou_ox2_vn_vnic1
2102026-07-14T05:35:42.392ZJul 14 05:35:41.788 DEBG external link mgduou_ox2_vn_vnic1@igb0 created
2112026-07-14T05:35:42.392ZJul 14 05:35:41.788 INFO creating nodes
2122026-07-14T05:35:42.393ZJul 14 05:35:41.788 INFO ox1: launching node
2132026-07-14T05:35:42.393ZJul 14 05:35:41.791 INFO ox2: launching node
2142026-07-14T05:35:42.393ZJul 14 05:35:41.817 INFO launched instance ox1 with pid 863 on port 49370
2152026-07-14T05:35:42.393ZJul 14 05:35:41.817 INFO ox1: instance ensure
2162026-07-14T05:35:42.393ZJul 14 05:35:41.818 INFO launched instance ox2 with pid 864 on port 43579
2172026-07-14T05:35:42.393ZJul 14 05:35:41.818 INFO ox2: instance ensure
2182026-07-14T05:35:43.689ZJul 14 05:35:43.676 INFO ox2: instance ensure completed after 0 retries
2192026-07-14T05:35:43.689ZJul 14 05:35:43.676 INFO ox2: instance run
2202026-07-14T05:35:43.713ZJul 14 05:35:43.677 DEBG [sc] ox2: starting
2212026-07-14T05:35:43.714ZJul 14 05:35:43.677 DEBG [sc] ox2: connecting to [::1]:43579
2222026-07-14T05:35:43.714ZJul 14 05:35:43.678 INFO ox1: instance ensure completed after 0 retries
2232026-07-14T05:35:43.714ZJul 14 05:35:43.678 INFO ox1: instance run
2242026-07-14T05:35:43.714ZJul 14 05:35:43.678 DEBG [sc] ox1: starting
2252026-07-14T05:35:43.714ZJul 14 05:35:43.678 DEBG [sc] ox1: connecting to [::1]:49370
2262026-07-14T05:35:43.714ZJul 14 05:35:43.678 DEBG [sc] ox2 waiting for prompt
2272026-07-14T05:35:43.714ZJul 14 05:35:43.679 DEBG [sc] ox1 waiting for prompt
2282026-07-14T05:36:02.059ZJul 14 05:36:02.046 DEBG [sc] ox2: logging in
2292026-07-14T05:36:02.212ZJul 14 05:36:02.199 DEBG [sc] ox1: logging in
2302026-07-14T05:36:02.261ZJul 14 05:36:02.247 INFO ox2: mounting /opt/cargo-bay
2312026-07-14T05:36:02.261ZJul 14 05:36:02.248 DEBG [sc] ox2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2322026-07-14T05:36:02.438ZJul 14 05:36:02.424 INFO ox1: mounting /opt/cargo-bay
2332026-07-14T05:36:02.438ZJul 14 05:36:02.424 DEBG [sc] ox1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-07-14T05:36:03.419ZJul 14 05:36:03.406 DEBG [sc] ox2: executing command `cd`
2352026-07-14T05:36:03.444ZJul 14 05:36:03.416 INFO ox2: finished mounting /opt/cargo-bay
2362026-07-14T05:36:03.444ZJul 14 05:36:03.416 DEBG [sc] ox2: executing command `hostname ox2`
2372026-07-14T05:36:03.444ZJul 14 05:36:03.427 DEBG [sc] ox2: executing command `echo 'ox2' > /etc/nodename`
2382026-07-14T05:36:03.468ZJul 14 05:36:03.438 DEBG [sc] ox2: executing command `echo '::1 ox2.local ox2' >> /etc/hosts`
2392026-07-14T05:36:03.468ZJul 14 05:36:03.449 DEBG [sc] ox2: executing command `echo '127.0.0.1 ox2.local ox2' >> /etc/hosts`
2402026-07-14T05:36:03.493ZJul 14 05:36:03.460 INFO ox2: logging out
2412026-07-14T05:36:03.518ZJul 14 05:36:03.504 DEBG [sc] ox1: executing command `cd`
2422026-07-14T05:36:03.542ZJul 14 05:36:03.515 INFO ox1: finished mounting /opt/cargo-bay
2432026-07-14T05:36:03.542ZJul 14 05:36:03.515 DEBG [sc] ox1: executing command `hostname ox1`
2442026-07-14T05:36:03.543ZJul 14 05:36:03.526 DEBG [sc] ox1: executing command `echo 'ox1' > /etc/nodename`
2452026-07-14T05:36:03.543ZJul 14 05:36:03.527 INFO ox2: logged out
2462026-07-14T05:36:03.567ZJul 14 05:36:03.538 DEBG [sc] ox1: executing command `echo '::1 ox1.local ox1' >> /etc/hosts`
2472026-07-14T05:36:03.567ZJul 14 05:36:03.549 DEBG [sc] ox1: executing command `echo '127.0.0.1 ox1.local ox1' >> /etc/hosts`
2482026-07-14T05:36:03.592ZJul 14 05:36:03.560 INFO ox1: logging out
2492026-07-14T05:36:03.693ZJul 14 05:36:03.680 INFO ox1: logged out
2502026-07-14T05:36:03.718ZJul 14 05:36:03.680 DEBG [sc] ox1: starting
2512026-07-14T05:36:03.718ZJul 14 05:36:03.680 DEBG [sc] ox1: connecting to [::1]:49370
2522026-07-14T05:36:03.718ZJul 14 05:36:03.681 DEBG [sc] ox2: starting
2532026-07-14T05:36:03.718ZJul 14 05:36:03.681 DEBG [sc] ox2: connecting to [::1]:43579
2542026-07-14T05:36:03.718ZJul 14 05:36:03.681 DEBG [sc] ox1 waiting for prompt
2552026-07-14T05:36:03.719ZJul 14 05:36:03.681 DEBG [sc] ox2 waiting for prompt
2562026-07-14T05:36:03.719ZJul 14 05:36:03.690 DEBG [sc] ox1: logging in
2572026-07-14T05:36:03.719ZJul 14 05:36:03.692 DEBG [sc] ox2: logging in
2582026-07-14T05:36:03.792ZJul 14 05:36:03.779 DEBG [sc] ox1: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2592026-07-14T05:36:03.819ZJul 14 05:36:03.780 DEBG [sc] ox2: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2602026-07-14T05:36:07.828ZJul 14 05:36:07.811 DEBG [sc] ox2: starting
2612026-07-14T05:36:07.828ZJul 14 05:36:07.811 DEBG [sc] ox2: connecting to [::1]:43579
2622026-07-14T05:36:07.857ZJul 14 05:36:07.812 DEBG [sc] ox2 waiting for prompt
2632026-07-14T05:36:07.857ZJul 14 05:36:07.822 DEBG [sc] ox2: logging in
2642026-07-14T05:36:07.956ZJul 14 05:36:07.943 DEBG [sc] ox2: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2652026-07-14T05:36:08.118ZJul 14 05:36:08.105 DEBG [sc] ox2: starting
2662026-07-14T05:36:08.119ZJul 14 05:36:08.105 DEBG [sc] ox2: connecting to [::1]:43579
2672026-07-14T05:36:08.154ZJul 14 05:36:08.106 DEBG [sc] ox2 waiting for prompt
2682026-07-14T05:36:08.155ZJul 14 05:36:08.126 DEBG [sc] ox2: logging in
2692026-07-14T05:36:08.250ZJul 14 05:36:08.236 DEBG [sc] ox2: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2702026-07-14T05:36:09.329ZJul 14 05:36:09.316 DEBG [sc] ox1: starting
2712026-07-14T05:36:09.329ZJul 14 05:36:09.316 DEBG [sc] ox1: connecting to [::1]:49370
2722026-07-14T05:36:09.354ZJul 14 05:36:09.317 DEBG [sc] ox1 waiting for prompt
2732026-07-14T05:36:09.354ZJul 14 05:36:09.327 DEBG [sc] ox1: logging in
2742026-07-14T05:36:09.428ZJul 14 05:36:09.415 DEBG [sc] ox1: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2752026-07-14T05:36:09.516ZJul 14 05:36:09.503 DEBG [sc] ox1: starting
2762026-07-14T05:36:09.516ZJul 14 05:36:09.503 DEBG [sc] ox1: connecting to [::1]:49370
2772026-07-14T05:36:09.541ZJul 14 05:36:09.504 DEBG [sc] ox1 waiting for prompt
2782026-07-14T05:36:09.541ZJul 14 05:36:09.514 DEBG [sc] ox1: logging in
2792026-07-14T05:36:09.615ZJul 14 05:36:09.602 DEBG [sc] ox1: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2802026-07-14T05:36:09.686ZJul 14 05:36:09.672 DEBG [sc] ox1: starting
2812026-07-14T05:36:09.686ZJul 14 05:36:09.673 DEBG [sc] ox1: connecting to [::1]:49370
2822026-07-14T05:36:09.710ZJul 14 05:36:09.673 DEBG [sc] ox1 waiting for prompt
2832026-07-14T05:36:09.710ZJul 14 05:36:09.679 DEBG [sc] ox1: logging in
2842026-07-14T05:36:09.780ZJul 14 05:36:09.767 DEBG [sc] ox1: executing command `ipadm create-addr -T addrconf vioif0/ll`
2852026-07-14T05:36:09.880ZJul 14 05:36:09.867 DEBG [sc] ox1: starting
2862026-07-14T05:36:09.880ZJul 14 05:36:09.867 DEBG [sc] ox1: connecting to [::1]:49370
2872026-07-14T05:36:09.905ZJul 14 05:36:09.867 DEBG [sc] ox1 waiting for prompt
2882026-07-14T05:36:09.905ZJul 14 05:36:09.877 DEBG [sc] ox1: logging in
2892026-07-14T05:36:09.980ZJul 14 05:36:09.966 DEBG [sc] ox1: executing command `ipadm show-addr vioif0/ll -p -o addr`
2902026-07-14T05:36:10.056ZJul 14 05:36:10.043 DEBG [sc] ox2: starting
2912026-07-14T05:36:10.057ZJul 14 05:36:10.043 DEBG [sc] ox2: connecting to [::1]:43579
2922026-07-14T05:36:10.081ZJul 14 05:36:10.044 DEBG [sc] ox2 waiting for prompt
2932026-07-14T05:36:10.081ZJul 14 05:36:10.055 DEBG [sc] ox2: logging in
2942026-07-14T05:36:10.167ZJul 14 05:36:10.154 DEBG [sc] ox2: executing command `ipadm create-addr -T addrconf vioif0/ll`
2952026-07-14T05:36:10.266ZJul 14 05:36:10.253 DEBG [sc] ox2: starting
2962026-07-14T05:36:10.266ZJul 14 05:36:10.253 DEBG [sc] ox2: connecting to [::1]:43579
2972026-07-14T05:36:10.291ZJul 14 05:36:10.253 DEBG [sc] ox2 waiting for prompt
2982026-07-14T05:36:10.291ZJul 14 05:36:10.264 DEBG [sc] ox2: logging in
2992026-07-14T05:36:10.366ZJul 14 05:36:10.353 DEBG [sc] ox2: executing command `ipadm show-addr vioif0/ll -p -o addr`
3002026-07-14T05:36:10.432ZJul 14 05:36:10.418 DEBG [sc] ox1: starting
3012026-07-14T05:36:10.432ZJul 14 05:36:10.418 DEBG [sc] ox1: connecting to [::1]:49370
3022026-07-14T05:36:10.456ZJul 14 05:36:10.419 DEBG [sc] ox2: starting
3032026-07-14T05:36:10.457ZJul 14 05:36:10.419 DEBG [sc] ox2: connecting to [::1]:43579
3042026-07-14T05:36:10.457ZJul 14 05:36:10.419 DEBG [sc] ox1 waiting for prompt
3052026-07-14T05:36:10.457ZJul 14 05:36:10.419 DEBG [sc] ox2 waiting for prompt
3062026-07-14T05:36:10.457ZJul 14 05:36:10.430 DEBG [sc] ox2: logging in
3072026-07-14T05:36:10.457ZJul 14 05:36:10.430 DEBG [sc] ox1: logging in
3082026-07-14T05:36:10.532ZJul 14 05:36:10.518 DEBG [sc] ox2: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3092026-07-14T05:36:10.556ZJul 14 05:36:10.519 DEBG [sc] ox1: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3102026-07-14T05:36:10.610ZJul 14 05:36:10.597 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.7.165:4676/bgp/config/routers)
3112026-07-14T05:36:10.611ZJul 14 05:36:10.597 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.7.164:4676/bgp/config/routers)
3122026-07-14T05:36:11.613ZJul 14 05:36:11.600 INFO adding BGP routers to both mgd nodes
3132026-07-14T05:36:11.639ZJul 14 05:36:11.603 INFO adding unnumbered BGP neighbors to both mgd nodes
3142026-07-14T05:36:11.639Zmgd1 bgp ox2 established: iteration 1/20: expected Some(Established), got Some(Connect)
3152026-07-14T05:36:12.628Zmgd1 bgp ox2 established: iteration 2/20: expected Some(Established), got Some(Connect)
3162026-07-14T05:36:13.630Zmgd1 bgp ox2 established: iteration 3/20: expected Some(Established), got Some(Connect)
3172026-07-14T05:36:14.632Zmgd1 bgp ox2 established: iteration 4/20: expected Some(Established), got Some(Connect)
3182026-07-14T05:36:15.635Zmgd1 bgp ox2 established: iteration 5/20: expected Some(Established), got Some(Connect)
3192026-07-14T05:36:16.638ZJul 14 05:36:16.624 INFO mgd-to-mgd bgp unnumbered test passed 🎉
3202026-07-14T05:36:16.663Z+ (( status != 0 ))
3212026-07-14T05:36:16.663Z+ pfexec ./falcon-lab cleanup mgd-unnumbered
3222026-07-14T05:36:16.687ZJul 14 05:36:16.653 INFO destroying runner for deployment mgduou
3232026-07-14T05:36:16.688ZJul 14 05:36:16.653 INFO destroying deployment mgduou
3242026-07-14T05:36:16.688ZJul 14 05:36:16.653 INFO destroying nodes
3252026-07-14T05:36:16.688ZJul 14 05:36:16.661 INFO destroying links
3262026-07-14T05:36:16.688ZJul 14 05:36:16.662 INFO destroying link mgduou_ox1_vn_vnic0
3272026-07-14T05:36:17.681ZJul 14 05:36:17.667 INFO destroying link mgduou_ox1_vn_sim0
3282026-07-14T05:36:17.706ZJul 14 05:36:17.667 INFO destroying link mgduou_ox2_vn_vnic0
3292026-07-14T05:36:17.706ZJul 14 05:36:17.668 INFO destroying link mgduou_ox2_vn_sim0
3302026-07-14T05:36:17.706ZJul 14 05:36:17.669 INFO destroying external links
3312026-07-14T05:36:17.706ZJul 14 05:36:17.669 INFO destroying external link mgduou_ox1_vn_vnic1
3322026-07-14T05:36:17.706ZJul 14 05:36:17.670 INFO destroying external link mgduou_ox2_vn_vnic1
3332026-07-14T05:36:17.706ZJul 14 05:36:17.672 INFO destroying images
3342026-07-14T05:36:17.833ZJul 14 05:36:17.819 INFO destroying workspace at .falcon
3352026-07-14T05:36:17.858Z+ return 0
3362026-07-14T05:36:17.858Z+ run_test quartet-unnumbered
3372026-07-14T05:36:17.858Z+ local test_name=quartet-unnumbered
3382026-07-14T05:36:17.858Z+ local status=0
3392026-07-14T05:36:17.858Z+ clear_falcon_workspace_files
3402026-07-14T05:36:17.858Z+ [[ -d .falcon ]]
3412026-07-14T05:36:17.858Z+ find .falcon -maxdepth 1 -type f -exec rm -f '{}' +
3422026-07-14T05:36:17.858Z+ RUST_LOG=debug
3432026-07-14T05:36:17.858Z+ pfexec ./falcon-lab run --no-cleanup quartet-unnumbered
3442026-07-14T05:36:17.883ZJul 14 05:36:17.851 DEBG using default route interface igb0
3452026-07-14T05:36:17.883ZJul 14 05:36:17.851 DEBG using default route interface igb0
3462026-07-14T05:36:17.884ZJul 14 05:36:17.851 DEBG using default route interface igb0
3472026-07-14T05:36:17.884ZJul 14 05:36:17.851 DEBG using default route interface igb0
3482026-07-14T05:36:17.884ZJul 14 05:36:17.851 INFO mgquartetu: launching quartet topology
3492026-07-14T05:36:17.884ZJul 14 05:36:17.851 INFO launching runner: deployment(mgquartetu) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
3502026-07-14T05:36:17.884ZJul 14 05:36:17.851 INFO starting preflight for deployment mgquartetu
3512026-07-14T05:36:18.351ZJul 14 05:36:18.333 INFO base image for debian-13.2 does not exist, attempting to install
3522026-07-14T05:36:18.351ZJul 14 05:36:18.333 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
3532026-07-14T05:36:38.272ZJul 14 05:36:38.258 INFO extracting image to /tmp/debian-13.2_0.raw
3542026-07-14T05:36:51.989ZJul 14 05:36:51.975 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
3552026-07-14T05:36:52.013ZJul 14 05:36:51.999 INFO copying image data to zvol
3562026-07-14T05:36:58.764ZJul 14 05:36:58.750 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
3572026-07-14T05:37:02.607ZJul 14 05:37:02.593 INFO base image for eos-4.35 does not exist, attempting to install
3582026-07-14T05:37:02.607ZJul 14 05:37:02.593 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
3592026-07-14T05:37:44.847ZJul 14 05:37:44.834 INFO extracting image to /tmp/eos-4.35_0.raw
3602026-07-14T05:38:49.690ZJul 14 05:38:49.678 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
3612026-07-14T05:38:49.717ZJul 14 05:38:49.705 INFO copying image data to zvol
3622026-07-14T05:39:38.984ZJul 14 05:39:38.972 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
3632026-07-14T05:39:42.458ZJul 14 05:39:42.446 INFO base image for junos-23.2 does not exist, attempting to install
3642026-07-14T05:39:42.459ZJul 14 05:39:42.446 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/junos-23.2_0.raw.xz
3652026-07-14T05:40:16.884ZJul 14 05:40:16.872 INFO extracting image to /tmp/junos-23.2_0.raw
3662026-07-14T05:41:16.193ZJul 14 05:41:16.181 INFO creating zvol cpool/falcon/img/junos-23.2 of size 21474840576
3672026-07-14T05:41:16.220ZJul 14 05:41:16.209 INFO copying image data to zvol
3682026-07-14T05:42:04.588ZJul 14 05:42:04.576 INFO creating zfs snapshot cpool/falcon/img/junos-23.2@base
3692026-07-14T05:42:07.902ZJul 14 05:42:07.891 INFO creating links
3702026-07-14T05:42:07.903ZJul 14 05:42:07.891 DEBG destroying link mgquartetu_ox_sn_vnic0
3712026-07-14T05:42:07.903ZJul 14 05:42:07.891 DEBG destroying link mgquartetu_ox_sn_sim0
3722026-07-14T05:42:07.903ZJul 14 05:42:07.891 INFO creating simnet link 'mgquartetu_ox_sn_sim0'
3732026-07-14T05:42:07.927ZJul 14 05:42:07.893 INFO creating vnic link 'mgquartetu_ox_sn_vnic0'
3742026-07-14T05:42:07.927ZJul 14 05:42:07.900 DEBG link pair created
3752026-07-14T05:42:07.927ZJul 14 05:42:07.900 DEBG destroying link mgquartetu_cr1_vn_vnic0
3762026-07-14T05:42:07.927ZJul 14 05:42:07.900 DEBG destroying link mgquartetu_cr1_vn_sim0
3772026-07-14T05:42:07.927ZJul 14 05:42:07.900 INFO creating simnet link 'mgquartetu_cr1_vn_sim0'
3782026-07-14T05:42:07.927ZJul 14 05:42:07.901 INFO creating vnic link 'mgquartetu_cr1_vn_vnic0'
3792026-07-14T05:42:07.927ZJul 14 05:42:07.908 DEBG link pair created
3802026-07-14T05:42:07.927ZJul 14 05:42:07.908 DEBG destroying link mgquartetu_ox_sn_vnic1
3812026-07-14T05:42:07.927ZJul 14 05:42:07.908 DEBG destroying link mgquartetu_ox_sn_sim1
3822026-07-14T05:42:07.927ZJul 14 05:42:07.908 INFO creating simnet link 'mgquartetu_ox_sn_sim1'
3832026-07-14T05:42:07.927ZJul 14 05:42:07.909 INFO creating vnic link 'mgquartetu_ox_sn_vnic1'
3842026-07-14T05:42:07.952ZJul 14 05:42:07.916 DEBG link pair created
3852026-07-14T05:42:07.952ZJul 14 05:42:07.916 DEBG destroying link mgquartetu_cr2_vn_vnic0
3862026-07-14T05:42:07.952ZJul 14 05:42:07.916 DEBG destroying link mgquartetu_cr2_vn_sim0
3872026-07-14T05:42:07.952ZJul 14 05:42:07.916 INFO creating simnet link 'mgquartetu_cr2_vn_sim0'
3882026-07-14T05:42:07.952ZJul 14 05:42:07.918 INFO creating vnic link 'mgquartetu_cr2_vn_vnic0'
3892026-07-14T05:42:07.952ZJul 14 05:42:07.924 DEBG link pair created
3902026-07-14T05:42:07.952ZJul 14 05:42:07.924 DEBG destroying link mgquartetu_ox_sn_vnic2
3912026-07-14T05:42:07.952ZJul 14 05:42:07.925 DEBG destroying link mgquartetu_ox_sn_sim2
3922026-07-14T05:42:07.952ZJul 14 05:42:07.925 INFO creating simnet link 'mgquartetu_ox_sn_sim2'
3932026-07-14T05:42:07.952ZJul 14 05:42:07.927 INFO creating vnic link 'mgquartetu_ox_sn_vnic2'
3942026-07-14T05:42:07.952ZJul 14 05:42:07.934 DEBG link pair created
3952026-07-14T05:42:07.952ZJul 14 05:42:07.934 DEBG destroying link mgquartetu_cr3_vn_vnic0
3962026-07-14T05:42:07.952ZJul 14 05:42:07.934 DEBG destroying link mgquartetu_cr3_vn_sim0
3972026-07-14T05:42:07.952ZJul 14 05:42:07.934 INFO creating simnet link 'mgquartetu_cr3_vn_sim0'
3982026-07-14T05:42:07.952ZJul 14 05:42:07.935 INFO creating vnic link 'mgquartetu_cr3_vn_vnic0'
3992026-07-14T05:42:07.976ZJul 14 05:42:07.942 DEBG link pair created
4002026-07-14T05:42:07.976ZJul 14 05:42:07.942 INFO creating external links
4012026-07-14T05:42:07.977ZJul 14 05:42:07.942 DEBG destroying external link mgquartetu_ox_vn_vnic3
4022026-07-14T05:42:07.977ZJul 14 05:42:07.942 INFO creating external link mgquartetu_ox_vn_vnic3
4032026-07-14T05:42:07.977ZJul 14 05:42:07.944 DEBG external link mgquartetu_ox_vn_vnic3@igb0 created
4042026-07-14T05:42:07.977ZJul 14 05:42:07.944 DEBG destroying external link mgquartetu_cr1_vn_vnic1
4052026-07-14T05:42:07.977ZJul 14 05:42:07.944 INFO creating external link mgquartetu_cr1_vn_vnic1
4062026-07-14T05:42:07.977ZJul 14 05:42:07.946 DEBG external link mgquartetu_cr1_vn_vnic1@igb0 created
4072026-07-14T05:42:07.977ZJul 14 05:42:07.946 DEBG destroying external link mgquartetu_cr2_vn_vnic1
4082026-07-14T05:42:07.977ZJul 14 05:42:07.946 INFO creating external link mgquartetu_cr2_vn_vnic1
4092026-07-14T05:42:07.977ZJul 14 05:42:07.947 DEBG external link mgquartetu_cr2_vn_vnic1@igb0 created
4102026-07-14T05:42:07.977ZJul 14 05:42:07.947 DEBG destroying external link mgquartetu_cr3_vn_vnic1
4112026-07-14T05:42:07.977ZJul 14 05:42:07.947 INFO creating external link mgquartetu_cr3_vn_vnic1
4122026-07-14T05:42:07.977ZJul 14 05:42:07.949 DEBG external link mgquartetu_cr3_vn_vnic1@igb0 created
4132026-07-14T05:42:07.977ZJul 14 05:42:07.949 INFO creating nodes
4142026-07-14T05:42:07.977ZJul 14 05:42:07.949 INFO ox: launching node
4152026-07-14T05:42:07.977ZJul 14 05:42:07.951 INFO cr1: launching node
4162026-07-14T05:42:07.977ZJul 14 05:42:07.953 INFO cr2: launching node
4172026-07-14T05:42:07.977ZJul 14 05:42:07.956 INFO cr3: launching node
4182026-07-14T05:42:08.002ZJul 14 05:42:07.970 INFO launched instance ox with pid 913 on port 36571
4192026-07-14T05:42:08.002ZJul 14 05:42:07.970 INFO ox: instance ensure
4202026-07-14T05:42:08.002ZJul 14 05:42:07.970 INFO launched instance cr1 with pid 914 on port 38293
4212026-07-14T05:42:08.002ZJul 14 05:42:07.970 INFO cr1: instance ensure
4222026-07-14T05:42:08.002ZJul 14 05:42:07.971 INFO launched instance cr2 with pid 915 on port 35139
4232026-07-14T05:42:08.002ZJul 14 05:42:07.971 INFO cr2: instance ensure
4242026-07-14T05:42:08.002ZJul 14 05:42:07.971 INFO launched instance cr3 with pid 916 on port 45655
4252026-07-14T05:42:08.002ZJul 14 05:42:07.971 INFO cr3: instance ensure
4262026-07-14T05:42:11.095ZJul 14 05:42:11.083 INFO cr2: instance ensure completed after 0 retries
4272026-07-14T05:42:11.095ZJul 14 05:42:11.083 INFO cr2: instance run
4282026-07-14T05:42:11.119ZJul 14 05:42:11.083 DEBG [sc] cr2: starting
4292026-07-14T05:42:11.119ZJul 14 05:42:11.083 DEBG [sc] cr2: connecting to [::1]:35139
4302026-07-14T05:42:11.119ZJul 14 05:42:11.084 DEBG [sc] cr2 waiting for prompt
4312026-07-14T05:42:11.297ZJul 14 05:42:11.285 INFO cr3: instance ensure completed after 0 retries
4322026-07-14T05:42:11.297ZJul 14 05:42:11.285 INFO cr3: instance run
4332026-07-14T05:42:11.340ZJul 14 05:42:11.328 INFO ox: instance ensure completed after 0 retries
4342026-07-14T05:42:11.340ZJul 14 05:42:11.328 INFO ox: instance run
4352026-07-14T05:42:11.364ZJul 14 05:42:11.328 DEBG [sc] ox: starting
4362026-07-14T05:42:11.364ZJul 14 05:42:11.328 DEBG [sc] ox: connecting to [::1]:36571
4372026-07-14T05:42:11.364ZJul 14 05:42:11.329 DEBG [sc] ox waiting for prompt
4382026-07-14T05:42:11.364ZJul 14 05:42:11.346 INFO cr1: instance ensure completed after 0 retries
4392026-07-14T05:42:11.364ZJul 14 05:42:11.346 INFO cr1: instance run
4402026-07-14T05:42:11.364ZJul 14 05:42:11.347 DEBG [sc] cr1: starting
4412026-07-14T05:42:11.364ZJul 14 05:42:11.347 DEBG [sc] cr1: connecting to [::1]:38293
4422026-07-14T05:42:11.364ZJul 14 05:42:11.347 DEBG [sc] cr1 waiting for prompt
4432026-07-14T05:42:28.449ZJul 14 05:42:28.437 DEBG [sc] cr1: logging in
4442026-07-14T05:42:28.854ZJul 14 05:42:28.842 INFO cr1: mounting /opt/cargo-bay
4452026-07-14T05:42:28.854ZJul 14 05:42:28.842 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; mount -t 9p -o ro,msize=65536 /opt/cargo-bay /opt/cargo-bay`
4462026-07-14T05:42:28.931ZJul 14 05:42:28.919 DEBG [sc] cr1: executing command `cd`
4472026-07-14T05:42:28.955ZJul 14 05:42:28.930 INFO cr1: finished mounting /opt/cargo-bay
4482026-07-14T05:42:28.956ZJul 14 05:42:28.930 DEBG [sc] cr1: executing command `hostname cr1`
4492026-07-14T05:42:28.956ZJul 14 05:42:28.941 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
4502026-07-14T05:42:28.980ZJul 14 05:42:28.952 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
4512026-07-14T05:42:28.980ZJul 14 05:42:28.962 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
4522026-07-14T05:42:29.005ZJul 14 05:42:28.974 INFO cr1: logging out
4532026-07-14T05:42:31.047ZJul 14 05:42:31.035 INFO cr1: logged out
4542026-07-14T05:42:31.071ZJul 14 05:42:31.059 DEBG [sc] ox: logging in
4552026-07-14T05:42:31.298ZJul 14 05:42:31.286 INFO ox: mounting /opt/cargo-bay
4562026-07-14T05:42:31.298ZJul 14 05:42:31.286 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
4572026-07-14T05:42:32.417ZJul 14 05:42:32.404 DEBG [sc] ox: executing command `cd`
4582026-07-14T05:42:32.441ZJul 14 05:42:32.415 INFO ox: finished mounting /opt/cargo-bay
4592026-07-14T05:42:32.441ZJul 14 05:42:32.415 DEBG [sc] ox: executing command `hostname ox`
4602026-07-14T05:42:32.441ZJul 14 05:42:32.426 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
4612026-07-14T05:42:32.465ZJul 14 05:42:32.437 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
4622026-07-14T05:42:32.465ZJul 14 05:42:32.449 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
4632026-07-14T05:42:32.489ZJul 14 05:42:32.460 INFO ox: logging out
4642026-07-14T05:42:32.588ZJul 14 05:42:32.575 INFO ox: logged out
4652026-07-14T05:42:32.738ZJul 14 05:42:32.726 DEBG [sc] cr2: logging in
4662026-07-14T05:42:33.177ZJul 14 05:42:33.165 INFO cr2: mounting /opt/cargo-bay
4672026-07-14T05:42:33.177ZJul 14 05:42:33.165 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
4682026-07-14T05:42:33.202ZJul 14 05:42:33.188 DEBG [sc] cr2: executing command `cd`
4692026-07-14T05:42:33.225ZJul 14 05:42:33.199 INFO cr2: finished mounting /opt/cargo-bay
4702026-07-14T05:42:33.225ZJul 14 05:42:33.199 DEBG [sc] cr2: executing command `hostname cr2`
4712026-07-14T05:42:33.226ZJul 14 05:42:33.210 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
4722026-07-14T05:42:33.250ZJul 14 05:42:33.221 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
4732026-07-14T05:42:33.250ZJul 14 05:42:33.231 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
4742026-07-14T05:42:33.275ZJul 14 05:42:33.242 INFO cr2: logging out
4752026-07-14T05:42:36.173ZJul 14 05:42:36.161 INFO cr2: logged out
4762026-07-14T05:42:36.173ZJul 14 05:42:36.162 INFO mgquartetu: quartet topology launch complete
4772026-07-14T05:42:36.198ZJul 14 05:42:36.162 DEBG [sc] ox: starting
4782026-07-14T05:42:36.198ZJul 14 05:42:36.162 DEBG [sc] ox: connecting to [::1]:36571
4792026-07-14T05:42:36.198ZJul 14 05:42:36.162 DEBG [sc] ox waiting for prompt
4802026-07-14T05:42:36.198ZJul 14 05:42:36.173 DEBG [sc] ox: logging in
4812026-07-14T05:42:36.277ZJul 14 05:42:36.262 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
4822026-07-14T05:42:41.031ZJul 14 05:42:41.019 DEBG [sc] ox: starting
4832026-07-14T05:42:41.031ZJul 14 05:42:41.019 DEBG [sc] ox: connecting to [::1]:36571
4842026-07-14T05:42:41.055ZJul 14 05:42:41.020 DEBG [sc] ox waiting for prompt
4852026-07-14T05:42:41.055ZJul 14 05:42:41.030 DEBG [sc] ox: logging in
4862026-07-14T05:42:41.118ZJul 14 05:42:41.106 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
4872026-07-14T05:42:41.207ZJul 14 05:42:41.195 DEBG [sc] ox: starting
4882026-07-14T05:42:41.207ZJul 14 05:42:41.195 DEBG [sc] ox: connecting to [::1]:36571
4892026-07-14T05:42:41.231ZJul 14 05:42:41.196 DEBG [sc] ox waiting for prompt
4902026-07-14T05:42:41.231ZJul 14 05:42:41.206 DEBG [sc] ox: logging in
4912026-07-14T05:42:41.307ZJul 14 05:42:41.295 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
4922026-07-14T05:42:41.373ZJul 14 05:42:41.362 INFO cr1: installing frr
4932026-07-14T05:42:41.373ZJul 14 05:42:41.362 INFO waiting for ceos to initialize
4942026-07-14T05:42:41.398ZJul 14 05:42:41.362 INFO cr3: starting juniper unnumbered setup
4952026-07-14T05:42:41.398ZJul 14 05:42:41.362 INFO ox: setting up npuvm
4962026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr1: starting
4972026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr1: connecting to [::1]:38293
4982026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr2: starting
4992026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr2: connecting to [::1]:35139
5002026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] ox: starting
5012026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] ox: connecting to [::1]:36571
5022026-07-14T05:42:41.398ZJul 14 05:42:41.362 INFO cr3: staged Juniper config and license for guest systemd services
5032026-07-14T05:42:41.398ZJul 14 05:42:41.362 INFO cr3: waiting for Juniper guest config apply
5042026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr3: starting
5052026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr3: connecting to [::1]:45655
5062026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr1 waiting for prompt
5072026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr2 waiting for prompt
5082026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] ox waiting for prompt
5092026-07-14T05:42:41.398ZJul 14 05:42:41.362 DEBG [sc] cr3 waiting for prompt
5102026-07-14T05:42:41.398ZJul 14 05:42:41.363 DEBG [sc] cr3: logging in
5112026-07-14T05:42:41.398ZJul 14 05:42:41.371 DEBG [sc] ox: logging in
5122026-07-14T05:42:41.398ZJul 14 05:42:41.373 DEBG [sc] cr1: logging in
5132026-07-14T05:42:41.398ZJul 14 05:42:41.373 DEBG [sc] cr2: logging in
5142026-07-14T05:42:41.460ZJul 14 05:42:41.449 DEBG [sc] ox: executing command `curl --fail --retry 5 --retry-all-errors --remote-name-all -L https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm.sha256.txt`
5152026-07-14T05:42:41.550ZJul 14 05:42:41.539 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
5162026-07-14T05:42:41.775ZJul 14 05:42:41.761 DEBG [sc] cr3: executing command `cat /run/falcon-junos-apply.status 2>/dev/null || true`
5172026-07-14T05:42:41.803ZJul 14 05:42:41.767 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
5182026-07-14T05:42:43.793ZJul 14 05:42:43.781 INFO cr2: executing eos script show version
5192026-07-14T05:42:43.793ZJul 14 05:42:43.781 DEBG [sc] cr2: starting
5202026-07-14T05:42:43.793ZJul 14 05:42:43.781 DEBG [sc] cr2: connecting to [::1]:35139
5212026-07-14T05:42:43.819ZJul 14 05:42:43.782 DEBG [sc] cr2 waiting for prompt
5222026-07-14T05:42:43.819ZJul 14 05:42:43.792 DEBG [sc] cr2: logging in
5232026-07-14T05:42:43.870ZJul 14 05:42:43.858 INFO cr3: Juniper apply status: waiting for staged Juniper license and config
5242026-07-14T05:42:43.947ZJul 14 05:42:43.935 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
5252026-07-14T05:42:44.872ZJul 14 05:42:44.860 DEBG [sc] cr3: starting
5262026-07-14T05:42:44.872ZJul 14 05:42:44.860 DEBG [sc] cr3: connecting to [::1]:45655
5272026-07-14T05:42:44.896ZJul 14 05:42:44.860 DEBG [sc] cr3 waiting for prompt
5282026-07-14T05:42:44.896ZJul 14 05:42:44.872 DEBG [sc] cr3: logging in
5292026-07-14T05:42:45.037ZJul 14 05:42:45.025 DEBG [sc] cr3: executing command `cat /run/falcon-junos-apply.status 2>/dev/null || true`
5302026-07-14T05:42:47.114ZJul 14 05:42:47.101 INFO cr3: Juniper guest config applied
5312026-07-14T05:42:48.722ZJul 14 05:42:48.710 DEBG [sc] ox: starting
5322026-07-14T05:42:48.722ZJul 14 05:42:48.710 DEBG [sc] ox: connecting to [::1]:36571
5332026-07-14T05:42:48.746ZJul 14 05:42:48.711 DEBG [sc] ox waiting for prompt
5342026-07-14T05:42:48.746ZJul 14 05:42:48.721 DEBG [sc] ox: logging in
5352026-07-14T05:42:48.821ZJul 14 05:42:48.809 DEBG [sc] ox: executing command `cat npuvm.sha256.txt`
5362026-07-14T05:42:48.887ZJul 14 05:42:48.876 DEBG [sc] ox: starting
5372026-07-14T05:42:48.887ZJul 14 05:42:48.876 DEBG [sc] ox: connecting to [::1]:36571
5382026-07-14T05:42:48.912ZJul 14 05:42:48.876 DEBG [sc] ox waiting for prompt
5392026-07-14T05:42:48.912ZJul 14 05:42:48.887 DEBG [sc] ox: logging in
5402026-07-14T05:42:48.986ZJul 14 05:42:48.974 DEBG [sc] ox: executing command `digest -a sha256 npuvm`
5412026-07-14T05:42:49.096ZJul 14 05:42:49.085 INFO ox: npuvm downloaded and checksum verified (attempt 1/5)
5422026-07-14T05:42:49.121ZJul 14 05:42:49.085 DEBG [sc] ox: starting
5432026-07-14T05:42:49.121ZJul 14 05:42:49.085 DEBG [sc] ox: connecting to [::1]:36571
5442026-07-14T05:42:49.121ZJul 14 05:42:49.085 DEBG [sc] ox waiting for prompt
5452026-07-14T05:42:49.121ZJul 14 05:42:49.096 DEBG [sc] ox: logging in
5462026-07-14T05:42:49.196ZJul 14 05:42:49.184 DEBG [sc] ox: executing command `chmod +x npuvm`
5472026-07-14T05:42:49.263ZJul 14 05:42:49.251 DEBG [sc] ox: starting
5482026-07-14T05:42:49.263ZJul 14 05:42:49.251 DEBG [sc] ox: connecting to [::1]:36571
5492026-07-14T05:42:49.287ZJul 14 05:42:49.251 DEBG [sc] ox waiting for prompt
5502026-07-14T05:42:49.287ZJul 14 05:42:49.262 DEBG [sc] ox: logging in
5512026-07-14T05:42:49.362ZJul 14 05:42:49.350 DEBG [sc] ox: executing command `./npuvm install --front-ports 3 --rear-ports 0 --pkt-source vioif0 `
5522026-07-14T05:42:53.084ZJul 14 05:42:53.072 INFO cr1: enabling frr daemon bgpd
5532026-07-14T05:42:53.084ZJul 14 05:42:53.072 DEBG [sc] cr1: starting
5542026-07-14T05:42:53.084ZJul 14 05:42:53.072 DEBG [sc] cr1: connecting to [::1]:38293
5552026-07-14T05:42:53.108ZJul 14 05:42:53.073 DEBG [sc] cr1 waiting for prompt
5562026-07-14T05:42:53.108ZJul 14 05:42:53.084 DEBG [sc] cr1: logging in
5572026-07-14T05:42:53.228ZJul 14 05:42:53.216 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
5582026-07-14T05:42:53.954ZJul 14 05:42:53.942 DEBG [sc] cr2: starting
5592026-07-14T05:42:53.954ZJul 14 05:42:53.942 DEBG [sc] cr2: connecting to [::1]:35139
5602026-07-14T05:42:53.978ZJul 14 05:42:53.942 DEBG [sc] cr2 waiting for prompt
5612026-07-14T05:42:53.978ZJul 14 05:42:53.953 DEBG [sc] cr2: logging in
5622026-07-14T05:42:54.098ZJul 14 05:42:54.086 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
5632026-07-14T05:42:55.304ZJul 14 05:42:55.292 INFO cr1: stopping frr
5642026-07-14T05:42:55.304ZJul 14 05:42:55.292 DEBG [sc] cr1: starting
5652026-07-14T05:42:55.304ZJul 14 05:42:55.292 DEBG [sc] cr1: connecting to [::1]:38293
5662026-07-14T05:42:55.328ZJul 14 05:42:55.292 DEBG [sc] cr1 waiting for prompt
5672026-07-14T05:42:55.328ZJul 14 05:42:55.303 DEBG [sc] cr1: logging in
5682026-07-14T05:42:55.435ZJul 14 05:42:55.424 DEBG [sc] cr1: executing command `systemctl stop frr`
5692026-07-14T05:42:56.222ZJul 14 05:42:56.210 INFO cr2: executing eos script show version
5702026-07-14T05:42:56.222ZJul 14 05:42:56.210 DEBG [sc] cr2: starting
5712026-07-14T05:42:56.222ZJul 14 05:42:56.210 DEBG [sc] cr2: connecting to [::1]:35139
5722026-07-14T05:42:56.247ZJul 14 05:42:56.211 DEBG [sc] cr2 waiting for prompt
5732026-07-14T05:42:56.247ZJul 14 05:42:56.221 DEBG [sc] cr2: logging in
5742026-07-14T05:42:56.387ZJul 14 05:42:56.375 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
5752026-07-14T05:42:57.804ZJul 14 05:42:57.792 INFO cr1: starting frr
5762026-07-14T05:42:57.804ZJul 14 05:42:57.792 DEBG [sc] cr1: starting
5772026-07-14T05:42:57.804ZJul 14 05:42:57.792 DEBG [sc] cr1: connecting to [::1]:38293
5782026-07-14T05:42:57.828ZJul 14 05:42:57.793 DEBG [sc] cr1 waiting for prompt
5792026-07-14T05:42:57.828ZJul 14 05:42:57.803 DEBG [sc] cr1: logging in
5802026-07-14T05:42:57.957ZJul 14 05:42:57.945 DEBG [sc] cr1: executing command `systemctl start frr`
5812026-07-14T05:42:58.717ZJul 14 05:42:58.705 DEBG [sc] cr2: starting
5822026-07-14T05:42:58.717ZJul 14 05:42:58.705 DEBG [sc] cr2: connecting to [::1]:35139
5832026-07-14T05:42:58.742ZJul 14 05:42:58.706 DEBG [sc] cr2 waiting for prompt
5842026-07-14T05:42:58.742ZJul 14 05:42:58.716 DEBG [sc] cr2: logging in
5852026-07-14T05:42:58.882ZJul 14 05:42:58.870 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
5862026-07-14T05:43:01.030ZJul 14 05:43:01.018 INFO cr2: executing eos script show version
5872026-07-14T05:43:01.030ZJul 14 05:43:01.018 DEBG [sc] cr2: starting
5882026-07-14T05:43:01.030ZJul 14 05:43:01.018 DEBG [sc] cr2: connecting to [::1]:35139
5892026-07-14T05:43:01.054ZJul 14 05:43:01.019 DEBG [sc] cr2 waiting for prompt
5902026-07-14T05:43:01.054ZJul 14 05:43:01.029 DEBG [sc] cr2: logging in
5912026-07-14T05:43:01.195ZJul 14 05:43:01.183 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
5922026-07-14T05:43:03.575ZJul 14 05:43:03.563 DEBG [sc] cr2: starting
5932026-07-14T05:43:03.575ZJul 14 05:43:03.563 DEBG [sc] cr2: connecting to [::1]:35139
5942026-07-14T05:43:03.601ZJul 14 05:43:03.564 DEBG [sc] cr2 waiting for prompt
5952026-07-14T05:43:03.601ZJul 14 05:43:03.575 DEBG [sc] cr2: logging in
5962026-07-14T05:43:03.707ZJul 14 05:43:03.695 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
5972026-07-14T05:43:05.259ZJul 14 05:43:05.247 INFO cr1: executing frr script
5982026-07-14T05:43:05.259Z configure
5992026-07-14T05:43:05.259Z ip forwarding
6002026-07-14T05:43:05.259Z ipv6 forwarding
6012026-07-14T05:43:05.259Z ip route 1.2.3.0/24 null0
6022026-07-14T05:43:05.259Z ipv6 route fd99::/64 null0
6032026-07-14T05:43:05.259Z router bgp 44
6042026-07-14T05:43:05.259Z no bgp ebgp-requires-policy
6052026-07-14T05:43:05.260Z timers bgp 2 6
6062026-07-14T05:43:05.260Z neighbor enp0s8 interface remote-as external
6072026-07-14T05:43:05.260Z neighbor enp0s8 timers connect 1
6082026-07-14T05:43:05.260Z address-family ipv4 unicast
6092026-07-14T05:43:05.260Z network 1.2.3.0/24
6102026-07-14T05:43:05.260Z neighbor enp0s8 activate
6112026-07-14T05:43:05.260Z exit-address-family
6122026-07-14T05:43:05.260Z address-family ipv6 unicast
6132026-07-14T05:43:05.260Z network fd99::/64
6142026-07-14T05:43:05.260Z neighbor enp0s8 activate
6152026-07-14T05:43:05.260Z exit-address-family
6162026-07-14T05:43:05.260Z exit
6172026-07-14T05:43:05.260Z
6182026-07-14T05:43:05.260ZJul 14 05:43:05.247 DEBG [sc] cr1: starting
6192026-07-14T05:43:05.260ZJul 14 05:43:05.247 DEBG [sc] cr1: connecting to [::1]:38293
6202026-07-14T05:43:05.285ZJul 14 05:43:05.248 DEBG [sc] cr1 waiting for prompt
6212026-07-14T05:43:05.285ZJul 14 05:43:05.259 DEBG [sc] cr1: logging in
6222026-07-14T05:43:05.425ZJul 14 05:43:05.413 DEBG [sc] cr1: executing command `/usr/bin/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 ' router bgp 44' -c ' no bgp ebgp-requires-policy' -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 ' exit-address-family' -c ' address-family ipv6 unicast' -c ' network fd99::/64' -c ' neighbor enp0s8 activate' -c ' exit-address-family' -c ' exit' -c ' '`
6232026-07-14T05:43:05.806ZJul 14 05:43:05.794 INFO cr2: executing eos script show version
6242026-07-14T05:43:05.806ZJul 14 05:43:05.794 DEBG [sc] cr2: starting
6252026-07-14T05:43:05.806ZJul 14 05:43:05.794 DEBG [sc] cr2: connecting to [::1]:35139
6262026-07-14T05:43:05.831ZJul 14 05:43:05.795 DEBG [sc] cr2 waiting for prompt
6272026-07-14T05:43:05.831ZJul 14 05:43:05.806 DEBG [sc] cr2: logging in
6282026-07-14T05:43:05.961ZJul 14 05:43:05.949 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
6292026-07-14T05:43:08.263ZJul 14 05:43:08.251 INFO cr2: executing eos script
6302026-07-14T05:43:08.263Z enable
6312026-07-14T05:43:08.264Z configure
6322026-07-14T05:43:08.264Z ipv6 unicast-routing
6332026-07-14T05:43:08.264Z ip routing ipv6 interfaces
6342026-07-14T05:43:08.264Z ip routing
6352026-07-14T05:43:08.264Z ip route 1.2.3.0/24 null0
6362026-07-14T05:43:08.264Z ipv6 route fd99::/64 null0
6372026-07-14T05:43:08.264Z interface et1
6382026-07-14T05:43:08.264Z no switchport
6392026-07-14T05:43:08.264Z ipv6 enable
6402026-07-14T05:43:08.264Z
6412026-07-14T05:43:08.264Z router bgp 45
6422026-07-14T05:43:08.264Z router-id 1.2.3.1
6432026-07-14T05:43:08.264Z no bgp default ipv4-unicast
6442026-07-14T05:43:08.264Z timers bgp 2 6
6452026-07-14T05:43:08.264Z neighbor ebgp peer group
6462026-07-14T05:43:08.264Z neighbor ebgp remote-as 33
6472026-07-14T05:43:08.264Z neighbor interface Et1 peer-group ebgp
6482026-07-14T05:43:08.264Z address-family ipv4
6492026-07-14T05:43:08.264Z neighbor ebgp activate
6502026-07-14T05:43:08.264Z neighbor ebgp next-hop address-family ipv6 originate
6512026-07-14T05:43:08.264Z network 1.2.3.0/24
6522026-07-14T05:43:08.264Z exit
6532026-07-14T05:43:08.264Z address-family ipv6
6542026-07-14T05:43:08.264Z neighbor ebgp activate
6552026-07-14T05:43:08.264Z neighbor ebgp next-hop address-family ipv6 originate
6562026-07-14T05:43:08.264Z network fd99::/64
6572026-07-14T05:43:08.264Z exit
6582026-07-14T05:43:08.264Z exit
6592026-07-14T05:43:08.264Z
6602026-07-14T05:43:08.264ZJul 14 05:43:08.251 DEBG [sc] cr2: starting
6612026-07-14T05:43:08.264ZJul 14 05:43:08.251 DEBG [sc] cr2: connecting to [::1]:35139
6622026-07-14T05:43:08.289ZJul 14 05:43:08.252 DEBG [sc] cr2 waiting for prompt
6632026-07-14T05:43:08.289ZJul 14 05:43:08.262 DEBG [sc] cr2: logging in
6642026-07-14T05:43:08.407ZJul 14 05:43:08.394 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
6652026-07-14T05:43:08.407Z enable
6662026-07-14T05:43:08.407Z configure
6672026-07-14T05:43:08.407Z ipv6 unicast-routing
6682026-07-14T05:43:08.407Z ip routing ipv6 interfaces
6692026-07-14T05:43:08.407Z ip routing
6702026-07-14T05:43:08.407Z ip route 1.2.3.0/24 null0
6712026-07-14T05:43:08.407Z ipv6 route fd99::/64 null0
6722026-07-14T05:43:08.407Z interface et1
6732026-07-14T05:43:08.407Z no switchport
6742026-07-14T05:43:08.407Z ipv6 enable
6752026-07-14T05:43:08.407Z
6762026-07-14T05:43:08.407Z router bgp 45
6772026-07-14T05:43:08.407Z router-id 1.2.3.1
6782026-07-14T05:43:08.407Z no bgp default ipv4-unicast
6792026-07-14T05:43:08.407Z timers bgp 2 6
6802026-07-14T05:43:08.407Z neighbor ebgp peer group
6812026-07-14T05:43:08.407Z neighbor ebgp remote-as 33
6822026-07-14T05:43:08.407Z neighbor interface Et1 peer-group ebgp
6832026-07-14T05:43:08.407Z address-family ipv4
6842026-07-14T05:43:08.407Z neighbor ebgp activate
6852026-07-14T05:43:08.407Z neighbor ebgp next-hop address-family ipv6 originate
6862026-07-14T05:43:08.407Z network 1.2.3.0/24
6872026-07-14T05:43:08.407Z exit
6882026-07-14T05:43:08.407Z address-family ipv6
6892026-07-14T05:43:08.407Z neighbor ebgp activate
6902026-07-14T05:43:08.408Z neighbor ebgp next-hop address-family ipv6 originate
6912026-07-14T05:43:08.408Z network fd99::/64
6922026-07-14T05:43:08.408Z exit
6932026-07-14T05:43:08.408Z exit
6942026-07-14T05:43:08.408Z '`
6952026-07-14T05:43:22.982ZJul 14 05:43:22.971 INFO ox: npuvm install output:
6962026-07-14T05:43:23.094Zdendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/66.01 MiB (0s) dendrite-softnpu.tar.gz [>---------------------------------------] 8.00 KiB/66.01 MiB (52m) dendrite-softnpu.tar.gz [>---------------------------------------] 8.00 KiB/66.01 MiB (52m) dendrite-softnpu.tar.gz [>---------------------------------------] 8.00 KiB/66.01 MiB (52m) dendrite-softnpu.tar.gz [>---------------------------------------] 8.00 KiB/66.01 MiB (52m) dendrite-softnpu.tar.gz [>---------------------------------------] 8.00 KiB/66.01 MiB (52m) dendrite-softnpu.tar.gz [>---------------------------------------] 8.00 KiB/66.01 MiB (52m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/66.01 MiB (46m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/66.01 MiB (45m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/66.01 MiB (45m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/66.01 MiB (44m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/66.01 MiB (43m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/66.01 MiB (43m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.46 KiB/66.01 MiB (39m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.46 KiB/66.01 MiB (38m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.46 KiB/66.01 MiB (37m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.46 KiB/66.01 MiB (37m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.46 KiB/66.01 MiB (36m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.46 KiB/66.01 MiB (35m) dendrite-softnpu.tar.gz [>---------------------------------------] 58.68 KiB/66.01 MiB (33m) dendrite-softnpu.tar.gz [>---------------------------------------] 58.68 KiB/66.01 MiB (32m) dendrite-softnpu.tar.gz [>---------------------------------------] 58.68 KiB/66.01 MiB (31m) dendrite-softnpu.tar.gz [>---------------------------------------] 58.68 KiB/66.01 MiB (30m) dendrite-softnpu.tar.gz [>---------------------------------------] 346.20 KiB/66.01 MiB (12m) dendrite-softnpu.tar.gz [>---------------------------------------] 594.20 KiB/66.01 MiB (7m) dendrite-softnpu.tar.gz [>---------------------------------------] 839.44 KiB/66.01 MiB (4m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.18 MiB/66.01 MiB (3m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.42 MiB/66.01 MiB (2m) dendrite-softnpu.tar.gz [#>--------------------------------------] 1.66 MiB/66.01 MiB (2m) dendrite-softnpu.tar.gz [#>--------------------------------------] 1.98 MiB/66.01 MiB (2m) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.26 MiB/66.01 MiB (75s) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.50 MiB/66.01 MiB (67s) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.81 MiB/66.01 MiB (60s) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.09 MiB/66.01 MiB (53s) dendrite-softnpu.tar.gz [##>-------------------------------------] 3.34 MiB/66.01 MiB (49s) dendrite-softnpu.tar.gz [##>-------------------------------------] 3.62 MiB/66.01 MiB (45s) dendrite-softnpu.tar.gz [##>-------------------------------------] 3.93 MiB/66.01 MiB (42s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.17 MiB/66.01 MiB (39s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.41 MiB/66.01 MiB (37s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.77 MiB/66.01 MiB (35s) dendrite-softnpu.tar.gz [###>------------------------------------] 5.01 MiB/66.01 MiB (33s) dendrite-softnpu.tar.gz [###>------------------------------------] 5.25 MiB/66.01 MiB (32s) dendrite-softnpu.tar.gz [###>------------------------------------] 5.61 MiB/66.01 MiB (30s) dendrite-softnpu.tar.gz [###>------------------------------------] 5.85 MiB/66.01 MiB (29s) dendrite-softnpu.tar.gz [###>------------------------------------] 6.09 MiB/66.01 MiB (28s) dendrite-softnpu.tar.gz [###>------------------------------------] 6.41 MiB/66.01 MiB (27s) dendrite-softnpu.tar.gz [####>-----------------------------------] 6.69 MiB/66.01 MiB (26s) dendrite-softnpu.tar.gz [####>-----------------------------------] 6.93 MiB/66.01 MiB (25s) dendrite-softnpu.tar.gz [####>-----------------------------------] 7.24 MiB/66.01 MiB (25s) dendrite-softnpu.tar.gz [####>-----------------------------------] 7.53 MiB/66.01 MiB (24s) dendrite-softnpu.tar.gz [####>-----------------------------------] 7.77 MiB/66.01 MiB (23s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.07 MiB/66.01 MiB (23s) dendrite-softnpu.tar.gz [#####>----------------------------------] 8.37 MiB/66.01 MiB (22s) dendrite-softnpu.tar.gz [#####>----------------------------------] 8.61 MiB/66.01 MiB (21s) dendrite-softnpu.tar.gz [#####>----------------------------------] 8.90 MiB/66.01 MiB (21s) dendrite-softnpu.tar.gz [#####>----------------------------------] 9.21 MiB/66.01 MiB (20s) dendrite-softnpu.tar.gz [#####>----------------------------------] 9.45 MiB/66.01 MiB (20s) dendrite-softnpu.tar.gz [#####>----------------------------------] 9.74 MiB/66.01 MiB (20s) dendrite-softnpu.tar.gz [######>---------------------------------] 10.05 MiB/66.01 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 10.29 MiB/66.01 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 10.58 MiB/66.01 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 10.88 MiB/66.01 MiB (18s) dendrite-softnpu.tar.gz [######>---------------------------------] 11.12 MiB/66.01 MiB (18s) dendrite-softnpu.tar.gz [######>---------------------------------] 11.40 MiB/66.01 MiB (18s) dendrite-softnpu.tar.gz [#######>--------------------------------] 11.72 MiB/66.01 MiB (17s) dendrite-softnpu.tar.gz [#######>--------------------------------] 11.96 MiB/66.01 MiB (17s) dendrite-softnpu.tar.gz [#######>--------------------------------] 12.23 MiB/66.01 MiB (17s) dendrite-softnpu.tar.gz [#######>--------------------------------] 12.54 MiB/66.01 MiB (16s) dendrite-softnpu.tar.gz [#######>--------------------------------] 12.80 MiB/66.01 MiB (16s) dendrite-softnpu.tar.gz [#######>--------------------------------] 13.06 MiB/66.01 MiB (16s) dendrite-softnpu.tar.gz [########>-------------------------------] 13.37 MiB/66.01 MiB (16s) dendrite-softnpu.tar.gz [########>-------------------------------] 13.64 MiB/66.01 MiB (15s) dendrite-softnpu.tar.gz [########>-------------------------------] 13.88 MiB/66.01 MiB (15s) dendrite-softnpu.tar.gz [########>-------------------------------] 14.20 MiB/66.01 MiB (15s) dendrite-softnpu.tar.gz [########>-------------------------------] 14.48 MiB/66.01 MiB (15s) dendrite-softnpu.tar.gz [########>-------------------------------] 14.72 MiB/66.01 MiB (15s) dendrite-softnpu.tar.gz [#########>------------------------------] 15.02 MiB/66.01 MiB (14s) dendrite-softnpu.tar.gz [#########>------------------------------] 15.32 MiB/66.01 MiB (14s) dendrite-softnpu.tar.gz [#########>------------------------------] 15.56 MiB/66.01 MiB (14s) dendrite-softnpu.tar.gz [#########>------------------------------] 15.86 MiB/66.01 MiB (14s) dendrite-softnpu.tar.gz [#########>------------------------------] 16.16 MiB/66.01 MiB (14s) dendrite-softnpu.tar.gz [#########>------------------------------] 16.39 MiB/66.01 MiB (14s) dendrite-softnpu.tar.gz [##########>-----------------------------] 16.70 MiB/66.01 MiB (13s) dendrite-softnpu.tar.gz [##########>-----------------------------] 17.00 MiB/66.01 MiB (13s) dendrite-softnpu.tar.gz [##########>-----------------------------] 17.23 MiB/66.01 MiB (13s) dendrite-softnpu.tar.gz [##########>-----------------------------] 17.54 MiB/66.01 MiB (13s) dendrite-softnpu.tar.gz [##########>-----------------------------] 17.83 MiB/66.01 MiB (13s) dendrite-softnpu.tar.gz [##########>-----------------------------] 18.07 MiB/66.01 MiB (13s) dendrite-softnpu.tar.gz [###########>----------------------------] 18.38 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [###########>----------------------------] 18.67 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [###########>----------------------------] 18.91 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [###########>----------------------------] 19.21 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [###########>----------------------------] 19.50 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [###########>----------------------------] 19.75 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [############>---------------------------] 20.05 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [############>---------------------------] 20.34 MiB/66.01 MiB (12s) dendrite-softnpu.tar.gz [############>---------------------------] 20.59 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [############>---------------------------] 20.89 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [############>---------------------------] 21.19 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [############>---------------------------] 21.43 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [#############>--------------------------] 21.71 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [#############>--------------------------] 22.03 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [#############>--------------------------] 22.27 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [#############>--------------------------] 22.55 MiB/66.01 MiB (11s) dendrite-softnpu.tar.gz [#############>--------------------------] 22.84 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [##############>-------------------------] 23.11 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [##############>-------------------------] 23.36 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [##############>-------------------------] 23.68 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [##############>-------------------------] 23.95 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [##############>-------------------------] 24.19 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [##############>-------------------------] 24.52 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [###############>------------------------] 24.79 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [###############>------------------------] 25.02 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [###############>------------------------] 25.36 MiB/66.01 MiB (10s) dendrite-softnpu.tar.gz [###############>------------------------] 25.62 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [###############>------------------------] 25.86 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [###############>------------------------] 26.20 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [################>-----------------------] 26.46 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [################>-----------------------] 26.70 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [################>-----------------------] 27.03 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [################>-----------------------] 27.30 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [################>-----------------------] 27.54 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [################>-----------------------] 27.84 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [#################>----------------------] 28.14 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [#################>----------------------] 28.38 MiB/66.01 MiB (9s) dendrite-softnpu.tar.gz [#################>----------------------] 28.68 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [#################>----------------------] 28.98 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [#################>----------------------] 29.22 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [#################>----------------------] 29.52 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [##################>---------------------] 29.82 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [##################>---------------------] 30.06 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [##################>---------------------] 30.36 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [##################>---------------------] 30.66 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [##################>---------------------] 30.90 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [##################>---------------------] 31.16 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 31.50 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 31.74 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 31.98 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.33 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.58 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.75 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.76 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.77 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.77 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.77 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.78 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [###################>--------------------] 32.83 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [####################>-------------------] 33.03 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [####################>-------------------] 33.27 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [####################>-------------------] 33.51 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [####################>-------------------] 33.87 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [####################>-------------------] 34.11 MiB/66.01 MiB (8s) dendrite-softnpu.tar.gz [####################>-------------------] 34.35 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#####################>------------------] 34.70 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#####################>------------------] 34.95 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#####################>------------------] 35.19 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#####################>------------------] 35.52 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#####################>------------------] 35.79 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#####################>------------------] 36.03 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [######################>-----------------] 36.36 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [######################>-----------------] 36.63 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [######################>-----------------] 36.86 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [######################>-----------------] 37.15 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [######################>-----------------] 37.47 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [######################>-----------------] 37.70 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#######################>----------------] 37.97 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#######################>----------------] 38.30 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#######################>----------------] 38.54 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#######################>----------------] 38.80 MiB/66.01 MiB (7s) dendrite-softnpu.tar.gz [#######################>----------------] 39.14 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#######################>----------------] 39.38 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [########################>---------------] 39.64 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [########################>---------------] 39.98 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [########################>---------------] 40.22 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [########################>---------------] 40.46 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [########################>---------------] 40.82 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [########################>---------------] 41.06 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#########################>--------------] 41.30 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#########################>--------------] 41.66 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#########################>--------------] 41.90 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#########################>--------------] 42.14 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#########################>--------------] 42.50 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [#########################>--------------] 42.73 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [##########################>-------------] 42.98 MiB/66.01 MiB (6s) dendrite-softnpu.tar.gz [##########################>-------------] 43.34 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [##########################>-------------] 43.57 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [##########################>-------------] 43.81 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [##########################>-------------] 44.17 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [##########################>-------------] 44.41 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [###########################>------------] 44.65 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [###########################>------------] 45.01 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [###########################>------------] 45.25 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [###########################>------------] 45.49 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [###########################>------------] 45.85 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [###########################>------------] 46.09 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [############################>-----------] 46.33 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [############################>-----------] 46.69 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [############################>-----------] 46.93 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [############################>-----------] 47.17 MiB/66.01 MiB (5s) dendrite-softnpu.tar.gz [############################>-----------] 47.53 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [############################>-----------] 47.77 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [#############################>----------] 48.01 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [#############################>----------] 48.35 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [#############################>----------] 48.60 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [#############################>----------] 48.85 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [#############################>----------] 49.18 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [#############################>----------] 49.45 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [##############################>---------] 49.69 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [##############################>---------] 50.01 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [##############################>---------] 50.28 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [##############################>---------] 50.53 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [##############################>---------] 50.83 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [##############################>---------] 51.12 MiB/66.01 MiB (4s) dendrite-softnpu.tar.gz [###############################>--------] 51.36 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [###############################>--------] 51.66 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [###############################>--------] 51.96 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [###############################>--------] 52.20 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [###############################>--------] 52.45 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [###############################>--------] 52.80 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [################################>-------] 53.04 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [################################>-------] 53.29 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [################################>-------] 53.64 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [################################>-------] 53.88 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [################################>-------] 54.12 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 54.48 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 54.72 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 54.96 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 55.32 MiB/66.01 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 55.56 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [#################################>------] 55.80 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [##################################>-----] 56.16 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [##################################>-----] 56.40 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [##################################>-----] 56.64 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [##################################>-----] 57.00 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [##################################>-----] 57.23 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [##################################>-----] 57.47 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 57.76 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 58.07 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 58.32 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 58.59 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 58.91 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 59.15 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 59.42 MiB/66.01 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 59.75 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [####################################>---] 59.99 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [####################################>---] 60.24 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [####################################>---] 60.59 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [####################################>---] 60.83 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 61.08 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 61.43 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 61.67 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 61.92 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 62.27 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 62.51 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 62.76 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 63.11 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 63.35 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 63.59 MiB/66.01 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 63.95 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [######################################>-] 64.19 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 64.43 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 64.79 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 65.03 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 65.27 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 65.62 MiB/66.01 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 65.87 MiB/66.01 MiB (0s) Refreshing catalog 1/1 helios Refreshing catalog 1/1 helios Refreshing catalog 1/1 helios Creating Plan (Solver setup): \ Creating Plan (Solver setup): | Creating Plan (Solver setup): / Creating Plan (Solver setup): - Creating Plan (Solver setup): \ Creating Plan (Solver setup): | Creating Plan (Solver setup): / Creating Plan (Solver setup): - Creating Plan (Solver setup): - Creating Plan (Running solver): \ Creating Plan (Running solver): \ Creating Plan (Finding local manifests): | Creating Plan (Finding local manifests): | Creating Plan (Download Manifests 0/1) / Creating Plan (Download Manifests 1/1) / Creating Plan (Download Manifests 1/1) / Creating Plan (Package planning: 1/1): - Creating Plan (Package planning: 1/1): - Creating Plan (Merging actions): \ Creating Plan (Merging actions): \ Creating Plan (Checking for conflicting actions): | Creating Plan (Checking for conflicting actions): / Creating Plan (Checking for conflicting actions): - Creating Plan (Checking for conflicting actions): - Creating Plan (Consolidating action changes): \ Creating Plan (Consolidating action changes): \ Creating Plan (Evaluating mediators): | Creating Plan (Evaluating mediators): / Creating Plan (Evaluating mediators): / Packages to install: 1
6972026-07-14T05:43:23.095Z Create boot environment: No
6982026-07-14T05:43:23.095ZCreate backup boot environment: No
6992026-07-14T05:43:23.096Z
7002026-07-14T05:43:23.096ZDOWNLOAD PKGS FILES XFER (MB) SPEED
7012026-07-14T05:43:23.096Z driver/misc/tofino 0/1 0/6 0.0/0.0 -- driver/misc/tofino 0/1 3/6 0.0/0.0 -- driver/misc/tofino 1/1 6/6 0.0/0.0 -- Completed 1/1 6/6 0.0/0.0 322k/s
7022026-07-14T05:43:23.096Z
7032026-07-14T05:43:23.096ZPHASE ITEMS
7042026-07-14T05:43:23.096Z Installing new actions 1/27 Installing new actions 24/27 Installing new actions 27/27
7052026-07-14T05:43:23.096Z Updating package state database working - Updating package state database Done
7062026-07-14T05:43:23.096Z Updating package cache 0/0 \ Updating package cache 0/0
7072026-07-14T05:43:23.096Z Updating image state working | Updating image state Done
7082026-07-14T05:43:23.096Z Creating fast lookup database working / Creating fast lookup database working - Creating fast lookup database working \ Creating fast lookup database working | Creating fast lookup database working / Creating fast lookup database working - Creating fast lookup database working \ Creating fast lookup database working | Creating fast lookup database working / Creating fast lookup database working - Creating fast lookup database working \ Creating fast lookup database working | Creating fast lookup database working / Creating fast lookup database working - Creating fast lookup database working \ Creating fast lookup database working | Creating fast lookup database working / Creating fast lookup database working - Creating fast lookup database working \ Creating fast lookup database Done
7092026-07-14T05:43:23.096Z Reading search index working | Reading search index Done
7102026-07-14T05:43:23.096Z Updating search index 0/1 / Updating search index 1/1
7112026-07-14T05:43:23.096Z Updating package cache 0/1 - Updating package cache 1/1
7122026-07-14T05:43:23.096Zscadm [########################################] 0 B/0 B (0s) scadm [########################################] 0 B/0 B (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [>---------------------------------------] 46.22 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 143.17 KiB/15.18 MiB (3m) scadm [>---------------------------------------] 353.17 KiB/15.18 MiB (2m) scadm [#>--------------------------------------] 685.17 KiB/15.18 MiB (52s) scadm [##>-------------------------------------] 970.17 KiB/15.18 MiB (31s) scadm [###>------------------------------------] 1.19 MiB/15.18 MiB (23s) scadm [###>------------------------------------] 1.47 MiB/15.18 MiB (19s) scadm [####>-----------------------------------] 1.79 MiB/15.18 MiB (14s) scadm [#####>----------------------------------] 2.03 MiB/15.18 MiB (12s) scadm [#####>----------------------------------] 2.27 MiB/15.18 MiB (11s) scadm [######>---------------------------------] 2.61 MiB/15.18 MiB (10s) scadm [#######>--------------------------------] 2.87 MiB/15.18 MiB (8s) scadm [########>-------------------------------] 3.11 MiB/15.18 MiB (8s) scadm [#########>------------------------------] 3.44 MiB/15.18 MiB (7s) scadm [#########>------------------------------] 3.71 MiB/15.18 MiB (6s) scadm [##########>-----------------------------] 3.95 MiB/15.18 MiB (6s) scadm [###########>----------------------------] 4.27 MiB/15.18 MiB (5s) scadm [###########>----------------------------] 4.54 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.79 MiB/15.18 MiB (5s) scadm [#############>--------------------------] 5.11 MiB/15.18 MiB (4s) scadm [##############>-------------------------] 5.38 MiB/15.18 MiB (4s) scadm [##############>-------------------------] 5.62 MiB/15.18 MiB (4s) scadm [###############>------------------------] 5.93 MiB/15.18 MiB (4s) scadm [################>-----------------------] 6.22 MiB/15.18 MiB (3s) scadm [#################>----------------------] 6.46 MiB/15.18 MiB (3s) scadm [#################>----------------------] 6.72 MiB/15.18 MiB (3s) scadm [##################>---------------------] 7.06 MiB/15.18 MiB (3s) scadm [###################>--------------------] 7.30 MiB/15.18 MiB (3s) scadm [###################>--------------------] 7.54 MiB/15.18 MiB (3s) scadm [####################>-------------------] 7.90 MiB/15.18 MiB (2s) scadm [#####################>------------------] 8.14 MiB/15.18 MiB (2s) scadm [######################>-----------------] 8.38 MiB/15.18 MiB (2s) scadm [#######################>----------------] 8.74 MiB/15.18 MiB (2s) scadm [#######################>----------------] 8.98 MiB/15.18 MiB (2s) scadm [########################>---------------] 9.22 MiB/15.18 MiB (2s) scadm [#########################>--------------] 9.53 MiB/15.18 MiB (2s) scadm [#########################>--------------] 9.81 MiB/15.18 MiB (2s) scadm [##########################>-------------] 10.06 MiB/15.18 MiB (2s) scadm [###########################>------------] 10.35 MiB/15.18 MiB (1s) scadm [############################>-----------] 10.65 MiB/15.18 MiB (1s) scadm [############################>-----------] 10.90 MiB/15.18 MiB (1s) scadm [#############################>----------] 11.13 MiB/15.18 MiB (1s) scadm [##############################>---------] 11.49 MiB/15.18 MiB (1s) scadm [##############################>---------] 11.73 MiB/15.18 MiB (1s) scadm [###############################>--------] 11.97 MiB/15.18 MiB (1s) scadm [################################>-------] 12.30 MiB/15.18 MiB (1s) scadm [#################################>------] 12.57 MiB/15.18 MiB (1s) scadm [#################################>------] 12.81 MiB/15.18 MiB (1s) scadm [##################################>-----] 13.11 MiB/15.18 MiB (1s) scadm [###################################>----] 13.41 MiB/15.18 MiB (0s) scadm [###################################>----] 13.65 MiB/15.18 MiB (0s) scadm [####################################>---] 13.92 MiB/15.18 MiB (0s) scadm [#####################################>--] 14.25 MiB/15.18 MiB (0s) scadm [######################################>-] 14.49 MiB/15.18 MiB (0s) scadm [######################################>-] 14.75 MiB/15.18 MiB (0s) scadm [#######################################>] 15.09 MiB/15.18 MiB (0s) libsidecar_lite.so [########################################] 0 B/0 B (0s) libsidecar_lite.so [########################################] 0 B/0 B (0s) libsidecar_lite.so [----------------------------------------] 0 B/11.89 MiB (0s) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.48 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 52.17 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 149.91 KiB/11.89 MiB (2m) libsidecar_lite.so [#>--------------------------------------] 369.22 KiB/11.89 MiB (54s) libsidecar_lite.so [##>-------------------------------------] 737.75 KiB/11.89 MiB (26s) libsidecar_lite.so [###>------------------------------------] 986.01 KiB/11.89 MiB (19s) libsidecar_lite.so [####>-----------------------------------] 1.21 MiB/11.89 MiB (14s) libsidecar_lite.so [#####>----------------------------------] 1.56 MiB/11.89 MiB (11s) libsidecar_lite.so [######>---------------------------------] 1.80 MiB/11.89 MiB (9s) libsidecar_lite.so [######>---------------------------------] 2.04 MiB/11.89 MiB (8s) libsidecar_lite.so [########>-------------------------------] 2.40 MiB/11.89 MiB (6s) libsidecar_lite.so [########>-------------------------------] 2.64 MiB/11.89 MiB (6s) libsidecar_lite.so [#########>------------------------------] 2.88 MiB/11.89 MiB (5s) libsidecar_lite.so [##########>-----------------------------] 3.23 MiB/11.89 MiB (5s) libsidecar_lite.so [###########>----------------------------] 3.48 MiB/11.89 MiB (4s) libsidecar_lite.so [############>---------------------------] 3.72 MiB/11.89 MiB (4s) libsidecar_lite.so [#############>--------------------------] 4.00 MiB/11.89 MiB (4s) libsidecar_lite.so [##############>-------------------------] 4.32 MiB/11.89 MiB (3s) libsidecar_lite.so [###############>------------------------] 4.56 MiB/11.89 MiB (3s) libsidecar_lite.so [################>-----------------------] 4.84 MiB/11.89 MiB (3s) libsidecar_lite.so [#################>----------------------] 5.16 MiB/11.89 MiB (3s) libsidecar_lite.so [##################>---------------------] 5.40 MiB/11.89 MiB (2s) libsidecar_lite.so [###################>--------------------] 5.68 MiB/11.89 MiB (2s) libsidecar_lite.so [####################>-------------------] 6.00 MiB/11.89 MiB (2s) libsidecar_lite.so [####################>-------------------] 6.24 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.51 MiB/11.89 MiB (2s) libsidecar_lite.so [######################>-----------------] 6.83 MiB/11.89 MiB (2s) libsidecar_lite.so [#######################>----------------] 7.07 MiB/11.89 MiB (2s) libsidecar_lite.so [########################>---------------] 7.32 MiB/11.89 MiB (1s) libsidecar_lite.so [#########################>--------------] 7.62 MiB/11.89 MiB (1s) libsidecar_lite.so [##########################>-------------] 7.91 MiB/11.89 MiB (1s) libsidecar_lite.so [###########################>------------] 8.16 MiB/11.89 MiB (1s) libsidecar_lite.so [############################>-----------] 8.47 MiB/11.89 MiB (1s) libsidecar_lite.so [#############################>----------] 8.75 MiB/11.89 MiB (1s) libsidecar_lite.so [##############################>---------] 8.99 MiB/11.89 MiB (1s) libsidecar_lite.so [###############################>--------] 9.29 MiB/11.89 MiB (1s) libsidecar_lite.so [################################>-------] 9.59 MiB/11.89 MiB (1s) libsidecar_lite.so [#################################>------] 9.83 MiB/11.89 MiB (1s) libsidecar_lite.so [##################################>-----] 10.12 MiB/11.89 MiB (1s) libsidecar_lite.so [###################################>----] 10.43 MiB/11.89 MiB (0s) libsidecar_lite.so [###################################>----] 10.67 MiB/11.89 MiB (0s) libsidecar_lite.so [####################################>---] 10.95 MiB/11.89 MiB (0s) libsidecar_lite.so [#####################################>--] 11.27 MiB/11.89 MiB (0s) libsidecar_lite.so [######################################>-] 11.51 MiB/11.89 MiB (0s) libsidecar_lite.so [#######################################>] 11.79 MiB/11.89 MiB (0s)
7132026-07-14T05:43:23.096ZJul 14 05:43:22.971 DEBG [sc] ox: starting
7142026-07-14T05:43:23.096ZJul 14 05:43:22.971 DEBG [sc] ox: connecting to [::1]:36571
7152026-07-14T05:43:23.096ZJul 14 05:43:22.971 DEBG [sc] ox waiting for prompt
7162026-07-14T05:43:23.096ZJul 14 05:43:22.982 DEBG [sc] ox: logging in
7172026-07-14T05:43:23.121ZJul 14 05:43:23.070 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
7182026-07-14T05:43:23.384ZJul 14 05:43:23.372 DEBG [sc] ox: starting
7192026-07-14T05:43:23.384ZJul 14 05:43:23.372 DEBG [sc] ox: connecting to [::1]:36571
7202026-07-14T05:43:23.408ZJul 14 05:43:23.373 DEBG [sc] ox waiting for prompt
7212026-07-14T05:43:23.408ZJul 14 05:43:23.384 DEBG [sc] ox: logging in
7222026-07-14T05:43:23.484ZJul 14 05:43:23.473 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
7232026-07-14T05:43:24.509ZJul 14 05:43:24.497 DEBG [sc] ox: starting
7242026-07-14T05:43:24.509ZJul 14 05:43:24.497 DEBG [sc] ox: connecting to [::1]:36571
7252026-07-14T05:43:24.534ZJul 14 05:43:24.498 DEBG [sc] ox waiting for prompt
7262026-07-14T05:43:24.534ZJul 14 05:43:24.498 DEBG [sc] ox: logging in
7272026-07-14T05:43:24.587ZJul 14 05:43:24.575 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
7282026-07-14T05:43:24.652ZJul 14 05:43:24.640 DEBG [sc] ox: starting
7292026-07-14T05:43:24.652ZJul 14 05:43:24.640 DEBG [sc] ox: connecting to [::1]:36571
7302026-07-14T05:43:24.677ZJul 14 05:43:24.641 DEBG [sc] ox waiting for prompt
7312026-07-14T05:43:24.677ZJul 14 05:43:24.651 DEBG [sc] ox: logging in
7322026-07-14T05:43:24.740ZJul 14 05:43:24.728 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
7332026-07-14T05:43:24.796ZJul 14 05:43:24.784 DEBG [sc] ox: starting
7342026-07-14T05:43:24.796ZJul 14 05:43:24.784 DEBG [sc] ox: connecting to [::1]:36571
7352026-07-14T05:43:24.821ZJul 14 05:43:24.785 DEBG [sc] ox waiting for prompt
7362026-07-14T05:43:24.821ZJul 14 05:43:24.794 DEBG [sc] ox: logging in
7372026-07-14T05:43:24.883ZJul 14 05:43:24.871 DEBG [sc] ox: executing command `dladm show-link tfportqsfp2_0 -p -o link`
7382026-07-14T05:43:24.939ZJul 14 05:43:24.927 DEBG [sc] ox: starting
7392026-07-14T05:43:24.940ZJul 14 05:43:24.927 DEBG [sc] ox: connecting to [::1]:36571
7402026-07-14T05:43:24.965ZJul 14 05:43:24.928 DEBG [sc] ox waiting for prompt
7412026-07-14T05:43:24.965ZJul 14 05:43:24.937 DEBG [sc] ox: logging in
7422026-07-14T05:43:25.027ZJul 14 05:43:25.015 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
7432026-07-14T05:43:25.093ZJul 14 05:43:25.081 DEBG [sc] ox: starting
7442026-07-14T05:43:25.093ZJul 14 05:43:25.081 DEBG [sc] ox: connecting to [::1]:36571
7452026-07-14T05:43:25.118ZJul 14 05:43:25.082 DEBG [sc] ox waiting for prompt
7462026-07-14T05:43:25.118ZJul 14 05:43:25.092 DEBG [sc] ox: logging in
7472026-07-14T05:43:25.181ZJul 14 05:43:25.169 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
7482026-07-14T05:43:25.248ZJul 14 05:43:25.236 DEBG [sc] ox: starting
7492026-07-14T05:43:25.249ZJul 14 05:43:25.236 DEBG [sc] ox: connecting to [::1]:36571
7502026-07-14T05:43:25.273ZJul 14 05:43:25.237 DEBG [sc] ox waiting for prompt
7512026-07-14T05:43:25.273ZJul 14 05:43:25.247 DEBG [sc] ox: logging in
7522026-07-14T05:43:25.336ZJul 14 05:43:25.324 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
7532026-07-14T05:43:25.391ZJul 14 05:43:25.379 DEBG [sc] ox: starting
7542026-07-14T05:43:25.391ZJul 14 05:43:25.379 DEBG [sc] ox: connecting to [::1]:36571
7552026-07-14T05:43:25.415ZJul 14 05:43:25.380 DEBG [sc] ox waiting for prompt
7562026-07-14T05:43:25.415ZJul 14 05:43:25.390 DEBG [sc] ox: logging in
7572026-07-14T05:43:25.479ZJul 14 05:43:25.467 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
7582026-07-14T05:43:25.546ZJul 14 05:43:25.534 DEBG [sc] ox: starting
7592026-07-14T05:43:25.546ZJul 14 05:43:25.534 DEBG [sc] ox: connecting to [::1]:36571
7602026-07-14T05:43:25.570ZJul 14 05:43:25.534 DEBG [sc] ox waiting for prompt
7612026-07-14T05:43:25.570ZJul 14 05:43:25.545 DEBG [sc] ox: logging in
7622026-07-14T05:43:25.633ZJul 14 05:43:25.621 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp2_0/ll`
7632026-07-14T05:43:25.689ZJul 14 05:43:25.677 DEBG [sc] ox: starting
7642026-07-14T05:43:25.689ZJul 14 05:43:25.677 DEBG [sc] ox: connecting to [::1]:36571
7652026-07-14T05:43:25.716ZJul 14 05:43:25.678 DEBG [sc] ox waiting for prompt
7662026-07-14T05:43:25.716ZJul 14 05:43:25.687 DEBG [sc] ox: logging in
7672026-07-14T05:43:25.778ZJul 14 05:43:25.766 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp2_0/ll -p -o addr`
7682026-07-14T05:43:25.845ZJul 14 05:43:25.833 DEBG [sc] ox: starting
7692026-07-14T05:43:25.845ZJul 14 05:43:25.833 DEBG [sc] ox: connecting to [::1]:36571
7702026-07-14T05:43:25.870ZJul 14 05:43:25.834 DEBG [sc] ox waiting for prompt
7712026-07-14T05:43:25.870ZJul 14 05:43:25.844 DEBG [sc] ox: logging in
7722026-07-14T05:43:25.932ZJul 14 05:43:25.920 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
7732026-07-14T05:43:25.987ZJul 14 05:43:25.975 DEBG [sc] ox: starting
7742026-07-14T05:43:25.987ZJul 14 05:43:25.975 DEBG [sc] ox: connecting to [::1]:36571
7752026-07-14T05:43:26.012ZJul 14 05:43:25.976 DEBG [sc] ox waiting for prompt
7762026-07-14T05:43:26.012ZJul 14 05:43:25.987 DEBG [sc] ox: logging in
7772026-07-14T05:43:26.088ZJul 14 05:43:26.075 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
7782026-07-14T05:43:26.160ZJul 14 05:43:26.147 INFO adding BGP router to mgd
7792026-07-14T05:43:26.184Zmgd bgp cr1 -> Established: iteration 1/20: expected Some(Established), got Some(Connect)
7802026-07-14T05:43:27.177Zmgd bgp cr2 -> Established: iteration 1/20: expected Some(Established), got Some(Connect)
7812026-07-14T05:43:28.179Zmgd bgp cr3 -> Established: iteration 1/20: expected Some(Established), got Some(OpenConfirm)
7822026-07-14T05:43:29.414Zdpd ipv6 targets for fd99::/64: iteration 1/20: expected 3, got 2
7832026-07-14T05:43:30.416ZJul 14 05:43:30.404 INFO cr1: executing frr script show ip bgp json
7842026-07-14T05:43:30.416ZJul 14 05:43:30.404 DEBG [sc] cr1: starting
7852026-07-14T05:43:30.416ZJul 14 05:43:30.404 DEBG [sc] cr1: connecting to [::1]:38293
7862026-07-14T05:43:30.440ZJul 14 05:43:30.405 DEBG [sc] cr1 waiting for prompt
7872026-07-14T05:43:30.440ZJul 14 05:43:30.415 DEBG [sc] cr1: logging in
7882026-07-14T05:43:30.766ZJul 14 05:43:30.754 DEBG [sc] cr1: executing command `/usr/bin/vtysh -c 'show ip bgp json'`
7892026-07-14T05:43:32.886ZJul 14 05:43:32.874 INFO cr1: executing frr script show bgp json
7902026-07-14T05:43:32.886ZJul 14 05:43:32.874 DEBG [sc] cr1: starting
7912026-07-14T05:43:32.886ZJul 14 05:43:32.874 DEBG [sc] cr1: connecting to [::1]:38293
7922026-07-14T05:43:32.911ZJul 14 05:43:32.875 DEBG [sc] cr1 waiting for prompt
7932026-07-14T05:43:32.911ZJul 14 05:43:32.885 DEBG [sc] cr1: logging in
7942026-07-14T05:43:33.043ZJul 14 05:43:33.031 DEBG [sc] cr1: executing command `/usr/bin/vtysh -c 'show bgp json'`
7952026-07-14T05:43:35.169ZJul 14 05:43:35.157 INFO cr2: executing eos script show ip bgp | json
7962026-07-14T05:43:35.169ZJul 14 05:43:35.157 DEBG [sc] cr2: starting
7972026-07-14T05:43:35.169ZJul 14 05:43:35.157 DEBG [sc] cr2: connecting to [::1]:35139
7982026-07-14T05:43:35.193ZJul 14 05:43:35.158 DEBG [sc] cr2 waiting for prompt
7992026-07-14T05:43:35.193ZJul 14 05:43:35.169 DEBG [sc] cr2: logging in
8002026-07-14T05:43:35.513ZJul 14 05:43:35.501 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
8012026-07-14T05:43:37.932ZJul 14 05:43:37.920 INFO cr2: executing eos script show ipv6 bgp | json
8022026-07-14T05:43:37.956ZJul 14 05:43:37.921 DEBG [sc] cr2: starting
8032026-07-14T05:43:37.957ZJul 14 05:43:37.921 DEBG [sc] cr2: connecting to [::1]:35139
8042026-07-14T05:43:37.957ZJul 14 05:43:37.921 DEBG [sc] cr2 waiting for prompt
8052026-07-14T05:43:37.957ZJul 14 05:43:37.931 DEBG [sc] cr2: logging in
8062026-07-14T05:43:38.088ZJul 14 05:43:38.076 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
8072026-07-14T05:43:40.473ZJul 14 05:43:40.461 INFO cr3: executing juniper script show route 4.5.6.0/24 protocol bgp
8082026-07-14T05:43:40.474ZJul 14 05:43:40.462 DEBG [sc] cr3: starting
8092026-07-14T05:43:40.474ZJul 14 05:43:40.462 DEBG [sc] cr3: connecting to [::1]:45655
8102026-07-14T05:43:40.498ZJul 14 05:43:40.463 DEBG [sc] cr3 waiting for prompt
8112026-07-14T05:43:40.498ZJul 14 05:43:40.473 DEBG [sc] cr3: logging in
8122026-07-14T05:43:40.816ZJul 14 05:43:40.804 DEBG [sc] cr3: executing command `docker exec crpd1 cli -c 'show route 4.5.6.0/24 protocol bgp'`
8132026-07-14T05:43:42.985ZJul 14 05:43:42.973 INFO cr3: executing juniper script show route fdee::/64 protocol bgp
8142026-07-14T05:43:42.985ZJul 14 05:43:42.973 DEBG [sc] cr3: starting
8152026-07-14T05:43:42.985ZJul 14 05:43:42.973 DEBG [sc] cr3: connecting to [::1]:45655
8162026-07-14T05:43:43.010ZJul 14 05:43:42.974 DEBG [sc] cr3 waiting for prompt
8172026-07-14T05:43:43.010ZJul 14 05:43:42.983 DEBG [sc] cr3: logging in
8182026-07-14T05:43:43.127ZJul 14 05:43:43.115 DEBG [sc] cr3: executing command `docker exec crpd1 cli -c 'show route fdee::/64 protocol bgp'`
8192026-07-14T05:43:45.303ZJul 14 05:43:45.291 INFO quartet bgp unnumbered test passed 🎉
8202026-07-14T05:43:45.327Z+ (( status != 0 ))
8212026-07-14T05:43:45.327Z+ pfexec ./falcon-lab cleanup quartet-unnumbered
8222026-07-14T05:43:45.351ZJul 14 05:43:45.331 INFO destroying runner for deployment mgquartetu
8232026-07-14T05:43:45.351ZJul 14 05:43:45.331 INFO destroying deployment mgquartetu
8242026-07-14T05:43:45.351ZJul 14 05:43:45.331 INFO destroying nodes
8252026-07-14T05:43:45.439ZJul 14 05:43:45.428 INFO destroying links
8262026-07-14T05:43:45.439ZJul 14 05:43:45.428 INFO destroying link mgquartetu_ox_sn_vnic0
8272026-07-14T05:43:45.463ZJul 14 05:43:45.432 INFO destroying link mgquartetu_ox_sn_sim0
8282026-07-14T05:43:45.463ZJul 14 05:43:45.433 INFO destroying link mgquartetu_cr1_vn_vnic0
8292026-07-14T05:43:46.448ZJul 14 05:43:46.436 INFO destroying link mgquartetu_cr1_vn_sim0
8302026-07-14T05:43:46.473ZJul 14 05:43:46.438 INFO destroying link mgquartetu_ox_sn_vnic1
8312026-07-14T05:43:46.473ZJul 14 05:43:46.439 INFO destroying link mgquartetu_ox_sn_sim1
8322026-07-14T05:43:46.473ZJul 14 05:43:46.440 INFO destroying link mgquartetu_cr2_vn_vnic0
8332026-07-14T05:43:46.473ZJul 14 05:43:46.441 INFO destroying link mgquartetu_cr2_vn_sim0
8342026-07-14T05:43:46.473ZJul 14 05:43:46.442 INFO destroying link mgquartetu_ox_sn_vnic2
8352026-07-14T05:43:46.473ZJul 14 05:43:46.443 INFO destroying link mgquartetu_ox_sn_sim2
8362026-07-14T05:43:46.473ZJul 14 05:43:46.444 INFO destroying link mgquartetu_cr3_vn_vnic0
8372026-07-14T05:43:46.473ZJul 14 05:43:46.445 INFO destroying link mgquartetu_cr3_vn_sim0
8382026-07-14T05:43:46.473ZJul 14 05:43:46.446 INFO destroying external links
8392026-07-14T05:43:46.473ZJul 14 05:43:46.446 INFO destroying external link mgquartetu_ox_vn_vnic3
8402026-07-14T05:43:46.473ZJul 14 05:43:46.447 INFO destroying external link mgquartetu_cr1_vn_vnic1
8412026-07-14T05:43:46.473ZJul 14 05:43:46.448 INFO destroying external link mgquartetu_cr2_vn_vnic1
8422026-07-14T05:43:46.473ZJul 14 05:43:46.449 INFO destroying external link mgquartetu_cr3_vn_vnic1
8432026-07-14T05:43:46.473ZJul 14 05:43:46.451 INFO destroying images
8442026-07-14T05:43:46.942ZJul 14 05:43:46.930 INFO destroying workspace at .falcon
8452026-07-14T05:43:46.966Z+ return 0
8462026-07-14T05:43:46.966Z+ run_test quartet-bfd-static-routing
8472026-07-14T05:43:46.966Z+ local test_name=quartet-bfd-static-routing
8482026-07-14T05:43:46.966Z+ local status=0
8492026-07-14T05:43:46.966Z+ clear_falcon_workspace_files
8502026-07-14T05:43:46.966Z+ [[ -d .falcon ]]
8512026-07-14T05:43:46.966Z+ find .falcon -maxdepth 1 -type f -exec rm -f '{}' +
8522026-07-14T05:43:46.966Z+ RUST_LOG=debug
8532026-07-14T05:43:46.966Z+ pfexec ./falcon-lab run --no-cleanup quartet-bfd-static-routing
8542026-07-14T05:43:46.990ZJul 14 05:43:46.962 DEBG using default route interface igb0
8552026-07-14T05:43:46.990ZJul 14 05:43:46.963 DEBG using default route interface igb0
8562026-07-14T05:43:46.990ZJul 14 05:43:46.963 DEBG using default route interface igb0
8572026-07-14T05:43:46.990ZJul 14 05:43:46.963 DEBG using default route interface igb0
8582026-07-14T05:43:46.990ZJul 14 05:43:46.963 INFO mgquartetbfd: launching quartet topology
8592026-07-14T05:43:46.990ZJul 14 05:43:46.963 INFO launching runner: deployment(mgquartetbfd) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
8602026-07-14T05:43:46.990ZJul 14 05:43:46.963 INFO starting preflight for deployment mgquartetbfd
8612026-07-14T05:43:47.684ZJul 14 05:43:47.672 INFO creating links
8622026-07-14T05:43:47.684ZJul 14 05:43:47.672 DEBG destroying link mgquartetbfd_ox_sn_vnic0
8632026-07-14T05:43:47.684ZJul 14 05:43:47.672 DEBG destroying link mgquartetbfd_ox_sn_sim0
8642026-07-14T05:43:47.684ZJul 14 05:43:47.672 INFO creating simnet link 'mgquartetbfd_ox_sn_sim0'
8652026-07-14T05:43:47.709ZJul 14 05:43:47.675 INFO creating vnic link 'mgquartetbfd_ox_sn_vnic0'
8662026-07-14T05:43:47.709ZJul 14 05:43:47.683 DEBG link pair created
8672026-07-14T05:43:47.709ZJul 14 05:43:47.683 DEBG destroying link mgquartetbfd_cr1_vn_vnic0
8682026-07-14T05:43:47.709ZJul 14 05:43:47.683 DEBG destroying link mgquartetbfd_cr1_vn_sim0
8692026-07-14T05:43:47.709ZJul 14 05:43:47.683 INFO creating simnet link 'mgquartetbfd_cr1_vn_sim0'
8702026-07-14T05:43:47.709ZJul 14 05:43:47.684 INFO creating vnic link 'mgquartetbfd_cr1_vn_vnic0'
8712026-07-14T05:43:47.709ZJul 14 05:43:47.691 DEBG link pair created
8722026-07-14T05:43:47.709ZJul 14 05:43:47.692 DEBG destroying link mgquartetbfd_ox_sn_vnic1
8732026-07-14T05:43:47.709ZJul 14 05:43:47.692 DEBG destroying link mgquartetbfd_ox_sn_sim1
8742026-07-14T05:43:47.709ZJul 14 05:43:47.692 INFO creating simnet link 'mgquartetbfd_ox_sn_sim1'
8752026-07-14T05:43:47.709ZJul 14 05:43:47.693 INFO creating vnic link 'mgquartetbfd_ox_sn_vnic1'
8762026-07-14T05:43:47.734ZJul 14 05:43:47.699 DEBG link pair created
8772026-07-14T05:43:47.734ZJul 14 05:43:47.699 DEBG destroying link mgquartetbfd_cr2_vn_vnic0
8782026-07-14T05:43:47.734ZJul 14 05:43:47.699 DEBG destroying link mgquartetbfd_cr2_vn_sim0
8792026-07-14T05:43:47.734ZJul 14 05:43:47.699 INFO creating simnet link 'mgquartetbfd_cr2_vn_sim0'
8802026-07-14T05:43:47.734ZJul 14 05:43:47.701 INFO creating vnic link 'mgquartetbfd_cr2_vn_vnic0'
8812026-07-14T05:43:47.734ZJul 14 05:43:47.708 DEBG link pair created
8822026-07-14T05:43:47.734ZJul 14 05:43:47.708 DEBG destroying link mgquartetbfd_ox_sn_vnic2
8832026-07-14T05:43:47.734ZJul 14 05:43:47.708 DEBG destroying link mgquartetbfd_ox_sn_sim2
8842026-07-14T05:43:47.734ZJul 14 05:43:47.708 INFO creating simnet link 'mgquartetbfd_ox_sn_sim2'
8852026-07-14T05:43:47.734ZJul 14 05:43:47.710 INFO creating vnic link 'mgquartetbfd_ox_sn_vnic2'
8862026-07-14T05:43:47.734ZJul 14 05:43:47.716 DEBG link pair created
8872026-07-14T05:43:47.734ZJul 14 05:43:47.716 DEBG destroying link mgquartetbfd_cr3_vn_vnic0
8882026-07-14T05:43:47.734ZJul 14 05:43:47.716 DEBG destroying link mgquartetbfd_cr3_vn_sim0
8892026-07-14T05:43:47.734ZJul 14 05:43:47.716 INFO creating simnet link 'mgquartetbfd_cr3_vn_sim0'
8902026-07-14T05:43:47.734ZJul 14 05:43:47.717 INFO creating vnic link 'mgquartetbfd_cr3_vn_vnic0'
8912026-07-14T05:43:47.759ZJul 14 05:43:47.724 DEBG link pair created
8922026-07-14T05:43:47.759ZJul 14 05:43:47.724 INFO creating external links
8932026-07-14T05:43:47.759ZJul 14 05:43:47.724 DEBG destroying external link mgquartetbfd_ox_vn_vnic3
8942026-07-14T05:43:47.759ZJul 14 05:43:47.724 INFO creating external link mgquartetbfd_ox_vn_vnic3
8952026-07-14T05:43:47.759ZJul 14 05:43:47.726 DEBG external link mgquartetbfd_ox_vn_vnic3@igb0 created
8962026-07-14T05:43:47.759ZJul 14 05:43:47.726 DEBG destroying external link mgquartetbfd_cr1_vn_vnic1
8972026-07-14T05:43:47.759ZJul 14 05:43:47.726 INFO creating external link mgquartetbfd_cr1_vn_vnic1
8982026-07-14T05:43:47.759ZJul 14 05:43:47.728 DEBG external link mgquartetbfd_cr1_vn_vnic1@igb0 created
8992026-07-14T05:43:47.759ZJul 14 05:43:47.728 DEBG destroying external link mgquartetbfd_cr2_vn_vnic1
9002026-07-14T05:43:47.759ZJul 14 05:43:47.728 INFO creating external link mgquartetbfd_cr2_vn_vnic1
9012026-07-14T05:43:47.759ZJul 14 05:43:47.729 DEBG external link mgquartetbfd_cr2_vn_vnic1@igb0 created
9022026-07-14T05:43:47.759ZJul 14 05:43:47.729 DEBG destroying external link mgquartetbfd_cr3_vn_vnic1
9032026-07-14T05:43:47.759ZJul 14 05:43:47.729 INFO creating external link mgquartetbfd_cr3_vn_vnic1
9042026-07-14T05:43:47.759ZJul 14 05:43:47.730 DEBG external link mgquartetbfd_cr3_vn_vnic1@igb0 created
9052026-07-14T05:43:47.759ZJul 14 05:43:47.730 INFO creating nodes
9062026-07-14T05:43:47.759ZJul 14 05:43:47.730 INFO ox: launching node
9072026-07-14T05:43:47.759ZJul 14 05:43:47.733 INFO cr1: launching node
9082026-07-14T05:43:47.759ZJul 14 05:43:47.735 INFO cr2: launching node
9092026-07-14T05:43:47.759ZJul 14 05:43:47.737 INFO cr3: launching node
9102026-07-14T05:43:47.784ZJul 14 05:43:47.750 INFO launched instance ox with pid 967 on port 65351
9112026-07-14T05:43:47.784ZJul 14 05:43:47.751 INFO ox: instance ensure
9122026-07-14T05:43:47.784ZJul 14 05:43:47.751 INFO launched instance cr1 with pid 968 on port 45634
9132026-07-14T05:43:47.784ZJul 14 05:43:47.751 INFO cr1: instance ensure
9142026-07-14T05:43:47.784ZJul 14 05:43:47.751 INFO launched instance cr2 with pid 969 on port 57860
9152026-07-14T05:43:47.784ZJul 14 05:43:47.751 INFO cr2: instance ensure
9162026-07-14T05:43:47.784ZJul 14 05:43:47.752 INFO launched instance cr3 with pid 970 on port 41324
9172026-07-14T05:43:47.784ZJul 14 05:43:47.752 INFO cr3: instance ensure
9182026-07-14T05:43:50.745ZJul 14 05:43:50.732 INFO cr3: instance ensure completed after 0 retries
9192026-07-14T05:43:50.745ZJul 14 05:43:50.733 INFO cr3: instance run
9202026-07-14T05:43:50.855ZJul 14 05:43:50.843 INFO cr1: instance ensure completed after 0 retries
9212026-07-14T05:43:50.855ZJul 14 05:43:50.843 INFO cr1: instance run
9222026-07-14T05:43:50.879ZJul 14 05:43:50.843 DEBG [sc] cr1: starting
9232026-07-14T05:43:50.879ZJul 14 05:43:50.843 DEBG [sc] cr1: connecting to [::1]:45634
9242026-07-14T05:43:50.879ZJul 14 05:43:50.844 DEBG [sc] cr1 waiting for prompt
9252026-07-14T05:43:50.904ZJul 14 05:43:50.871 INFO ox: instance ensure completed after 0 retries
9262026-07-14T05:43:50.904ZJul 14 05:43:50.871 INFO ox: instance run
9272026-07-14T05:43:50.904ZJul 14 05:43:50.872 DEBG [sc] ox: starting
9282026-07-14T05:43:50.904ZJul 14 05:43:50.872 DEBG [sc] ox: connecting to [::1]:65351
9292026-07-14T05:43:50.904ZJul 14 05:43:50.873 DEBG [sc] ox waiting for prompt
9302026-07-14T05:43:50.929ZJul 14 05:43:50.907 INFO cr2: instance ensure completed after 0 retries
9312026-07-14T05:43:50.929ZJul 14 05:43:50.907 INFO cr2: instance run
9322026-07-14T05:43:50.929ZJul 14 05:43:50.908 DEBG [sc] cr2: starting
9332026-07-14T05:43:50.929ZJul 14 05:43:50.908 DEBG [sc] cr2: connecting to [::1]:57860
9342026-07-14T05:43:50.929ZJul 14 05:43:50.909 DEBG [sc] cr2 waiting for prompt
9352026-07-14T05:44:07.955ZJul 14 05:44:07.943 DEBG [sc] cr1: logging in
9362026-07-14T05:44:10.781ZJul 14 05:44:10.769 DEBG [sc] ox: logging in
9372026-07-14T05:44:10.993ZJul 14 05:44:10.981 INFO ox: mounting /opt/cargo-bay
9382026-07-14T05:44:10.993ZJul 14 05:44:10.981 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
9392026-07-14T05:44:12.564ZJul 14 05:44:12.552 DEBG [sc] ox: executing command `cd`
9402026-07-14T05:44:12.588ZJul 14 05:44:12.564 INFO ox: finished mounting /opt/cargo-bay
9412026-07-14T05:44:12.588ZJul 14 05:44:12.564 DEBG [sc] ox: executing command `hostname ox`
9422026-07-14T05:44:12.588ZJul 14 05:44:12.575 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
9432026-07-14T05:44:12.612ZJul 14 05:44:12.586 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
9442026-07-14T05:44:12.612ZJul 14 05:44:12.596 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
9452026-07-14T05:44:12.636ZJul 14 05:44:12.607 INFO ox: logging out
9462026-07-14T05:44:12.685ZJul 14 05:44:12.674 INFO ox: logged out
9472026-07-14T05:44:12.734ZJul 14 05:44:12.722 DEBG [sc] cr2: logging in
9482026-07-14T05:44:13.223ZJul 14 05:44:13.211 INFO cr2: mounting /opt/cargo-bay
9492026-07-14T05:44:13.223ZJul 14 05:44:13.211 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
9502026-07-14T05:44:13.247ZJul 14 05:44:13.232 DEBG [sc] cr2: executing command `cd`
9512026-07-14T05:44:13.271ZJul 14 05:44:13.243 INFO cr2: finished mounting /opt/cargo-bay
9522026-07-14T05:44:13.271ZJul 14 05:44:13.243 DEBG [sc] cr2: executing command `hostname cr2`
9532026-07-14T05:44:13.271ZJul 14 05:44:13.255 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
9542026-07-14T05:44:13.295ZJul 14 05:44:13.266 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
9552026-07-14T05:44:13.295ZJul 14 05:44:13.277 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
9562026-07-14T05:44:13.319ZJul 14 05:44:13.288 INFO cr2: logging out
9572026-07-14T05:44:16.211ZJul 14 05:44:16.199 INFO cr2: logged out
9582026-07-14T05:44:18.593ZJul 14 05:44:18.582 ERRO launch failed: exec: [sc] cr1: timeout waiting for data
9592026-07-14T05:44:18.618ZError: launch failed
9602026-07-14T05:44:18.618Z
9612026-07-14T05:44:18.618ZCaused by:
9622026-07-14T05:44:18.618Z exec: [sc] cr1: timeout waiting for data
9632026-07-14T05:44:18.618Z+ status=1
9642026-07-14T05:44:18.618Z+ (( status != 0 ))
9652026-07-14T05:44:18.618Z+ collect_falcon_artifacts quartet-bfd-static-routing
9662026-07-14T05:44:18.618Z+ local test_name=quartet-bfd-static-routing
9672026-07-14T05:44:18.618Z+ local artifact_dir=/work/falcon-quartet-bfd-static-routing
9682026-07-14T05:44:18.618Z+ local path
9692026-07-14T05:44:18.618Z+ [[ ! -d .falcon ]]
9702026-07-14T05:44:18.618Z+ rm -rf /work/falcon-quartet-bfd-static-routing
9712026-07-14T05:44:18.619Z+ mkdir -p /work/falcon-quartet-bfd-static-routing
9722026-07-14T05:44:18.619Z+ for path in .falcon/*.{out,err}
9732026-07-14T05:44:18.619Z+ [[ -e .falcon/cr1.out ]]
9742026-07-14T05:44:18.619Z+ cp -p .falcon/cr1.out /work/falcon-quartet-bfd-static-routing/
9752026-07-14T05:44:18.643Z+ for path in .falcon/*.{out,err}
9762026-07-14T05:44:18.643Z+ [[ -e .falcon/cr2.out ]]
9772026-07-14T05:44:18.643Z+ cp -p .falcon/cr2.out /work/falcon-quartet-bfd-static-routing/
9782026-07-14T05:44:18.643Z+ for path in .falcon/*.{out,err}
9792026-07-14T05:44:18.643Z+ [[ -e .falcon/cr3.out ]]
9802026-07-14T05:44:18.643Z+ cp -p .falcon/cr3.out /work/falcon-quartet-bfd-static-routing/
9812026-07-14T05:44:18.643Z+ for path in .falcon/*.{out,err}
9822026-07-14T05:44:18.643Z+ [[ -e .falcon/ox.out ]]
9832026-07-14T05:44:18.643Z+ cp -p .falcon/ox.out /work/falcon-quartet-bfd-static-routing/
9842026-07-14T05:44:18.643Z+ for path in .falcon/*.{out,err}
9852026-07-14T05:44:18.643Z+ [[ -e .falcon/cr1.err ]]
9862026-07-14T05:44:18.643Z+ cp -p .falcon/cr1.err /work/falcon-quartet-bfd-static-routing/
9872026-07-14T05:44:18.643Z+ for path in .falcon/*.{out,err}
9882026-07-14T05:44:18.643Z+ [[ -e .falcon/cr2.err ]]
9892026-07-14T05:44:18.644Z+ cp -p .falcon/cr2.err /work/falcon-quartet-bfd-static-routing/
9902026-07-14T05:44:18.644Z+ for path in .falcon/*.{out,err}
9912026-07-14T05:44:18.644Z+ [[ -e .falcon/cr3.err ]]
9922026-07-14T05:44:18.644Z+ cp -p .falcon/cr3.err /work/falcon-quartet-bfd-static-routing/
9932026-07-14T05:44:18.669Z+ for path in .falcon/*.{out,err}
9942026-07-14T05:44:18.669Z+ [[ -e .falcon/ox.err ]]
9952026-07-14T05:44:18.669Z+ cp -p .falcon/ox.err /work/falcon-quartet-bfd-static-routing/
9962026-07-14T05:44:18.669Z+ tar cvfz /work/falcon-quartet-bfd-static-routing.tar.gz -C /work falcon-quartet-bfd-static-routing
9972026-07-14T05:44:18.669ZCompressing '/work/falcon-quartet-bfd-static-routing.tar.gz' with '/usr/bin/gzip'...
9982026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/ 0K
9992026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/ox.out 53K
10002026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/cr3.err 1K
10012026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/cr1.out 51K
10022026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/cr2.err 1K
10032026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/ox.err 1K
10042026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/cr3.out 51K
10052026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/cr1.err 1K
10062026-07-14T05:44:18.669Za falcon-quartet-bfd-static-routing/cr2.out 51K
10072026-07-14T05:44:18.669Z+ rm -rf /work/falcon-quartet-bfd-static-routing
10082026-07-14T05:44:18.669Z+ pfexec ./falcon-lab cleanup quartet-bfd-static-routing
10092026-07-14T05:44:18.694ZJul 14 05:44:18.673 INFO destroying runner for deployment mgquartetbfd
10102026-07-14T05:44:18.694ZJul 14 05:44:18.673 INFO destroying deployment mgquartetbfd
10112026-07-14T05:44:18.694ZJul 14 05:44:18.673 INFO destroying nodes
10122026-07-14T05:44:18.761ZJul 14 05:44:18.750 INFO destroying links
10132026-07-14T05:44:18.761ZJul 14 05:44:18.750 INFO destroying link mgquartetbfd_ox_sn_vnic0
10142026-07-14T05:44:18.785ZJul 14 05:44:18.754 INFO destroying link mgquartetbfd_ox_sn_sim0
10152026-07-14T05:44:18.785ZJul 14 05:44:18.755 INFO destroying link mgquartetbfd_cr1_vn_vnic0
10162026-07-14T05:44:19.769ZJul 14 05:44:19.758 INFO destroying link mgquartetbfd_cr1_vn_sim0
10172026-07-14T05:44:19.793ZJul 14 05:44:19.759 INFO destroying link mgquartetbfd_ox_sn_vnic1
10182026-07-14T05:44:19.793ZJul 14 05:44:19.760 INFO destroying link mgquartetbfd_ox_sn_sim1
10192026-07-14T05:44:19.793ZJul 14 05:44:19.761 INFO destroying link mgquartetbfd_cr2_vn_vnic0
10202026-07-14T05:44:19.794ZJul 14 05:44:19.762 INFO destroying link mgquartetbfd_cr2_vn_sim0
10212026-07-14T05:44:19.794ZJul 14 05:44:19.763 INFO destroying link mgquartetbfd_ox_sn_vnic2
10222026-07-14T05:44:19.794ZJul 14 05:44:19.764 INFO destroying link mgquartetbfd_ox_sn_sim2
10232026-07-14T05:44:19.794ZJul 14 05:44:19.765 INFO destroying link mgquartetbfd_cr3_vn_vnic0
10242026-07-14T05:44:19.794ZJul 14 05:44:19.766 INFO destroying link mgquartetbfd_cr3_vn_sim0
10252026-07-14T05:44:19.794ZJul 14 05:44:19.767 INFO destroying external links
10262026-07-14T05:44:19.794ZJul 14 05:44:19.767 INFO destroying external link mgquartetbfd_ox_vn_vnic3
10272026-07-14T05:44:19.794ZJul 14 05:44:19.768 INFO destroying external link mgquartetbfd_cr1_vn_vnic1
10282026-07-14T05:44:19.794ZJul 14 05:44:19.769 INFO destroying external link mgquartetbfd_cr2_vn_vnic1
10292026-07-14T05:44:19.794ZJul 14 05:44:19.771 INFO destroying external link mgquartetbfd_cr3_vn_vnic1
10302026-07-14T05:44:19.794ZJul 14 05:44:19.772 INFO destroying images
10312026-07-14T05:44:20.016ZJul 14 05:44:20.004 INFO destroying workspace at .falcon
10322026-07-14T05:44:20.040Z+ return 1
10332026-07-14T05:44:20.040Zprocess exited: duration 608058 ms, exit code 1
 
10342026-07-14T05:44:20.091Zfound 2 output files
10352026-07-14T05:44:20.118Zuploading: /work/dhcp-server.log (34983 bytes)
10362026-07-14T05:44:21.232Zuploaded: /work/dhcp-server.log
10372026-07-14T05:44:21.232Zuploading: /work/falcon-quartet-bfd-static-routing.tar.gz (18933 bytes)
10382026-07-14T05:44:22.369Zuploaded: /work/falcon-quartet-bfd-static-routing.tar.gz