01M5E8B4P9WR6YSJRHZHMEJB8Z: falcon

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

Buildomat Job: 01M5E8CA3EF8PS5V8BBGEQ4S3P

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-10-21T03:48:39.752Zjob dependencies complete; ready to run (waiting for 14 m 21 s)
22026-10-21T03:51:57.806Zjob assigned to worker 01M5E96KQE4JGVYQ1BN3Y98FNV [factory edgar, gimlet/BRM42220010/1790] (queued for 3 m 18 s)
32026-10-21T03:51:58.808Zdownloading input: /input/build-dhcp-server/work/dhcp-server
42026-10-21T03:51:59.166Zdownloaded input: /input/build-dhcp-server/work/dhcp-server
52026-10-21T03:51:59.190Zdownloading input: /input/build/work/debug/ddmadm
62026-10-21T03:52:26.382Zdownloaded input: /input/build/work/debug/ddmadm
72026-10-21T03:52:26.382Zdownloading input: /input/build/work/debug/ddmd
82026-10-21T03:52:55.132Zdownloaded input: /input/build/work/debug/ddmd
92026-10-21T03:52:55.132Zdownloading input: /input/build/work/debug/mgadm
102026-10-21T03:53:28.126Zdownloaded input: /input/build/work/debug/mgadm
112026-10-21T03:53:28.126Zdownloading input: /input/build/work/debug/mgd
122026-10-21T03:54:06.927Zdownloaded input: /input/build/work/debug/mgd
132026-10-21T03:54:06.951Zdownloading input: /input/build/work/release/ddmadm
142026-10-21T03:54:09.098Zdownloaded input: /input/build/work/release/ddmadm
152026-10-21T03:54:09.121Zdownloading input: /input/build/work/release/ddmd
162026-10-21T03:54:11.899Zdownloaded input: /input/build/work/release/ddmd
172026-10-21T03:54:11.899Zdownloading input: /input/build/work/release/falcon-lab
182026-10-21T03:54:14.063Zdownloaded input: /input/build/work/release/falcon-lab
192026-10-21T03:54:14.087Zdownloading input: /input/build/work/release/mgadm
202026-10-21T03:54:16.718Zdownloaded input: /input/build/work/release/mgadm
212026-10-21T03:54:16.718Zdownloading input: /input/build/work/release/mgd
222026-10-21T03:54:21.035Zdownloaded input: /input/build/work/release/mgd
 
232026-10-21T03:54:21.035Zstarting task 0: "setup"
242026-10-21T03:54:21.058Z++ uname -s
252026-10-21T03:54:21.058Z+ kern=SunOS
262026-10-21T03:54:21.058Z+ build_user=build
272026-10-21T03:54:21.059Z+ build_uid=12345
282026-10-21T03:54:21.059Z+ work_dir=/work
292026-10-21T03:54:21.059Z+ input_dir=/input
302026-10-21T03:54:21.059Z+ [[ 0 == 12345 ]]
312026-10-21T03:54:21.059Z+ case "$kern" in
322026-10-21T03:54:21.059Z+ groupadd -g 12345 build
332026-10-21T03:54:21.081Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
342026-10-21T03:54:23.070Z+ zfs create -o mountpoint=/work rpool/work
352026-10-21T03:54:23.708Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
362026-10-21T03:54:23.731Z+ home_fs=
372026-10-21T03:54:23.731Z+ [[ '' == autofs ]]
382026-10-21T03:54:23.731Z+ mkdir -p /home/build
392026-10-21T03:54:23.731Z+ chown build:build /home/build /work
402026-10-21T03:54:25.720Z+ chmod 0700 /home/build /work
412026-10-21T03:54:25.744Zprocess exited: duration 4688 ms, exit code 0
 
422026-10-21T03:54:25.790Zstarting task 1: "authentication"
432026-10-21T03:54:25.887Zprocess exited: duration 96 ms, exit code 0
 
442026-10-21T03:54:25.934Zstarting task 2: "build"
452026-10-21T03:54:25.958Z+ set -e
462026-10-21T03:54:25.958Z+ set -o pipefail
472026-10-21T03:54:25.958Z+ banner zpool
482026-10-21T03:54:25.958Z
492026-10-21T03:54:25.958Z ###### ##### #### #### #
502026-10-21T03:54:25.958Z # # # # # # # #
512026-10-21T03:54:25.958Z # # # # # # # #
522026-10-21T03:54:25.958Z # ##### # # # # #
532026-10-21T03:54:25.958Z # # # # # # #
542026-10-21T03:54:25.958Z ###### # #### #### ######
552026-10-21T03:54:25.958Z
562026-10-21T03:54:25.958Z++ pfexec diskinfo -pH
572026-10-21T03:54:25.958Z++ sort -k8 -n -r
582026-10-21T03:54:25.982Z++ head -1
592026-10-21T03:54:25.982Z++ awk '{print $2}'
602026-10-21T03:54:26.012Z+ DISK=c9t0014EE81000BC3B1d0
612026-10-21T03:54:26.012Z+ export DISK
622026-10-21T03:54:26.036Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0
632026-10-21T03:54:26.337Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
642026-10-21T03:54:26.363Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
652026-10-21T03:54:26.421Z+ trim_response=true
662026-10-21T03:54:26.421Z+ [[ true =~ true ]]
672026-10-21T03:54:26.444Z+ pfexec zpool trim cpool
682026-10-21T03:54:26.444Z+ true
692026-10-21T03:54:26.444Z++ zpool status -t cpool
702026-10-21T03:54:26.467Z+ trim_status=' pool: cpool
712026-10-21T03:54:26.468Z state: ONLINE
722026-10-21T03:54:26.468Z scan: none requested
732026-10-21T03:54:26.468Zconfig:
742026-10-21T03:54:26.468Z
752026-10-21T03:54:26.468Z NAME STATE READ WRITE CKSUM
762026-10-21T03:54:26.468Z cpool ONLINE 0 0 0
772026-10-21T03:54:26.468Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at October 21, 2026 at 03:54:26 AM UTC)
782026-10-21T03:54:26.468Z
792026-10-21T03:54:26.468Zerrors: No known data errors'
802026-10-21T03:54:26.468Z+ [[ pool: cpool
812026-10-21T03:54:26.468Z state: ONLINE
822026-10-21T03:54:26.468Z scan: none requested
832026-10-21T03:54:26.468Zconfig:
842026-10-21T03:54:26.468Z
852026-10-21T03:54:26.468Z NAME STATE READ WRITE CKSUM
862026-10-21T03:54:26.468Z cpool ONLINE 0 0 0
872026-10-21T03:54:26.468Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (1% trimmed, started at October 21, 2026 at 03:54:26 AM UTC)
882026-10-21T03:54:26.468Z
892026-10-21T03:54:26.468Zerrors: No known data errors =~ 100% ]]
902026-10-21T03:54:26.468Z+ sleep 10
912026-10-21T03:54:36.453Z+ true
922026-10-21T03:54:36.476Z++ zpool status -t cpool
932026-10-21T03:54:36.476Z+ trim_status=' pool: cpool
942026-10-21T03:54:36.476Z state: ONLINE
952026-10-21T03:54:36.476Z scan: none requested
962026-10-21T03:54:36.476Zconfig:
972026-10-21T03:54:36.476Z
982026-10-21T03:54:36.476Z NAME STATE READ WRITE CKSUM
992026-10-21T03:54:36.476Z cpool ONLINE 0 0 0
1002026-10-21T03:54:36.476Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at October 21, 2026 at 03:54:26 AM UTC)
1012026-10-21T03:54:36.476Z
1022026-10-21T03:54:36.476Zerrors: No known data errors'
1032026-10-21T03:54:36.476Z+ [[ pool: cpool
1042026-10-21T03:54:36.476Z state: ONLINE
1052026-10-21T03:54:36.476Z scan: none requested
1062026-10-21T03:54:36.477Zconfig:
1072026-10-21T03:54:36.477Z
1082026-10-21T03:54:36.477Z NAME STATE READ WRITE CKSUM
1092026-10-21T03:54:36.477Z cpool ONLINE 0 0 0
1102026-10-21T03:54:36.477Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (69% trimmed, started at October 21, 2026 at 03:54:26 AM UTC)
1112026-10-21T03:54:36.477Z
1122026-10-21T03:54:36.477Zerrors: No known data errors =~ 100% ]]
1132026-10-21T03:54:36.477Z+ sleep 10
1142026-10-21T03:54:46.467Z+ true
1152026-10-21T03:54:46.490Z++ zpool status -t cpool
1162026-10-21T03:54:46.490Z+ trim_status=' pool: cpool
1172026-10-21T03:54:46.490Z state: ONLINE
1182026-10-21T03:54:46.490Z scan: none requested
1192026-10-21T03:54:46.490Zconfig:
1202026-10-21T03:54:46.490Z
1212026-10-21T03:54:46.490Z NAME STATE READ WRITE CKSUM
1222026-10-21T03:54:46.490Z cpool ONLINE 0 0 0
1232026-10-21T03:54:46.490Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at October 21, 2026 at 03:54:41 AM UTC)
1242026-10-21T03:54:46.490Z
1252026-10-21T03:54:46.490Zerrors: No known data errors'
1262026-10-21T03:54:46.490Z+ [[ pool: cpool
1272026-10-21T03:54:46.490Z state: ONLINE
1282026-10-21T03:54:46.490Z scan: none requested
1292026-10-21T03:54:46.490Zconfig:
1302026-10-21T03:54:46.490Z
1312026-10-21T03:54:46.491Z NAME STATE READ WRITE CKSUM
1322026-10-21T03:54:46.491Z cpool ONLINE 0 0 0
1332026-10-21T03:54:46.491Z c9t0014EE81000BC3B1d0 ONLINE 0 0 0 (100% trimmed, completed at October 21, 2026 at 03:54:41 AM UTC)
1342026-10-21T03:54:46.491Z
1352026-10-21T03:54:46.491Zerrors: No known data errors =~ 100% ]]
1362026-10-21T03:54:46.491Z+ break
1372026-10-21T03:54:46.491Z+ pfexec chown 12345 /ci
1382026-10-21T03:54:46.491Z+ cd /ci
1392026-10-21T03:54:46.491Z+ export FALCON_DATASET=cpool/falcon
1402026-10-21T03:54:46.491Z+ FALCON_DATASET=cpool/falcon
1412026-10-21T03:54:46.491Z+ banner setup
1422026-10-21T03:54:46.491Z
1432026-10-21T03:54:46.491Z #### ###### ##### # # #####
1442026-10-21T03:54:46.491Z # # # # # # #
1452026-10-21T03:54:46.491Z #### ##### # # # # #
1462026-10-21T03:54:46.491Z # # # # # #####
1472026-10-21T03:54:46.491Z # # # # # # #
1482026-10-21T03:54:46.491Z #### ###### # #### #
1492026-10-21T03:54:46.491Z
1502026-10-21T03:54:46.491Z+ cp /input/build-dhcp-server/work/dhcp-server .
1512026-10-21T03:54:46.514Z+ cp /input/build/work/release/falcon-lab .
1522026-10-21T03:54:46.537Z+ cp /input/build/work/release/mgd .
1532026-10-21T03:54:46.607Z+ cp /input/build/work/release/ddmd .
1542026-10-21T03:54:46.672Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1552026-10-21T03:54:46.695Z+ mkdir -p cargo-bay
1562026-10-21T03:54:46.695Z+ mv mgd cargo-bay/
1572026-10-21T03:54:46.695Z+ mv ddmd cargo-bay/
1582026-10-21T03:54:46.695Z+ curl -sSfL --retry 10 --retry-all-errors -o cargo-bay/falcon-juniper-license.key http://catacomb.eng.oxide.computer:12346/falcon/jl
1592026-10-21T03:54:46.739Z+ chmod 0600 cargo-bay/falcon-juniper-license.key
1602026-10-21T03:54:46.762Z+ export EXT_INTERFACE=igb0
1612026-10-21T03:54:46.762Z+ EXT_INTERFACE=igb0
1622026-10-21T03:54:46.762Z++ bmat address ls -f extra -Ho first
1632026-10-21T03:54:46.785Z+ first=10.151.6.164
1642026-10-21T03:54:46.785Z++ bmat address ls -f extra -Ho last
1652026-10-21T03:54:46.808Z+ last=10.151.6.227
1662026-10-21T03:54:46.808Z++ bmat address ls -f extra -Ho gateway
1672026-10-21T03:54:46.831Z+ gw=10.151.6.1
1682026-10-21T03:54:46.831Z++ ipadm show-addr igb0/dhcp -po ADDR
1692026-10-21T03:54:46.831Z++ sed 's#/.*##g'
1702026-10-21T03:54:46.831Z+ server=10.151.6.100
1712026-10-21T03:54:46.831Z+ run_test mgd-duo bgp-unnumbered
1722026-10-21T03:54:46.831Z+ pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100
1732026-10-21T03:54:46.831Z+ local topology=mgd-duo
1742026-10-21T03:54:46.831Z+ local scenario=bgp-unnumbered
1752026-10-21T03:54:46.831Z+ local test_name=mgd-duo-bgp-unnumbered
1762026-10-21T03:54:46.831Z+ local status=0
1772026-10-21T03:54:46.831Z+ clear_falcon_workspace_files
1782026-10-21T03:54:46.831Z+ [[ -d .falcon ]]
1792026-10-21T03:54:46.831Z+ RUST_LOG=debug
1802026-10-21T03:54:46.831Z+ pfexec ./falcon-lab run mgd-duo bgp-unnumbered --no-cleanup
1812026-10-21T03:54:46.854ZOct 21 03:54:46.848 DEBG using default route interface igb0
1822026-10-21T03:54:46.854ZOct 21 03:54:46.848 DEBG using default route interface igb0
1832026-10-21T03:54:46.854ZOct 21 03:54:46.848 INFO launching runner: deployment(mgdduo_bgpu) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1842026-10-21T03:54:46.854ZOct 21 03:54:46.848 INFO starting preflight for deployment mgdduo_bgpu
1852026-10-21T03:54:46.854ZOct 21 03:54:46.848 INFO propolis-server binary not found
1862026-10-21T03:54:46.854ZOct 21 03:54:46.848 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1872026-10-21T03:54:50.496ZOct 21 03:54:50.500 INFO ovmf fd not found
1882026-10-21T03:54:50.496ZOct 21 03:54:50.500 INFO downloading ovmf
1892026-10-21T03:54:50.867ZOct 21 03:54:50.871 INFO base image for helios-3.0 does not exist, attempting to install
1902026-10-21T03:54:50.867ZOct 21 03:54:50.871 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-3.0_0.raw.xz
1912026-10-21T03:55:18.195ZOct 21 03:55:18.205 INFO extracting image to /tmp/helios-3.0_0.raw
1922026-10-21T03:55:41.026ZOct 21 03:55:41.038 INFO creating zvol cpool/falcon/img/helios-3.0 of size 4508880896
1932026-10-21T03:55:41.074ZOct 21 03:55:41.087 INFO copying image data to zvol
1942026-10-21T03:55:51.506ZOct 21 03:55:51.521 INFO creating zfs snapshot cpool/falcon/img/helios-3.0@base
1952026-10-21T03:55:54.959ZOct 21 03:55:54.975 INFO creating links
1962026-10-21T03:55:54.960ZOct 21 03:55:54.975 DEBG destroying link mgdduo_bgpu_ox1_vn_vnic0
1972026-10-21T03:55:54.960ZOct 21 03:55:54.975 DEBG destroying link mgdduo_bgpu_ox1_vn_sim0
1982026-10-21T03:55:54.960ZOct 21 03:55:54.975 INFO creating simnet link 'mgdduo_bgpu_ox1_vn_sim0'
1992026-10-21T03:55:54.983ZOct 21 03:55:54.977 INFO creating vnic link 'mgdduo_bgpu_ox1_vn_vnic0'
2002026-10-21T03:55:54.983ZOct 21 03:55:54.984 DEBG link pair created
2012026-10-21T03:55:54.983ZOct 21 03:55:54.984 DEBG destroying link mgdduo_bgpu_ox2_vn_vnic0
2022026-10-21T03:55:54.983ZOct 21 03:55:54.984 DEBG destroying link mgdduo_bgpu_ox2_vn_sim0
2032026-10-21T03:55:54.983ZOct 21 03:55:54.984 INFO creating simnet link 'mgdduo_bgpu_ox2_vn_sim0'
2042026-10-21T03:55:54.983ZOct 21 03:55:54.986 INFO creating vnic link 'mgdduo_bgpu_ox2_vn_vnic0'
2052026-10-21T03:55:54.983ZOct 21 03:55:54.992 DEBG link pair created
2062026-10-21T03:55:54.983ZOct 21 03:55:54.993 INFO creating external links
2072026-10-21T03:55:54.983ZOct 21 03:55:54.993 DEBG destroying external link mgdduo_bgpu_ox1_vn_vnic1
2082026-10-21T03:55:54.983ZOct 21 03:55:54.993 INFO creating external link mgdduo_bgpu_ox1_vn_vnic1
2092026-10-21T03:55:54.983ZOct 21 03:55:54.995 DEBG external link mgdduo_bgpu_ox1_vn_vnic1@igb0 created
2102026-10-21T03:55:54.983ZOct 21 03:55:54.995 DEBG destroying external link mgdduo_bgpu_ox2_vn_vnic1
2112026-10-21T03:55:54.983ZOct 21 03:55:54.995 INFO creating external link mgdduo_bgpu_ox2_vn_vnic1
2122026-10-21T03:55:54.983ZOct 21 03:55:54.997 DEBG external link mgdduo_bgpu_ox2_vn_vnic1@igb0 created
2132026-10-21T03:55:54.983ZOct 21 03:55:54.997 INFO creating nodes
2142026-10-21T03:55:54.983ZOct 21 03:55:54.997 INFO ox1: launching node
2152026-10-21T03:55:55.006ZOct 21 03:55:54.999 INFO ox2: launching node
2162026-10-21T03:55:55.029ZOct 21 03:55:55.024 INFO launched instance ox1 with pid 863 on port 55975
2172026-10-21T03:55:55.029ZOct 21 03:55:55.024 INFO ox1: instance ensure
2182026-10-21T03:55:55.029ZOct 21 03:55:55.025 INFO launched instance ox2 with pid 864 on port 39991
2192026-10-21T03:55:55.029ZOct 21 03:55:55.025 INFO ox2: instance ensure
2202026-10-21T03:55:56.826ZOct 21 03:55:56.841 INFO ox2: instance ensure completed after 0 retries
2212026-10-21T03:55:56.826ZOct 21 03:55:56.841 INFO ox2: instance run
2222026-10-21T03:55:56.849ZOct 21 03:55:56.842 DEBG [sc] ox2: starting
2232026-10-21T03:55:56.849ZOct 21 03:55:56.842 DEBG [sc] ox2: connecting to [::1]:39991
2242026-10-21T03:55:56.849ZOct 21 03:55:56.843 INFO ox1: instance ensure completed after 0 retries
2252026-10-21T03:55:56.849ZOct 21 03:55:56.843 INFO ox1: instance run
2262026-10-21T03:55:56.849ZOct 21 03:55:56.843 DEBG [sc] ox2 waiting for prompt
2272026-10-21T03:55:56.849ZOct 21 03:55:56.844 DEBG [sc] ox1: starting
2282026-10-21T03:55:56.849ZOct 21 03:55:56.844 DEBG [sc] ox1: connecting to [::1]:55975
2292026-10-21T03:55:56.849ZOct 21 03:55:56.844 DEBG [sc] ox1 waiting for prompt
2302026-10-21T03:56:17.420ZOct 21 03:56:17.412 DEBG [sc] ox1: logging in
2312026-10-21T03:56:17.444ZOct 21 03:56:17.416 DEBG [sc] ox2: logging in
2322026-10-21T03:56:17.784ZOct 21 03:56:17.776 INFO ox2: mounting /opt/cargo-bay
2332026-10-21T03:56:17.784ZOct 21 03:56:17.776 DEBG [sc] ox2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-10-21T03:56:17.807ZOct 21 03:56:17.787 INFO ox1: mounting /opt/cargo-bay
2352026-10-21T03:56:17.807ZOct 21 03:56:17.787 DEBG [sc] ox1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2362026-10-21T03:56:19.003ZOct 21 03:56:18.995 DEBG [sc] ox1: executing command `cd`
2372026-10-21T03:56:19.026ZOct 21 03:56:19.005 INFO ox1: finished mounting /opt/cargo-bay
2382026-10-21T03:56:19.026ZOct 21 03:56:19.005 DEBG [sc] ox1: executing command `hostname ox1`
2392026-10-21T03:56:19.026ZOct 21 03:56:19.017 DEBG [sc] ox1: executing command `echo 'ox1' > /etc/nodename`
2402026-10-21T03:56:19.050ZOct 21 03:56:19.028 DEBG [sc] ox1: executing command `echo '::1 ox1.local ox1' >> /etc/hosts`
2412026-10-21T03:56:19.050ZOct 21 03:56:19.039 DEBG [sc] ox1: executing command `echo '127.0.0.1 ox1.local ox1' >> /etc/hosts`
2422026-10-21T03:56:19.050ZOct 21 03:56:19.040 DEBG [sc] ox2: executing command `cd`
2432026-10-21T03:56:19.073ZOct 21 03:56:19.050 INFO ox1: logging out
2442026-10-21T03:56:19.073ZOct 21 03:56:19.051 INFO ox2: finished mounting /opt/cargo-bay
2452026-10-21T03:56:19.073ZOct 21 03:56:19.051 DEBG [sc] ox2: executing command `hostname ox2`
2462026-10-21T03:56:19.073ZOct 21 03:56:19.062 DEBG [sc] ox2: executing command `echo 'ox2' > /etc/nodename`
2472026-10-21T03:56:19.096ZOct 21 03:56:19.073 DEBG [sc] ox2: executing command `echo '::1 ox2.local ox2' >> /etc/hosts`
2482026-10-21T03:56:19.097ZOct 21 03:56:19.084 DEBG [sc] ox2: executing command `echo '127.0.0.1 ox2.local ox2' >> /etc/hosts`
2492026-10-21T03:56:19.119ZOct 21 03:56:19.095 INFO ox2: logging out
2502026-10-21T03:56:19.145ZOct 21 03:56:19.138 INFO ox1: logged out
2512026-10-21T03:56:19.169ZOct 21 03:56:19.161 INFO ox2: logged out
2522026-10-21T03:56:19.192ZOct 21 03:56:19.162 DEBG [sc] ox1: starting
2532026-10-21T03:56:19.192ZOct 21 03:56:19.162 DEBG [sc] ox1: connecting to [::1]:55975
2542026-10-21T03:56:19.192ZOct 21 03:56:19.162 DEBG [sc] ox2: starting
2552026-10-21T03:56:19.192ZOct 21 03:56:19.162 DEBG [sc] ox2: connecting to [::1]:39991
2562026-10-21T03:56:19.192ZOct 21 03:56:19.162 DEBG [sc] ox2 waiting for prompt
2572026-10-21T03:56:19.192ZOct 21 03:56:19.162 DEBG [sc] ox1 waiting for prompt
2582026-10-21T03:56:19.192ZOct 21 03:56:19.171 DEBG [sc] ox1: logging in
2592026-10-21T03:56:19.192ZOct 21 03:56:19.172 DEBG [sc] ox2: logging in
2602026-10-21T03:56:19.266ZOct 21 03:56:19.259 DEBG [sc] ox1: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2612026-10-21T03:56:19.289ZOct 21 03:56:19.260 DEBG [sc] ox2: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2622026-10-21T03:56:24.190ZOct 21 03:56:24.184 DEBG [sc] ox2: starting
2632026-10-21T03:56:24.190ZOct 21 03:56:24.184 DEBG [sc] ox2: connecting to [::1]:39991
2642026-10-21T03:56:24.213ZOct 21 03:56:24.184 DEBG [sc] ox2 waiting for prompt
2652026-10-21T03:56:24.213ZOct 21 03:56:24.195 DEBG [sc] ox2: logging in
2662026-10-21T03:56:24.280ZOct 21 03:56:24.273 DEBG [sc] ox2: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2672026-10-21T03:56:24.368ZOct 21 03:56:24.361 DEBG [sc] ox2: starting
2682026-10-21T03:56:24.368ZOct 21 03:56:24.361 DEBG [sc] ox2: connecting to [::1]:39991
2692026-10-21T03:56:24.391ZOct 21 03:56:24.362 DEBG [sc] ox2 waiting for prompt
2702026-10-21T03:56:24.391ZOct 21 03:56:24.372 DEBG [sc] ox2: logging in
2712026-10-21T03:56:24.466ZOct 21 03:56:24.460 DEBG [sc] ox2: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2722026-10-21T03:56:25.245ZOct 21 03:56:25.239 DEBG [sc] ox1: starting
2732026-10-21T03:56:25.245ZOct 21 03:56:25.239 DEBG [sc] ox1: connecting to [::1]:55975
2742026-10-21T03:56:25.268ZOct 21 03:56:25.239 DEBG [sc] ox1 waiting for prompt
2752026-10-21T03:56:25.268ZOct 21 03:56:25.250 DEBG [sc] ox1: logging in
2762026-10-21T03:56:25.345ZOct 21 03:56:25.338 DEBG [sc] ox1: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2772026-10-21T03:56:25.433ZOct 21 03:56:25.427 DEBG [sc] ox1: starting
2782026-10-21T03:56:25.433ZOct 21 03:56:25.427 DEBG [sc] ox1: connecting to [::1]:55975
2792026-10-21T03:56:25.456ZOct 21 03:56:25.427 DEBG [sc] ox1 waiting for prompt
2802026-10-21T03:56:25.457ZOct 21 03:56:25.437 DEBG [sc] ox1: logging in
2812026-10-21T03:56:25.533ZOct 21 03:56:25.526 DEBG [sc] ox1: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2822026-10-21T03:56:25.602ZOct 21 03:56:25.596 DEBG [sc] ox1: starting
2832026-10-21T03:56:25.602ZOct 21 03:56:25.596 DEBG [sc] ox1: connecting to [::1]:55975
2842026-10-21T03:56:25.625ZOct 21 03:56:25.596 DEBG [sc] ox1 waiting for prompt
2852026-10-21T03:56:25.625ZOct 21 03:56:25.603 DEBG [sc] ox1: logging in
2862026-10-21T03:56:25.697ZOct 21 03:56:25.690 DEBG [sc] ox1: executing command `ipadm create-addr -T addrconf vioif0/ll`
2872026-10-21T03:56:25.797ZOct 21 03:56:25.790 DEBG [sc] ox1: starting
2882026-10-21T03:56:25.797ZOct 21 03:56:25.790 DEBG [sc] ox1: connecting to [::1]:55975
2892026-10-21T03:56:25.819ZOct 21 03:56:25.791 DEBG [sc] ox1 waiting for prompt
2902026-10-21T03:56:25.820ZOct 21 03:56:25.801 DEBG [sc] ox1: logging in
2912026-10-21T03:56:25.896ZOct 21 03:56:25.889 DEBG [sc] ox1: executing command `ipadm show-addr vioif0/ll -p -o addr`
2922026-10-21T03:56:25.973ZOct 21 03:56:25.966 DEBG [sc] ox2: starting
2932026-10-21T03:56:25.973ZOct 21 03:56:25.966 DEBG [sc] ox2: connecting to [::1]:39991
2942026-10-21T03:56:25.996ZOct 21 03:56:25.967 DEBG [sc] ox2 waiting for prompt
2952026-10-21T03:56:25.996ZOct 21 03:56:25.978 DEBG [sc] ox2: logging in
2962026-10-21T03:56:26.072ZOct 21 03:56:26.066 DEBG [sc] ox2: executing command `ipadm create-addr -T addrconf vioif0/ll`
2972026-10-21T03:56:26.171ZOct 21 03:56:26.165 DEBG [sc] ox2: starting
2982026-10-21T03:56:26.171ZOct 21 03:56:26.165 DEBG [sc] ox2: connecting to [::1]:39991
2992026-10-21T03:56:26.194ZOct 21 03:56:26.165 DEBG [sc] ox2 waiting for prompt
3002026-10-21T03:56:26.194ZOct 21 03:56:26.176 DEBG [sc] ox2: logging in
3012026-10-21T03:56:26.270ZOct 21 03:56:26.264 DEBG [sc] ox2: executing command `ipadm show-addr vioif0/ll -p -o addr`
3022026-10-21T03:56:26.348ZOct 21 03:56:26.341 DEBG [sc] ox1: starting
3032026-10-21T03:56:26.348ZOct 21 03:56:26.341 DEBG [sc] ox1: connecting to [::1]:55975
3042026-10-21T03:56:26.370ZOct 21 03:56:26.342 DEBG [sc] ox2: starting
3052026-10-21T03:56:26.370ZOct 21 03:56:26.342 DEBG [sc] ox2: connecting to [::1]:39991
3062026-10-21T03:56:26.370ZOct 21 03:56:26.342 DEBG [sc] ox2 waiting for prompt
3072026-10-21T03:56:26.370ZOct 21 03:56:26.342 DEBG [sc] ox1 waiting for prompt
3082026-10-21T03:56:26.371ZOct 21 03:56:26.352 DEBG [sc] ox2: logging in
3092026-10-21T03:56:26.371ZOct 21 03:56:26.353 DEBG [sc] ox1: logging in
3102026-10-21T03:56:26.447ZOct 21 03:56:26.440 DEBG [sc] ox2: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3112026-10-21T03:56:26.469ZOct 21 03:56:26.441 DEBG [sc] ox1: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3122026-10-21T03:56:26.525ZOct 21 03:56:26.518 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/config/routers, method: GET, operation_id: read_routers
3132026-10-21T03:56:26.547ZOct 21 03:56:26.519 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/config/routers, method: GET, operation_id: read_routers
3142026-10-21T03:56:26.547ZOct 21 03:56:26.519 DEBG client response, result: Err(reqwest::Error { kind: Request, url: "http://10.151.6.164:4676/bgp/config/routers", source: hyper_util::client::legacy::Error(Connect, ConnectError("tcp connect error", 10.151.6.164:4676, Os { code: 146, kind: ConnectionRefused, message: "Connection refused" })) }), operation_id: read_routers
3152026-10-21T03:56:26.547ZOct 21 03:56:26.519 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.6.164:4676/bgp/config/routers)
3162026-10-21T03:56:26.548ZOct 21 03:56:26.519 DEBG client response, result: Err(reqwest::Error { kind: Request, url: "http://10.151.6.165:4676/bgp/config/routers", source: hyper_util::client::legacy::Error(Connect, ConnectError("tcp connect error", 10.151.6.165:4676, Os { code: 146, kind: ConnectionRefused, message: "Connection refused" })) }), operation_id: read_routers
3172026-10-21T03:56:26.548ZOct 21 03:56:26.519 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.6.165:4676/bgp/config/routers)
3182026-10-21T03:56:27.527ZOct 21 03:56:27.520 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/config/routers, method: GET, operation_id: read_routers
3192026-10-21T03:56:27.527ZOct 21 03:56:27.520 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/config/routers, method: GET, operation_id: read_routers
3202026-10-21T03:56:27.570ZOct 21 03:56:27.524 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "cb5d3b43-83fa-4268-a09b-08155de5555a", "content-length": "2", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: read_routers
3212026-10-21T03:56:27.570ZOct 21 03:56:27.524 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "c67908ba-c5d3-4dbb-88cc-f8059d981b62", "content-length": "2", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: read_routers
3222026-10-21T03:56:27.570ZOct 21 03:56:27.524 INFO adding BGP routers to both mgd nodes
3232026-10-21T03:56:27.570ZOct 21 03:56:27.524 DEBG client request, body: Some(Body), uri: http://10.151.6.165:4676/bgp/config/router, method: PUT, operation_id: create_router
3242026-10-21T03:56:27.570ZOct 21 03:56:27.524 DEBG client request, body: Some(Body), uri: http://10.151.6.164:4676/bgp/config/router, method: PUT, operation_id: create_router
3252026-10-21T03:56:27.570ZOct 21 03:56:27.527 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/config/router", status: 204, headers: {"x-request-id": "8c96071a-025e-4e37-b346-e75749ac34d3", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: create_router
3262026-10-21T03:56:27.570ZOct 21 03:56:27.527 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/config/router", status: 204, headers: {"x-request-id": "066c62c2-c43d-46a1-9b51-674f78dbd241", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: create_router
3272026-10-21T03:56:27.570ZOct 21 03:56:27.527 INFO adding unnumbered BGP neighbors to both mgd nodes
3282026-10-21T03:56:27.570ZOct 21 03:56:27.527 DEBG client request, body: Some(Body), uri: http://10.151.6.165:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
3292026-10-21T03:56:27.571ZOct 21 03:56:27.527 DEBG client request, body: Some(Body), uri: http://10.151.6.164:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
3302026-10-21T03:56:27.571ZOct 21 03:56:27.533 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "e4a139b8-a308-4e44-982b-43238962fb62", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: create_unnumbered_neighbor
3312026-10-21T03:56:27.571ZOct 21 03:56:27.534 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "7f9fadd2-a6af-43a4-944d-7aca18faeaa6", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: create_unnumbered_neighbor
3322026-10-21T03:56:27.571ZOct 21 03:56:27.534 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3332026-10-21T03:56:27.571ZOct 21 03:56:27.534 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3342026-10-21T03:56:27.571ZOct 21 03:56:27.535 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "123ca622-b44b-4c2d-b64a-b83f418d48f7", "content-length": "2139", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: get_neighbors
3352026-10-21T03:56:27.571ZOct 21 03:56:27.535 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3362026-10-21T03:56:27.571ZOct 21 03:56:27.535 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "f9fb3a2a-5d5d-453b-ae33-a274a72b050e", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: get_neighbors
3372026-10-21T03:56:27.571ZOct 21 03:56:27.535 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3382026-10-21T03:56:27.571ZOct 21 03:56:27.536 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "24b09d2b-69c2-4188-bace-69e693a205a9", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: get_neighbors
3392026-10-21T03:56:27.571Zmgd1 bgp ox2 established: iteration 1/20: expected Some(Established), got Some(Connect)
3402026-10-21T03:56:27.571ZOct 21 03:56:27.536 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "aebfc67d-6dc3-4751-8d76-9863a55eb574", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:27 GMT"} }), operation_id: get_neighbors
3412026-10-21T03:56:27.571Zmgd2 bgp ox1 established: iteration 1/20: expected Some(Established), got Some(Connect)
3422026-10-21T03:56:28.544ZOct 21 03:56:28.537 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3432026-10-21T03:56:28.544ZOct 21 03:56:28.537 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3442026-10-21T03:56:28.566ZOct 21 03:56:28.538 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "fae18cc8-3b37-47c1-ad45-c665c8eccc3c", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:28 GMT"} }), operation_id: get_neighbors
3452026-10-21T03:56:28.566Zmgd1 bgp ox2 established: iteration 2/20: expected Some(Established), got Some(Connect)
3462026-10-21T03:56:28.566Zmgd2 bgp ox1 established: iteration 2/20: expected Some(Established), got Some(Connect)
3472026-10-21T03:56:28.567ZOct 21 03:56:28.538 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "257b7627-7124-4747-aacd-d5e9bbae6e77", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:28 GMT"} }), operation_id: get_neighbors
3482026-10-21T03:56:29.545ZOct 21 03:56:29.539 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3492026-10-21T03:56:29.568ZOct 21 03:56:29.539 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "5c5f0b5e-20b9-4e6b-92b2-f77d32bac54d", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:29 GMT"} }), operation_id: get_neighbors
3502026-10-21T03:56:29.568Zmgd1 bgp ox2 established: iteration 3/20: expected Some(Established), got Some(Connect)
3512026-10-21T03:56:29.568ZOct 21 03:56:29.539 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3522026-10-21T03:56:29.568ZOct 21 03:56:29.540 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "73e0a4b0-b5b2-43e7-8a2a-b3a3946307ed", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:29 GMT"} }), operation_id: get_neighbors
3532026-10-21T03:56:29.568Zmgd2 bgp ox1 established: iteration 3/20: expected Some(Established), got Some(Connect)
3542026-10-21T03:56:30.549ZOct 21 03:56:30.542 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3552026-10-21T03:56:30.549ZOct 21 03:56:30.542 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3562026-10-21T03:56:30.572ZOct 21 03:56:30.543 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "1cfb69bc-5a45-4130-ae02-a4e75d09b8b3", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:30 GMT"} }), operation_id: get_neighbors
3572026-10-21T03:56:30.572Zmgd1 bgp ox2 established: iteration 4/20: expected Some(Established), got Some(Connect)
3582026-10-21T03:56:30.572ZOct 21 03:56:30.543 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "d953bbbe-f962-4d45-8e98-6501553020e2", "content-length": "2140", "date": "Wed, 21 Oct 2026 03:56:30 GMT"} }), operation_id: get_neighbors
3592026-10-21T03:56:30.572Zmgd2 bgp ox1 established: iteration 4/20: expected Some(Established), got Some(Connect)
3602026-10-21T03:56:31.551ZOct 21 03:56:31.544 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3612026-10-21T03:56:31.551ZOct 21 03:56:31.544 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3622026-10-21T03:56:31.575ZOct 21 03:56:31.545 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "0a22d1a2-2062-4359-9418-08d7172c997c", "content-length": "2141", "date": "Wed, 21 Oct 2026 03:56:31 GMT"} }), operation_id: get_neighbors
3632026-10-21T03:56:31.575Zmgd2 bgp ox1 established: iteration 5/20: expected Some(Established), got Some(Connect)
3642026-10-21T03:56:31.575Zmgd1 bgp ox2 established: iteration 5/20: expected Some(Established), got Some(Connect)
3652026-10-21T03:56:31.575ZOct 21 03:56:31.545 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "c7c53822-d151-43c2-b9e7-0f80b016d0ba", "content-length": "2141", "date": "Wed, 21 Oct 2026 03:56:31 GMT"} }), operation_id: get_neighbors
3662026-10-21T03:56:32.552ZOct 21 03:56:32.546 DEBG client request, body: None, uri: http://10.151.6.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3672026-10-21T03:56:32.575ZOct 21 03:56:32.546 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3682026-10-21T03:56:32.575ZOct 21 03:56:32.547 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "7dbadb63-295e-4e9f-9c4f-84a8c454d934", "content-length": "2380", "date": "Wed, 21 Oct 2026 03:56:32 GMT"} }), operation_id: get_neighbors
3692026-10-21T03:56:32.575Zmgd1 bgp ox2 established: iteration 6/20: expected Some(Established), got Some(ConnectionCollision)
3702026-10-21T03:56:32.575ZOct 21 03:56:32.548 DEBG client response, result: Ok(Response { url: "http://10.151.6.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "1f723186-c4a2-43b6-995c-fec19b4c4f5c", "content-length": "2372", "date": "Wed, 21 Oct 2026 03:56:32 GMT"} }), operation_id: get_neighbors
3712026-10-21T03:56:33.555ZOct 21 03:56:33.548 DEBG client request, body: None, uri: http://10.151.6.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3722026-10-21T03:56:33.579ZOct 21 03:56:33.549 DEBG client response, result: Ok(Response { url: "http://10.151.6.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "36c4493a-82ef-4245-b4f6-f54af6f7f968", "content-length": "2390", "date": "Wed, 21 Oct 2026 03:56:33 GMT"} }), operation_id: get_neighbors
3732026-10-21T03:56:33.579ZOct 21 03:56:33.549 INFO mgd-to-mgd bgp unnumbered test passed 🎉
3742026-10-21T03:56:33.579Z+ (( status != 0 ))
3752026-10-21T03:56:33.579Z+ pfexec ./falcon-lab cleanup mgd-duo bgp-unnumbered
3762026-10-21T03:56:33.601ZOct 21 03:56:33.580 INFO destroying runner for deployment mgdduo_bgpu
3772026-10-21T03:56:33.602ZOct 21 03:56:33.580 INFO destroying deployment mgdduo_bgpu
3782026-10-21T03:56:33.602ZOct 21 03:56:33.580 INFO destroying nodes
3792026-10-21T03:56:33.602ZOct 21 03:56:33.589 INFO destroying links
3802026-10-21T03:56:33.602ZOct 21 03:56:33.589 INFO destroying link mgdduo_bgpu_ox1_vn_vnic0
3812026-10-21T03:56:34.600ZOct 21 03:56:34.594 INFO destroying link mgdduo_bgpu_ox1_vn_sim0
3822026-10-21T03:56:34.623ZOct 21 03:56:34.595 INFO destroying link mgdduo_bgpu_ox2_vn_vnic0
3832026-10-21T03:56:34.623ZOct 21 03:56:34.596 INFO destroying link mgdduo_bgpu_ox2_vn_sim0
3842026-10-21T03:56:34.623ZOct 21 03:56:34.597 INFO destroying external links
3852026-10-21T03:56:34.623ZOct 21 03:56:34.597 INFO destroying external link mgdduo_bgpu_ox1_vn_vnic1
3862026-10-21T03:56:34.623ZOct 21 03:56:34.598 INFO destroying external link mgdduo_bgpu_ox2_vn_vnic1
3872026-10-21T03:56:34.623ZOct 21 03:56:34.600 INFO destroying images
3882026-10-21T03:56:34.760ZOct 21 03:56:34.755 INFO destroying workspace at .falcon
3892026-10-21T03:56:34.783Z+ return 0
3902026-10-21T03:56:34.783Z+ run_test interop bgp-unnumbered
3912026-10-21T03:56:34.783Z+ local topology=interop
3922026-10-21T03:56:34.783Z+ local scenario=bgp-unnumbered
3932026-10-21T03:56:34.783Z+ local test_name=interop-bgp-unnumbered
3942026-10-21T03:56:34.783Z+ local status=0
3952026-10-21T03:56:34.783Z+ clear_falcon_workspace_files
3962026-10-21T03:56:34.783Z+ [[ -d .falcon ]]
3972026-10-21T03:56:34.783Z+ find .falcon -maxdepth 1 -type f -exec rm -f '{}' +
3982026-10-21T03:56:34.783Z+ RUST_LOG=debug
3992026-10-21T03:56:34.783Z+ pfexec ./falcon-lab run interop bgp-unnumbered --no-cleanup
4002026-10-21T03:56:34.806ZOct 21 03:56:34.786 DEBG using default route interface igb0
4012026-10-21T03:56:34.806ZOct 21 03:56:34.787 DEBG using default route interface igb0
4022026-10-21T03:56:34.806ZOct 21 03:56:34.787 DEBG using default route interface igb0
4032026-10-21T03:56:34.806ZOct 21 03:56:34.787 DEBG using default route interface igb0
4042026-10-21T03:56:34.807ZOct 21 03:56:34.787 DEBG using default route interface igb0
4052026-10-21T03:56:34.807ZOct 21 03:56:34.787 INFO interop_bgpu: launching interop topology
4062026-10-21T03:56:34.807ZOct 21 03:56:34.787 INFO launching runner: deployment(interop_bgpu) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
4072026-10-21T03:56:34.807ZOct 21 03:56:34.787 INFO starting preflight for deployment interop_bgpu
4082026-10-21T03:56:35.257ZOct 21 03:56:35.251 INFO base image for debian-13.2 does not exist, attempting to install
4092026-10-21T03:56:35.257ZOct 21 03:56:35.251 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
4102026-10-21T03:56:55.185ZOct 21 03:56:55.181 INFO extracting image to /tmp/debian-13.2_0.raw
4112026-10-21T03:57:09.168ZOct 21 03:57:09.164 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
4122026-10-21T03:57:09.192ZOct 21 03:57:09.189 INFO copying image data to zvol
4132026-10-21T03:57:16.259ZOct 21 03:57:16.248 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
4142026-10-21T03:57:19.389ZOct 21 03:57:19.377 INFO base image for eos-4.35 does not exist, attempting to install
4152026-10-21T03:57:19.389ZOct 21 03:57:19.377 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
4162026-10-21T03:58:01.494ZOct 21 03:58:01.482 INFO extracting image to /tmp/eos-4.35_0.raw
4172026-10-21T03:59:06.646ZOct 21 03:59:06.634 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
4182026-10-21T03:59:06.670ZOct 21 03:59:06.659 INFO copying image data to zvol
4192026-10-21T03:59:55.310ZOct 21 03:59:55.298 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
4202026-10-21T03:59:58.631ZOct 21 03:59:58.595 INFO base image for junos-23.2 does not exist, attempting to install
4212026-10-21T03:59:58.631ZOct 21 03:59:58.595 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/junos-23.2_0.raw.xz
4222026-10-21T04:00:33.232ZOct 21 04:00:33.221 INFO extracting image to /tmp/junos-23.2_0.raw
4232026-10-21T04:01:32.149ZOct 21 04:01:32.137 INFO creating zvol cpool/falcon/img/junos-23.2 of size 21474840576
4242026-10-21T04:01:32.176ZOct 21 04:01:32.164 INFO copying image data to zvol
4252026-10-21T04:02:20.382ZOct 21 04:02:20.371 INFO creating zfs snapshot cpool/falcon/img/junos-23.2@base
4262026-10-21T04:02:23.833ZOct 21 04:02:23.822 INFO creating links
4272026-10-21T04:02:23.833ZOct 21 04:02:23.822 DEBG destroying link interop_bgpu_ox_sn_vnic0
4282026-10-21T04:02:23.833ZOct 21 04:02:23.822 DEBG destroying link interop_bgpu_ox_sn_sim0
4292026-10-21T04:02:23.833ZOct 21 04:02:23.822 INFO creating simnet link 'interop_bgpu_ox_sn_sim0'
4302026-10-21T04:02:23.856ZOct 21 04:02:23.824 INFO creating vnic link 'interop_bgpu_ox_sn_vnic0'
4312026-10-21T04:02:23.857ZOct 21 04:02:23.831 DEBG link pair created
4322026-10-21T04:02:23.857ZOct 21 04:02:23.831 DEBG destroying link interop_bgpu_cr1_vn_vnic0
4332026-10-21T04:02:23.857ZOct 21 04:02:23.831 DEBG destroying link interop_bgpu_cr1_vn_sim0
4342026-10-21T04:02:23.857ZOct 21 04:02:23.831 INFO creating simnet link 'interop_bgpu_cr1_vn_sim0'
4352026-10-21T04:02:23.857ZOct 21 04:02:23.833 INFO creating vnic link 'interop_bgpu_cr1_vn_vnic0'
4362026-10-21T04:02:23.857ZOct 21 04:02:23.840 DEBG link pair created
4372026-10-21T04:02:23.857ZOct 21 04:02:23.840 DEBG destroying link interop_bgpu_ox_sn_vnic1
4382026-10-21T04:02:23.857ZOct 21 04:02:23.840 DEBG destroying link interop_bgpu_ox_sn_sim1
4392026-10-21T04:02:23.857ZOct 21 04:02:23.840 INFO creating simnet link 'interop_bgpu_ox_sn_sim1'
4402026-10-21T04:02:23.857ZOct 21 04:02:23.842 INFO creating vnic link 'interop_bgpu_ox_sn_vnic1'
4412026-10-21T04:02:23.880ZOct 21 04:02:23.848 DEBG link pair created
4422026-10-21T04:02:23.880ZOct 21 04:02:23.848 DEBG destroying link interop_bgpu_cr2_vn_vnic0
4432026-10-21T04:02:23.880ZOct 21 04:02:23.848 DEBG destroying link interop_bgpu_cr2_vn_sim0
4442026-10-21T04:02:23.880ZOct 21 04:02:23.848 INFO creating simnet link 'interop_bgpu_cr2_vn_sim0'
4452026-10-21T04:02:23.880ZOct 21 04:02:23.850 INFO creating vnic link 'interop_bgpu_cr2_vn_vnic0'
4462026-10-21T04:02:23.880ZOct 21 04:02:23.856 DEBG link pair created
4472026-10-21T04:02:23.880ZOct 21 04:02:23.857 DEBG destroying link interop_bgpu_ox_sn_vnic2
4482026-10-21T04:02:23.880ZOct 21 04:02:23.857 DEBG destroying link interop_bgpu_ox_sn_sim2
4492026-10-21T04:02:23.880ZOct 21 04:02:23.857 INFO creating simnet link 'interop_bgpu_ox_sn_sim2'
4502026-10-21T04:02:23.880ZOct 21 04:02:23.858 INFO creating vnic link 'interop_bgpu_ox_sn_vnic2'
4512026-10-21T04:02:23.880ZOct 21 04:02:23.865 DEBG link pair created
4522026-10-21T04:02:23.880ZOct 21 04:02:23.865 DEBG destroying link interop_bgpu_cr3_vn_vnic0
4532026-10-21T04:02:23.880ZOct 21 04:02:23.865 DEBG destroying link interop_bgpu_cr3_vn_sim0
4542026-10-21T04:02:23.880ZOct 21 04:02:23.865 INFO creating simnet link 'interop_bgpu_cr3_vn_sim0'
4552026-10-21T04:02:23.880ZOct 21 04:02:23.866 INFO creating vnic link 'interop_bgpu_cr3_vn_vnic0'
4562026-10-21T04:02:23.903ZOct 21 04:02:23.873 DEBG link pair created
4572026-10-21T04:02:23.903ZOct 21 04:02:23.873 DEBG destroying link interop_bgpu_ox_sn_vnic3
4582026-10-21T04:02:23.903ZOct 21 04:02:23.873 DEBG destroying link interop_bgpu_ox_sn_sim3
4592026-10-21T04:02:23.903ZOct 21 04:02:23.873 INFO creating simnet link 'interop_bgpu_ox_sn_sim3'
4602026-10-21T04:02:23.903ZOct 21 04:02:23.874 INFO creating vnic link 'interop_bgpu_ox_sn_vnic3'
4612026-10-21T04:02:23.903ZOct 21 04:02:23.881 DEBG link pair created
4622026-10-21T04:02:23.903ZOct 21 04:02:23.881 DEBG destroying link interop_bgpu_peer_vn_vnic0
4632026-10-21T04:02:23.903ZOct 21 04:02:23.881 DEBG destroying link interop_bgpu_peer_vn_sim0
4642026-10-21T04:02:23.903ZOct 21 04:02:23.881 INFO creating simnet link 'interop_bgpu_peer_vn_sim0'
4652026-10-21T04:02:23.903ZOct 21 04:02:23.883 INFO creating vnic link 'interop_bgpu_peer_vn_vnic0'
4662026-10-21T04:02:23.903ZOct 21 04:02:23.890 DEBG link pair created
4672026-10-21T04:02:23.903ZOct 21 04:02:23.890 INFO creating external links
4682026-10-21T04:02:23.903ZOct 21 04:02:23.890 DEBG destroying external link interop_bgpu_ox_vn_vnic4
4692026-10-21T04:02:23.903ZOct 21 04:02:23.890 INFO creating external link interop_bgpu_ox_vn_vnic4
4702026-10-21T04:02:23.903ZOct 21 04:02:23.891 DEBG external link interop_bgpu_ox_vn_vnic4@igb0 created
4712026-10-21T04:02:23.903ZOct 21 04:02:23.891 DEBG destroying external link interop_bgpu_cr1_vn_vnic1
4722026-10-21T04:02:23.903ZOct 21 04:02:23.891 INFO creating external link interop_bgpu_cr1_vn_vnic1
4732026-10-21T04:02:23.926ZOct 21 04:02:23.893 DEBG external link interop_bgpu_cr1_vn_vnic1@igb0 created
4742026-10-21T04:02:23.927ZOct 21 04:02:23.893 DEBG destroying external link interop_bgpu_cr2_vn_vnic1
4752026-10-21T04:02:23.927ZOct 21 04:02:23.893 INFO creating external link interop_bgpu_cr2_vn_vnic1
4762026-10-21T04:02:23.927ZOct 21 04:02:23.894 DEBG external link interop_bgpu_cr2_vn_vnic1@igb0 created
4772026-10-21T04:02:23.927ZOct 21 04:02:23.894 DEBG destroying external link interop_bgpu_cr3_vn_vnic1
4782026-10-21T04:02:23.927ZOct 21 04:02:23.894 INFO creating external link interop_bgpu_cr3_vn_vnic1
4792026-10-21T04:02:23.927ZOct 21 04:02:23.895 DEBG external link interop_bgpu_cr3_vn_vnic1@igb0 created
4802026-10-21T04:02:23.927ZOct 21 04:02:23.895 DEBG destroying external link interop_bgpu_peer_vn_vnic1
4812026-10-21T04:02:23.927ZOct 21 04:02:23.895 INFO creating external link interop_bgpu_peer_vn_vnic1
4822026-10-21T04:02:23.927ZOct 21 04:02:23.897 DEBG external link interop_bgpu_peer_vn_vnic1@igb0 created
4832026-10-21T04:02:23.927ZOct 21 04:02:23.897 INFO creating nodes
4842026-10-21T04:02:23.927ZOct 21 04:02:23.897 INFO ox: launching node
4852026-10-21T04:02:23.927ZOct 21 04:02:23.899 INFO cr1: launching node
4862026-10-21T04:02:23.927ZOct 21 04:02:23.902 INFO cr2: launching node
4872026-10-21T04:02:23.927ZOct 21 04:02:23.904 INFO cr3: launching node
4882026-10-21T04:02:23.927ZOct 21 04:02:23.906 INFO peer: launching node
4892026-10-21T04:02:23.950ZOct 21 04:02:23.920 INFO launched instance cr2 with pid 922 on port 32883
4902026-10-21T04:02:23.950ZOct 21 04:02:23.920 INFO cr2: instance ensure
4912026-10-21T04:02:23.950ZOct 21 04:02:23.920 INFO launched instance ox with pid 920 on port 44116
4922026-10-21T04:02:23.950ZOct 21 04:02:23.920 INFO ox: instance ensure
4932026-10-21T04:02:23.950ZOct 21 04:02:23.921 INFO launched instance cr1 with pid 921 on port 42530
4942026-10-21T04:02:23.950ZOct 21 04:02:23.921 INFO cr1: instance ensure
4952026-10-21T04:02:23.950ZOct 21 04:02:23.921 INFO launched instance cr3 with pid 923 on port 54502
4962026-10-21T04:02:23.950ZOct 21 04:02:23.921 INFO cr3: instance ensure
4972026-10-21T04:02:23.950ZOct 21 04:02:23.921 INFO launched instance peer with pid 924 on port 34919
4982026-10-21T04:02:23.950ZOct 21 04:02:23.921 INFO peer: instance ensure
4992026-10-21T04:02:27.976ZOct 21 04:02:27.965 INFO ox: instance ensure completed after 0 retries
5002026-10-21T04:02:27.976ZOct 21 04:02:27.965 INFO ox: instance run
5012026-10-21T04:02:27.999ZOct 21 04:02:27.965 DEBG [sc] ox: starting
5022026-10-21T04:02:27.999ZOct 21 04:02:27.965 DEBG [sc] ox: connecting to [::1]:44116
5032026-10-21T04:02:27.999ZOct 21 04:02:27.966 DEBG [sc] ox waiting for prompt
5042026-10-21T04:02:28.022ZOct 21 04:02:28.011 INFO peer: instance ensure completed after 0 retries
5052026-10-21T04:02:28.022ZOct 21 04:02:28.011 INFO peer: instance run
5062026-10-21T04:02:28.045ZOct 21 04:02:28.012 DEBG [sc] peer: starting
5072026-10-21T04:02:28.045ZOct 21 04:02:28.012 DEBG [sc] peer: connecting to [::1]:34919
5082026-10-21T04:02:28.045ZOct 21 04:02:28.012 DEBG [sc] peer waiting for prompt
5092026-10-21T04:02:28.076ZOct 21 04:02:28.065 INFO cr2: instance ensure completed after 0 retries
5102026-10-21T04:02:28.076ZOct 21 04:02:28.065 INFO cr2: instance run
5112026-10-21T04:02:28.099ZOct 21 04:02:28.066 DEBG [sc] cr2: starting
5122026-10-21T04:02:28.099ZOct 21 04:02:28.066 DEBG [sc] cr2: connecting to [::1]:32883
5132026-10-21T04:02:28.099ZOct 21 04:02:28.066 DEBG [sc] cr2 waiting for prompt
5142026-10-21T04:02:28.142ZOct 21 04:02:28.131 INFO cr1: instance ensure completed after 0 retries
5152026-10-21T04:02:28.143ZOct 21 04:02:28.131 INFO cr1: instance run
5162026-10-21T04:02:28.167ZOct 21 04:02:28.132 DEBG [sc] cr1: starting
5172026-10-21T04:02:28.167ZOct 21 04:02:28.132 DEBG [sc] cr1: connecting to [::1]:42530
5182026-10-21T04:02:28.167ZOct 21 04:02:28.132 DEBG [sc] cr1 waiting for prompt
5192026-10-21T04:02:28.202ZOct 21 04:02:28.191 INFO cr3: instance ensure completed after 0 retries
5202026-10-21T04:02:28.202ZOct 21 04:02:28.191 INFO cr3: instance run
5212026-10-21T04:02:45.379ZOct 21 04:02:45.368 DEBG [sc] cr1: logging in
5222026-10-21T04:02:45.742ZOct 21 04:02:45.731 INFO cr1: mounting /opt/cargo-bay
5232026-10-21T04:02:45.742ZOct 21 04:02:45.731 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; mount -t 9p -o ro,msize=65536 /opt/cargo-bay /opt/cargo-bay`
5242026-10-21T04:02:45.775ZOct 21 04:02:45.763 DEBG [sc] cr1: executing command `cd`
5252026-10-21T04:02:45.798ZOct 21 04:02:45.774 INFO cr1: finished mounting /opt/cargo-bay
5262026-10-21T04:02:45.798ZOct 21 04:02:45.774 DEBG [sc] cr1: executing command `hostname cr1`
5272026-10-21T04:02:45.798ZOct 21 04:02:45.785 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
5282026-10-21T04:02:45.821ZOct 21 04:02:45.797 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
5292026-10-21T04:02:45.822ZOct 21 04:02:45.808 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
5302026-10-21T04:02:45.844ZOct 21 04:02:45.818 INFO cr1: logging out
5312026-10-21T04:02:47.361ZOct 21 04:02:47.350 DEBG [sc] ox: logging in
5322026-10-21T04:02:47.417ZOct 21 04:02:47.406 DEBG [sc] peer: logging in
5332026-10-21T04:02:47.611ZOct 21 04:02:47.600 INFO ox: mounting /opt/cargo-bay
5342026-10-21T04:02:47.611ZOct 21 04:02:47.600 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
5352026-10-21T04:02:47.673ZOct 21 04:02:47.662 INFO peer: mounting /opt/cargo-bay
5362026-10-21T04:02:47.673ZOct 21 04:02:47.662 DEBG [sc] peer: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
5372026-10-21T04:02:47.893ZOct 21 04:02:47.882 INFO cr1: logged out
5382026-10-21T04:02:48.756ZOct 21 04:02:48.745 DEBG [sc] ox: executing command `cd`
5392026-10-21T04:02:48.779ZOct 21 04:02:48.756 INFO ox: finished mounting /opt/cargo-bay
5402026-10-21T04:02:48.779ZOct 21 04:02:48.756 DEBG [sc] ox: executing command `hostname ox`
5412026-10-21T04:02:48.779ZOct 21 04:02:48.767 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
5422026-10-21T04:02:48.861ZOct 21 04:02:48.778 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
5432026-10-21T04:02:48.884ZOct 21 04:02:48.790 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
5442026-10-21T04:02:48.884ZOct 21 04:02:48.800 INFO ox: logging out
5452026-10-21T04:02:48.907ZOct 21 04:02:48.877 INFO ox: logged out
5462026-10-21T04:02:49.034ZOct 21 04:02:49.022 DEBG [sc] peer: executing command `cd`
5472026-10-21T04:02:49.057ZOct 21 04:02:49.032 INFO peer: finished mounting /opt/cargo-bay
5482026-10-21T04:02:49.057ZOct 21 04:02:49.032 DEBG [sc] peer: executing command `hostname peer`
5492026-10-21T04:02:49.057ZOct 21 04:02:49.044 DEBG [sc] peer: executing command `echo 'peer' > /etc/nodename`
5502026-10-21T04:02:49.080ZOct 21 04:02:49.055 DEBG [sc] peer: executing command `echo '::1 peer.local peer' >> /etc/hosts`
5512026-10-21T04:02:49.080ZOct 21 04:02:49.066 DEBG [sc] peer: executing command `echo '127.0.0.1 peer.local peer' >> /etc/hosts`
5522026-10-21T04:02:49.102ZOct 21 04:02:49.077 INFO peer: logging out
5532026-10-21T04:02:49.164ZOct 21 04:02:49.153 INFO peer: logged out
5542026-10-21T04:02:49.744ZOct 21 04:02:49.733 DEBG [sc] cr2: logging in
5552026-10-21T04:02:50.208ZOct 21 04:02:50.197 INFO cr2: mounting /opt/cargo-bay
5562026-10-21T04:02:50.208ZOct 21 04:02:50.197 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
5572026-10-21T04:02:50.231ZOct 21 04:02:50.219 DEBG [sc] cr2: executing command `cd`
5582026-10-21T04:02:50.255ZOct 21 04:02:50.230 INFO cr2: finished mounting /opt/cargo-bay
5592026-10-21T04:02:50.255ZOct 21 04:02:50.230 DEBG [sc] cr2: executing command `hostname cr2`
5602026-10-21T04:02:50.255ZOct 21 04:02:50.241 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
5612026-10-21T04:02:50.279ZOct 21 04:02:50.252 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
5622026-10-21T04:02:50.279ZOct 21 04:02:50.263 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
5632026-10-21T04:02:50.314ZOct 21 04:02:50.274 INFO cr2: logging out
5642026-10-21T04:02:53.173ZOct 21 04:02:53.162 INFO cr2: logged out
5652026-10-21T04:02:53.173ZOct 21 04:02:53.162 INFO interop_bgpu: interop topology launch complete
5662026-10-21T04:02:53.196ZOct 21 04:02:53.162 DEBG [sc] ox: starting
5672026-10-21T04:02:53.196ZOct 21 04:02:53.162 DEBG [sc] ox: connecting to [::1]:44116
5682026-10-21T04:02:53.196ZOct 21 04:02:53.162 DEBG [sc] peer: starting
5692026-10-21T04:02:53.196ZOct 21 04:02:53.162 DEBG [sc] peer: connecting to [::1]:34919
5702026-10-21T04:02:53.196ZOct 21 04:02:53.163 DEBG [sc] ox waiting for prompt
5712026-10-21T04:02:53.196ZOct 21 04:02:53.163 DEBG [sc] peer waiting for prompt
5722026-10-21T04:02:53.196ZOct 21 04:02:53.174 DEBG [sc] ox: logging in
5732026-10-21T04:02:53.196ZOct 21 04:02:53.175 DEBG [sc] peer: logging in
5742026-10-21T04:02:53.273ZOct 21 04:02:53.262 DEBG [sc] peer: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
5752026-10-21T04:02:53.296ZOct 21 04:02:53.262 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
5762026-10-21T04:02:58.267ZOct 21 04:02:58.256 DEBG [sc] ox: starting
5772026-10-21T04:02:58.267ZOct 21 04:02:58.256 DEBG [sc] ox: connecting to [::1]:44116
5782026-10-21T04:02:58.290ZOct 21 04:02:58.257 DEBG [sc] ox waiting for prompt
5792026-10-21T04:02:58.290ZOct 21 04:02:58.267 DEBG [sc] ox: logging in
5802026-10-21T04:02:58.366ZOct 21 04:02:58.355 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
5812026-10-21T04:02:58.453ZOct 21 04:02:58.443 DEBG [sc] ox: starting
5822026-10-21T04:02:58.454ZOct 21 04:02:58.443 DEBG [sc] ox: connecting to [::1]:44116
5832026-10-21T04:02:58.476ZOct 21 04:02:58.443 DEBG [sc] ox waiting for prompt
5842026-10-21T04:02:58.476ZOct 21 04:02:58.453 DEBG [sc] ox: logging in
5852026-10-21T04:02:58.552ZOct 21 04:02:58.541 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
5862026-10-21T04:02:59.451ZOct 21 04:02:59.440 DEBG [sc] peer: starting
5872026-10-21T04:02:59.451ZOct 21 04:02:59.440 DEBG [sc] peer: connecting to [::1]:34919
5882026-10-21T04:02:59.474ZOct 21 04:02:59.441 DEBG [sc] peer waiting for prompt
5892026-10-21T04:02:59.475ZOct 21 04:02:59.451 DEBG [sc] peer: logging in
5902026-10-21T04:02:59.551ZOct 21 04:02:59.540 DEBG [sc] peer: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
5912026-10-21T04:02:59.640ZOct 21 04:02:59.629 DEBG [sc] peer: starting
5922026-10-21T04:02:59.640ZOct 21 04:02:59.629 DEBG [sc] peer: connecting to [::1]:34919
5932026-10-21T04:02:59.663ZOct 21 04:02:59.630 DEBG [sc] peer waiting for prompt
5942026-10-21T04:02:59.663ZOct 21 04:02:59.641 DEBG [sc] peer: logging in
5952026-10-21T04:02:59.740ZOct 21 04:02:59.729 DEBG [sc] peer: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
5962026-10-21T04:02:59.807ZOct 21 04:02:59.796 INFO waiting for ceos to initialize
5972026-10-21T04:02:59.807ZOct 21 04:02:59.796 INFO ox: setting up npuvm
5982026-10-21T04:02:59.807ZOct 21 04:02:59.796 INFO cr1: installing frr
5992026-10-21T04:02:59.830ZOct 21 04:02:59.796 INFO cr3: starting juniper unnumbered setup
6002026-10-21T04:02:59.830ZOct 21 04:02:59.796 INFO cr3: staged Juniper config and license for guest systemd services
6012026-10-21T04:02:59.830ZOct 21 04:02:59.796 INFO cr3: waiting for Juniper guest config apply
6022026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] ox: starting
6032026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] ox: connecting to [::1]:44116
6042026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] cr2: starting
6052026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] cr2: connecting to [::1]:32883
6062026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] cr1: starting
6072026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] cr1: connecting to [::1]:42530
6082026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] cr3: starting
6092026-10-21T04:02:59.830ZOct 21 04:02:59.796 DEBG [sc] cr3: connecting to [::1]:54502
6102026-10-21T04:02:59.830ZOct 21 04:02:59.797 DEBG [sc] cr2 waiting for prompt
6112026-10-21T04:02:59.830ZOct 21 04:02:59.797 DEBG [sc] ox waiting for prompt
6122026-10-21T04:02:59.831ZOct 21 04:02:59.797 DEBG [sc] cr1 waiting for prompt
6132026-10-21T04:02:59.831ZOct 21 04:02:59.797 DEBG [sc] cr3 waiting for prompt
6142026-10-21T04:02:59.831ZOct 21 04:02:59.797 DEBG [sc] cr3: logging in
6152026-10-21T04:02:59.831ZOct 21 04:02:59.808 DEBG [sc] ox: logging in
6162026-10-21T04:02:59.831ZOct 21 04:02:59.808 DEBG [sc] cr2: logging in
6172026-10-21T04:02:59.831ZOct 21 04:02:59.808 DEBG [sc] cr1: logging in
6182026-10-21T04:02:59.907ZOct 21 04:02:59.896 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`
6192026-10-21T04:02:59.951ZOct 21 04:02:59.940 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
6202026-10-21T04:03:00.167ZOct 21 04:03:00.156 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
6212026-10-21T04:03:00.206ZOct 21 04:03:00.195 DEBG [sc] cr3: executing command `cat /run/falcon-junos-apply.status 2>/dev/null || true`
6222026-10-21T04:03:02.215ZOct 21 04:03:02.204 INFO cr2: executing eos script show version
6232026-10-21T04:03:02.216ZOct 21 04:03:02.204 DEBG [sc] cr2: starting
6242026-10-21T04:03:02.216ZOct 21 04:03:02.204 DEBG [sc] cr2: connecting to [::1]:32883
6252026-10-21T04:03:02.239ZOct 21 04:03:02.205 DEBG [sc] cr2 waiting for prompt
6262026-10-21T04:03:02.239ZOct 21 04:03:02.214 DEBG [sc] cr2: logging in
6272026-10-21T04:03:02.303ZOct 21 04:03:02.292 INFO cr3: Juniper apply status: installing Juniper license
6282026-10-21T04:03:02.369ZOct 21 04:03:02.358 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
6292026-10-21T04:03:03.304ZOct 21 04:03:03.293 DEBG [sc] cr3: starting
6302026-10-21T04:03:03.304ZOct 21 04:03:03.293 DEBG [sc] cr3: connecting to [::1]:54502
6312026-10-21T04:03:03.327ZOct 21 04:03:03.293 DEBG [sc] cr3 waiting for prompt
6322026-10-21T04:03:03.327ZOct 21 04:03:03.304 DEBG [sc] cr3: logging in
6332026-10-21T04:03:03.448ZOct 21 04:03:03.437 DEBG [sc] cr3: executing command `cat /run/falcon-junos-apply.status 2>/dev/null || true`
6342026-10-21T04:03:05.512ZOct 21 04:03:05.501 INFO cr3: Juniper guest config applied
6352026-10-21T04:03:07.827ZOct 21 04:03:07.816 DEBG [sc] ox: starting
6362026-10-21T04:03:07.827ZOct 21 04:03:07.816 DEBG [sc] ox: connecting to [::1]:44116
6372026-10-21T04:03:07.850ZOct 21 04:03:07.816 DEBG [sc] ox waiting for prompt
6382026-10-21T04:03:07.850ZOct 21 04:03:07.827 DEBG [sc] ox: logging in
6392026-10-21T04:03:07.926ZOct 21 04:03:07.915 DEBG [sc] ox: executing command `cat npuvm.sha256.txt`
6402026-10-21T04:03:07.993ZOct 21 04:03:07.982 DEBG [sc] ox: starting
6412026-10-21T04:03:07.993ZOct 21 04:03:07.982 DEBG [sc] ox: connecting to [::1]:44116
6422026-10-21T04:03:08.015ZOct 21 04:03:07.982 DEBG [sc] ox waiting for prompt
6432026-10-21T04:03:08.015ZOct 21 04:03:07.992 DEBG [sc] ox: logging in
6442026-10-21T04:03:08.091ZOct 21 04:03:08.080 DEBG [sc] ox: executing command `digest -a sha256 npuvm`
6452026-10-21T04:03:08.213ZOct 21 04:03:08.202 INFO ox: npuvm downloaded and checksum verified (attempt 1/5)
6462026-10-21T04:03:08.236ZOct 21 04:03:08.202 DEBG [sc] ox: starting
6472026-10-21T04:03:08.236ZOct 21 04:03:08.202 DEBG [sc] ox: connecting to [::1]:44116
6482026-10-21T04:03:08.236ZOct 21 04:03:08.202 DEBG [sc] ox waiting for prompt
6492026-10-21T04:03:08.236ZOct 21 04:03:08.213 DEBG [sc] ox: logging in
6502026-10-21T04:03:08.313ZOct 21 04:03:08.302 DEBG [sc] ox: executing command `chmod +x npuvm`
6512026-10-21T04:03:08.380ZOct 21 04:03:08.369 DEBG [sc] ox: starting
6522026-10-21T04:03:08.380ZOct 21 04:03:08.369 DEBG [sc] ox: connecting to [::1]:44116
6532026-10-21T04:03:08.403ZOct 21 04:03:08.369 DEBG [sc] ox waiting for prompt
6542026-10-21T04:03:08.404ZOct 21 04:03:08.381 DEBG [sc] ox: logging in
6552026-10-21T04:03:08.469ZOct 21 04:03:08.458 DEBG [sc] ox: executing command `./npuvm install --front-ports 4 --rear-ports 0 --pkt-source vioif0 `
6562026-10-21T04:03:09.791ZOct 21 04:03:09.780 INFO cr1: enabling frr daemon bgpd
6572026-10-21T04:03:09.791ZOct 21 04:03:09.780 DEBG [sc] cr1: starting
6582026-10-21T04:03:09.791ZOct 21 04:03:09.780 DEBG [sc] cr1: connecting to [::1]:42530
6592026-10-21T04:03:09.813ZOct 21 04:03:09.780 DEBG [sc] cr1 waiting for prompt
6602026-10-21T04:03:09.814ZOct 21 04:03:09.791 DEBG [sc] cr1: logging in
6612026-10-21T04:03:09.944ZOct 21 04:03:09.933 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
6622026-10-21T04:03:10.659ZOct 21 04:03:10.647 DEBG [sc] cr2: starting
6632026-10-21T04:03:10.659ZOct 21 04:03:10.647 DEBG [sc] cr2: connecting to [::1]:32883
6642026-10-21T04:03:10.682ZOct 21 04:03:10.648 DEBG [sc] cr2 waiting for prompt
6652026-10-21T04:03:10.682ZOct 21 04:03:10.658 DEBG [sc] cr2: logging in
6662026-10-21T04:03:10.805ZOct 21 04:03:10.790 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
6672026-10-21T04:03:12.025ZOct 21 04:03:12.014 INFO cr1: stopping frr
6682026-10-21T04:03:12.048ZOct 21 04:03:12.014 DEBG [sc] cr1: starting
6692026-10-21T04:03:12.048ZOct 21 04:03:12.014 DEBG [sc] cr1: connecting to [::1]:42530
6702026-10-21T04:03:12.048ZOct 21 04:03:12.014 DEBG [sc] cr1 waiting for prompt
6712026-10-21T04:03:12.048ZOct 21 04:03:12.024 DEBG [sc] cr1: logging in
6722026-10-21T04:03:12.168ZOct 21 04:03:12.157 DEBG [sc] cr1: executing command `systemctl stop frr`
6732026-10-21T04:03:12.905ZOct 21 04:03:12.894 INFO cr2: executing eos script show version
6742026-10-21T04:03:12.905ZOct 21 04:03:12.894 DEBG [sc] cr2: starting
6752026-10-21T04:03:12.905ZOct 21 04:03:12.894 DEBG [sc] cr2: connecting to [::1]:32883
6762026-10-21T04:03:12.928ZOct 21 04:03:12.894 DEBG [sc] cr2 waiting for prompt
6772026-10-21T04:03:12.928ZOct 21 04:03:12.905 DEBG [sc] cr2: logging in
6782026-10-21T04:03:13.081ZOct 21 04:03:13.070 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
6792026-10-21T04:03:14.532ZOct 21 04:03:14.521 INFO cr1: starting frr
6802026-10-21T04:03:14.532ZOct 21 04:03:14.521 DEBG [sc] cr1: starting
6812026-10-21T04:03:14.532ZOct 21 04:03:14.521 DEBG [sc] cr1: connecting to [::1]:42530
6822026-10-21T04:03:14.555ZOct 21 04:03:14.521 DEBG [sc] cr1 waiting for prompt
6832026-10-21T04:03:14.555ZOct 21 04:03:14.531 DEBG [sc] cr1: logging in
6842026-10-21T04:03:14.675ZOct 21 04:03:14.664 DEBG [sc] cr1: executing command `systemctl reset-failed frr && systemctl start frr`
6852026-10-21T04:03:15.432ZOct 21 04:03:15.415 DEBG [sc] cr2: starting
6862026-10-21T04:03:15.432ZOct 21 04:03:15.415 DEBG [sc] cr2: connecting to [::1]:32883
6872026-10-21T04:03:15.456ZOct 21 04:03:15.416 DEBG [sc] cr2 waiting for prompt
6882026-10-21T04:03:15.456ZOct 21 04:03:15.426 DEBG [sc] cr2: logging in
6892026-10-21T04:03:15.614ZOct 21 04:03:15.603 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
6902026-10-21T04:03:17.801ZOct 21 04:03:17.790 INFO cr2: executing eos script show version
6912026-10-21T04:03:17.801ZOct 21 04:03:17.790 DEBG [sc] cr2: starting
6922026-10-21T04:03:17.801ZOct 21 04:03:17.790 DEBG [sc] cr2: connecting to [::1]:32883
6932026-10-21T04:03:17.824ZOct 21 04:03:17.790 DEBG [sc] cr2 waiting for prompt
6942026-10-21T04:03:17.824ZOct 21 04:03:17.801 DEBG [sc] cr2: logging in
6952026-10-21T04:03:17.954ZOct 21 04:03:17.943 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
6962026-10-21T04:03:20.405ZOct 21 04:03:20.393 INFO cr2: executing eos script
6972026-10-21T04:03:20.405Z enable
6982026-10-21T04:03:20.405Z configure
6992026-10-21T04:03:20.405Z ipv6 unicast-routing
7002026-10-21T04:03:20.405Z ip routing ipv6 interfaces
7012026-10-21T04:03:20.405Z ip routing
7022026-10-21T04:03:20.405Z ip route 1.2.3.0/24 null0
7032026-10-21T04:03:20.405Z ipv6 route fd99::/64 null0
7042026-10-21T04:03:20.405Z interface et1
7052026-10-21T04:03:20.405Z no switchport
7062026-10-21T04:03:20.405Z ipv6 enable
7072026-10-21T04:03:20.405Z
7082026-10-21T04:03:20.406Z router bgp 45
7092026-10-21T04:03:20.406Z router-id 1.2.3.1
7102026-10-21T04:03:20.406Z no bgp default ipv4-unicast
7112026-10-21T04:03:20.406Z timers bgp 2 6
7122026-10-21T04:03:20.406Z neighbor ebgp peer group
7132026-10-21T04:03:20.406Z neighbor ebgp remote-as 33
7142026-10-21T04:03:20.406Z neighbor interface Et1 peer-group ebgp
7152026-10-21T04:03:20.406Z address-family ipv4
7162026-10-21T04:03:20.406Z neighbor ebgp activate
7172026-10-21T04:03:20.406Z neighbor ebgp next-hop address-family ipv6 originate
7182026-10-21T04:03:20.406Z network 1.2.3.0/24
7192026-10-21T04:03:20.406Z exit
7202026-10-21T04:03:20.406Z address-family ipv6
7212026-10-21T04:03:20.406Z neighbor ebgp activate
7222026-10-21T04:03:20.406Z neighbor ebgp next-hop address-family ipv6 originate
7232026-10-21T04:03:20.406Z network fd99::/64
7242026-10-21T04:03:20.406Z exit
7252026-10-21T04:03:20.406Z exit
7262026-10-21T04:03:20.406Z
7272026-10-21T04:03:20.406ZOct 21 04:03:20.393 DEBG [sc] cr2: starting
7282026-10-21T04:03:20.406ZOct 21 04:03:20.393 DEBG [sc] cr2: connecting to [::1]:32883
7292026-10-21T04:03:20.430ZOct 21 04:03:20.394 DEBG [sc] cr2 waiting for prompt
7302026-10-21T04:03:20.430ZOct 21 04:03:20.404 DEBG [sc] cr2: logging in
7312026-10-21T04:03:20.549ZOct 21 04:03:20.537 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
7322026-10-21T04:03:20.549Z enable
7332026-10-21T04:03:20.549Z configure
7342026-10-21T04:03:20.549Z ipv6 unicast-routing
7352026-10-21T04:03:20.549Z ip routing ipv6 interfaces
7362026-10-21T04:03:20.549Z ip routing
7372026-10-21T04:03:20.549Z ip route 1.2.3.0/24 null0
7382026-10-21T04:03:20.549Z ipv6 route fd99::/64 null0
7392026-10-21T04:03:20.549Z interface et1
7402026-10-21T04:03:20.549Z no switchport
7412026-10-21T04:03:20.549Z ipv6 enable
7422026-10-21T04:03:20.549Z
7432026-10-21T04:03:20.549Z router bgp 45
7442026-10-21T04:03:20.549Z router-id 1.2.3.1
7452026-10-21T04:03:20.549Z no bgp default ipv4-unicast
7462026-10-21T04:03:20.549Z timers bgp 2 6
7472026-10-21T04:03:20.549Z neighbor ebgp peer group
7482026-10-21T04:03:20.549Z neighbor ebgp remote-as 33
7492026-10-21T04:03:20.549Z neighbor interface Et1 peer-group ebgp
7502026-10-21T04:03:20.549Z address-family ipv4
7512026-10-21T04:03:20.549Z neighbor ebgp activate
7522026-10-21T04:03:20.549Z neighbor ebgp next-hop address-family ipv6 originate
7532026-10-21T04:03:20.549Z network 1.2.3.0/24
7542026-10-21T04:03:20.549Z exit
7552026-10-21T04:03:20.549Z address-family ipv6
7562026-10-21T04:03:20.550Z neighbor ebgp activate
7572026-10-21T04:03:20.550Z neighbor ebgp next-hop address-family ipv6 originate
7582026-10-21T04:03:20.550Z network fd99::/64
7592026-10-21T04:03:20.550Z exit
7602026-10-21T04:03:20.550Z exit
7612026-10-21T04:03:20.550Z '`
7622026-10-21T04:03:22.019ZOct 21 04:03:22.007 INFO cr1: executing frr script
7632026-10-21T04:03:22.019Z configure
7642026-10-21T04:03:22.019Z ip forwarding
7652026-10-21T04:03:22.019Z ipv6 forwarding
7662026-10-21T04:03:22.019Z ip route 1.2.3.0/24 null0
7672026-10-21T04:03:22.019Z ipv6 route fd99::/64 null0
7682026-10-21T04:03:22.019Z router bgp 44
7692026-10-21T04:03:22.019Z no bgp ebgp-requires-policy
7702026-10-21T04:03:22.019Z timers bgp 2 6
7712026-10-21T04:03:22.019Z neighbor enp0s8 interface remote-as external
7722026-10-21T04:03:22.019Z neighbor enp0s8 timers connect 1
7732026-10-21T04:03:22.019Z address-family ipv4 unicast
7742026-10-21T04:03:22.019Z network 1.2.3.0/24
7752026-10-21T04:03:22.019Z neighbor enp0s8 activate
7762026-10-21T04:03:22.019Z exit-address-family
7772026-10-21T04:03:22.019Z address-family ipv6 unicast
7782026-10-21T04:03:22.019Z network fd99::/64
7792026-10-21T04:03:22.019Z neighbor enp0s8 activate
7802026-10-21T04:03:22.019Z exit-address-family
7812026-10-21T04:03:22.019Z exit
7822026-10-21T04:03:22.019Z
7832026-10-21T04:03:22.019ZOct 21 04:03:22.007 DEBG [sc] cr1: starting
7842026-10-21T04:03:22.019ZOct 21 04:03:22.007 DEBG [sc] cr1: connecting to [::1]:42530
7852026-10-21T04:03:22.043ZOct 21 04:03:22.008 DEBG [sc] cr1 waiting for prompt
7862026-10-21T04:03:22.043ZOct 21 04:03:22.019 DEBG [sc] cr1: logging in
7872026-10-21T04:03:22.163ZOct 21 04:03:22.151 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 ' '`
7882026-10-21T04:03:57.068ZOct 21 04:03:57.056 INFO ox: npuvm install output:
7892026-10-21T04:03:57.172Zdendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [----------------------------------------] 0 B/82.16 MiB (0s) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (54m) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (55m) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (55m) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (55m) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (55m) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (55m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/82.16 MiB (47m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/82.16 MiB (46m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/82.16 MiB (45m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/82.16 MiB (45m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/82.16 MiB (44m) dendrite-softnpu.tar.gz [>---------------------------------------] 24.00 KiB/82.16 MiB (44m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.00 KiB/82.16 MiB (39m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.00 KiB/82.16 MiB (38m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.00 KiB/82.16 MiB (37m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.00 KiB/82.16 MiB (37m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.00 KiB/82.16 MiB (36m) dendrite-softnpu.tar.gz [>---------------------------------------] 40.00 KiB/82.16 MiB (35m) dendrite-softnpu.tar.gz [>---------------------------------------] 56.00 KiB/82.16 MiB (32m) dendrite-softnpu.tar.gz [>---------------------------------------] 56.00 KiB/82.16 MiB (32m) dendrite-softnpu.tar.gz [>---------------------------------------] 56.00 KiB/82.16 MiB (31m) dendrite-softnpu.tar.gz [>---------------------------------------] 139.48 KiB/82.16 MiB (23m) dendrite-softnpu.tar.gz [>---------------------------------------] 347.05 KiB/82.16 MiB (12m) dendrite-softnpu.tar.gz [>---------------------------------------] 719.05 KiB/82.16 MiB (5m) dendrite-softnpu.tar.gz [>---------------------------------------] 966.79 KiB/82.16 MiB (4m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.20 MiB/82.16 MiB (3m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.54 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.78 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.09 MiB/82.16 MiB (89s) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.38 MiB/82.16 MiB (75s) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.62 MiB/82.16 MiB (68s) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.96 MiB/82.16 MiB (62s) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.22 MiB/82.16 MiB (55s) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.46 MiB/82.16 MiB (52s) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.80 MiB/82.16 MiB (48s) dendrite-softnpu.tar.gz [#>--------------------------------------] 4.06 MiB/82.16 MiB (45s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.18 MiB/82.16 MiB (42s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.40 MiB/82.16 MiB (41s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.68 MiB/82.16 MiB (39s) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.92 MiB/82.16 MiB (37s) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.28 MiB/82.16 MiB (35s) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.52 MiB/82.16 MiB (34s) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.76 MiB/82.16 MiB (33s) dendrite-softnpu.tar.gz [##>-------------------------------------] 6.12 MiB/82.16 MiB (32s) dendrite-softnpu.tar.gz [###>------------------------------------] 6.36 MiB/82.16 MiB (31s) dendrite-softnpu.tar.gz [###>------------------------------------] 6.60 MiB/82.16 MiB (30s) dendrite-softnpu.tar.gz [###>------------------------------------] 6.90 MiB/82.16 MiB (30s) dendrite-softnpu.tar.gz [###>------------------------------------] 7.20 MiB/82.16 MiB (29s) dendrite-softnpu.tar.gz [###>------------------------------------] 7.44 MiB/82.16 MiB (28s) dendrite-softnpu.tar.gz [###>------------------------------------] 7.76 MiB/82.16 MiB (27s) dendrite-softnpu.tar.gz [###>------------------------------------] 8.03 MiB/82.16 MiB (27s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.30 MiB/82.16 MiB (26s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.63 MiB/82.16 MiB (26s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.87 MiB/82.16 MiB (25s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.14 MiB/82.16 MiB (25s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.47 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.71 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [####>-----------------------------------] 10.01 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#####>----------------------------------] 10.32 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#####>----------------------------------] 10.57 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#####>----------------------------------] 10.89 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.15 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.42 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.75 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.99 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [#####>----------------------------------] 12.26 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 12.59 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [######>---------------------------------] 12.83 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.06 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.07 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.08 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.13 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.29 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.42 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.54 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.70 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.87 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 14.01 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 14.22 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [######>---------------------------------] 14.36 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 14.50 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 14.72 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 14.88 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.03 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.26 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.42 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.59 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.84 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 16.02 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [#######>--------------------------------] 16.21 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 16.46 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 16.64 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 16.89 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.10 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.29 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.58 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.78 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.98 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [########>-------------------------------] 18.28 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 18.49 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 18.70 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.02 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.24 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.47 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.80 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 20.03 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [#########>------------------------------] 20.32 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 20.61 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 20.84 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 21.20 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 21.44 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 21.68 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 22.04 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 22.28 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [##########>-----------------------------] 22.52 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 22.85 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.12 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.36 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.71 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.96 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 24.23 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [###########>----------------------------] 24.53 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 24.80 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 25.07 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 25.38 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 25.63 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 25.93 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 26.23 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [############>---------------------------] 26.48 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 26.77 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.07 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.32 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.61 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.90 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 28.17 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [#############>--------------------------] 28.47 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [#############>--------------------------] 28.74 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.04 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.34 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.58 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.88 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 30.18 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [##############>-------------------------] 30.42 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [##############>-------------------------] 30.72 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.01 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.26 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.59 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.85 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 32.13 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [###############>------------------------] 32.45 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [###############>------------------------] 32.69 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.00 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.29 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.53 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.83 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 34.13 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [################>-----------------------] 34.37 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [################>-----------------------] 34.71 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 34.97 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 35.24 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 35.56 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 35.81 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 36.11 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 36.40 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 36.65 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 36.95 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 37.24 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 37.48 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 37.80 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 38.08 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 38.33 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.68 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.92 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 39.20 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 39.52 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 39.76 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 40.07 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 40.36 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 40.60 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [###################>--------------------] 40.92 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.20 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.44 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.79 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.04 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.31 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.64 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.88 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 43.20 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 43.48 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 43.72 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.06 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.32 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.56 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.92 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 45.16 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 45.43 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 45.76 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 45.99 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 46.30 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 46.60 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 46.83 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 47.19 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 47.43 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 47.67 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 48.03 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#######################>----------------] 48.27 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#######################>----------------] 48.52 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#######################>----------------] 48.87 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#######################>----------------] 49.11 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 49.38 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 49.71 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 49.95 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.20 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.55 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.79 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [########################>---------------] 51.06 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 51.38 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 51.63 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 51.92 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.22 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.47 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.74 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.86 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.87 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.87 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.87 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.90 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.97 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 53.09 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 53.28 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 53.40 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 53.56 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 53.74 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 53.87 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.08 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.23 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.37 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.59 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.75 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.90 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 55.14 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 55.31 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [###########################>------------] 55.47 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [###########################>------------] 55.73 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 55.90 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.07 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.34 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.53 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.71 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.99 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 57.18 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 57.39 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 57.68 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 57.88 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.12 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.39 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.61 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.88 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 59.15 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 59.37 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 59.69 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 59.94 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.17 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.50 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.76 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 61.01 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 61.34 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 61.60 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 61.88 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 62.18 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 62.44 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 62.75 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 63.04 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 63.28 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 63.62 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 63.88 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.13 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.45 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.72 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.98 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 65.23 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 65.56 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [################################>-------] 65.83 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [################################>-------] 66.09 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [################################>-------] 66.39 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 66.67 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 66.92 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 67.23 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 67.51 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 67.77 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.08 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.32 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.62 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.92 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 69.20 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 69.49 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 69.76 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.04 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.35 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.60 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.89 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 71.20 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 71.44 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 71.74 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 72.04 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 72.28 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 72.60 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 72.88 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 73.15 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 73.47 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 73.72 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.01 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.32 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.56 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.85 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.15 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.40 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.72 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.99 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [#####################################>--] 76.25 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [#####################################>--] 76.59 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [#####################################>--] 76.83 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 77.08 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 77.43 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 77.67 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 77.93 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.27 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.51 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.77 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.11 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.35 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.64 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.95 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#######################################>] 80.20 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#######################################>] 80.48 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 80.79 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.05 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.36 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.63 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.90 MiB/82.16 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 (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 (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 (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 (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 (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 (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 (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
7902026-10-21T04:03:57.172Z Create boot environment: No
7912026-10-21T04:03:57.172ZCreate backup boot environment: No
7922026-10-21T04:03:57.173Z
7932026-10-21T04:03:57.173ZDOWNLOAD PKGS FILES XFER (MB) SPEED
7942026-10-21T04:03:57.173Z driver/misc/tofino 0/1 0/6 0.0/0.0 -- driver/misc/tofino 0/1 2/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 321k/s
7952026-10-21T04:03:57.173Z
7962026-10-21T04:03:57.173ZPHASE ITEMS
7972026-10-21T04:03:57.173Z Installing new actions 1/27 Installing new actions 24/27 Installing new actions 27/27
7982026-10-21T04:03:57.173Z Updating package state database working / Updating package state database working - Updating package state database Done
7992026-10-21T04:03:57.173Z Updating package cache 0/0 \ Updating package cache 0/0
8002026-10-21T04:03:57.173Z Updating image state working | Updating image state working / Updating image state Done
8012026-10-21T04:03:57.173Z 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
8022026-10-21T04:03:57.173Z Reading search index working | Reading search index Done
8032026-10-21T04:03:57.173Z Updating search index 0/1 / Updating search index 1/1
8042026-10-21T04:03:57.173Z Updating package cache 0/1 - Updating package cache 1/1
8052026-10-21T04:03:57.173Zscadm [########################################] 0 B/0 B (0s) scadm [########################################] 0 B/0 B (0s) scadm [----------------------------------------] 0 B/15.18 MiB (0s) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (7m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 24.48 KiB/15.18 MiB (6m) scadm [>---------------------------------------] 86.17 KiB/15.18 MiB (4m) scadm [>---------------------------------------] 223.69 KiB/15.18 MiB (2m) scadm [#>--------------------------------------] 529.69 KiB/15.18 MiB (63s) scadm [##>-------------------------------------] 843.69 KiB/15.18 MiB (34s) scadm [##>-------------------------------------] 1.06 MiB/15.18 MiB (25s) scadm [###>------------------------------------] 1.42 MiB/15.18 MiB (18s) scadm [####>-----------------------------------] 1.66 MiB/15.18 MiB (15s) scadm [#####>----------------------------------] 1.92 MiB/15.18 MiB (13s) scadm [#####>----------------------------------] 2.26 MiB/15.18 MiB (10s) scadm [######>---------------------------------] 2.50 MiB/15.18 MiB (9s) scadm [#######>--------------------------------] 2.86 MiB/15.18 MiB (8s) scadm [########>-------------------------------] 3.10 MiB/15.18 MiB (7s) scadm [########>-------------------------------] 3.34 MiB/15.18 MiB (7s) scadm [#########>------------------------------] 3.70 MiB/15.18 MiB (6s) scadm [##########>-----------------------------] 3.94 MiB/15.18 MiB (6s) scadm [###########>----------------------------] 4.27 MiB/15.18 MiB (5s) scadm [###########>----------------------------] 4.54 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (5s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.63 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.64 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.65 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.65 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.69 MiB/15.18 MiB (7s) scadm [############>---------------------------] 4.79 MiB/15.18 MiB (7s) scadm [#############>--------------------------] 5.00 MiB/15.18 MiB (7s) scadm [##############>-------------------------] 5.36 MiB/15.18 MiB (7s) scadm [##############>-------------------------] 5.60 MiB/15.18 MiB (7s) scadm [###############>------------------------] 5.84 MiB/15.18 MiB (6s) scadm [################>-----------------------] 6.20 MiB/15.18 MiB (6s) scadm [################>-----------------------] 6.43 MiB/15.18 MiB (6s) scadm [#################>----------------------] 6.71 MiB/15.18 MiB (6s) scadm [##################>---------------------] 7.04 MiB/15.18 MiB (6s) scadm [###################>--------------------] 7.27 MiB/15.18 MiB (5s) scadm [####################>-------------------] 7.63 MiB/15.18 MiB (5s) scadm [####################>-------------------] 7.87 MiB/15.18 MiB (5s) scadm [#####################>------------------] 8.15 MiB/15.18 MiB (5s) scadm [######################>-----------------] 8.47 MiB/15.18 MiB (4s) scadm [######################>-----------------] 8.71 MiB/15.18 MiB (4s) scadm [#######################>----------------] 9.05 MiB/15.18 MiB (4s) scadm [########################>---------------] 9.31 MiB/15.18 MiB (4s) scadm [#########################>--------------] 9.56 MiB/15.18 MiB (4s) scadm [##########################>-------------] 9.91 MiB/15.18 MiB (3s) scadm [##########################>-------------] 10.15 MiB/15.18 MiB (3s) scadm [###########################>------------] 10.49 MiB/15.18 MiB (3s) scadm [############################>-----------] 10.75 MiB/15.18 MiB (3s) scadm [############################>-----------] 11.00 MiB/15.18 MiB (3s) scadm [#############################>----------] 11.35 MiB/15.18 MiB (2s) scadm [##############################>---------] 11.59 MiB/15.18 MiB (2s) scadm [###############################>--------] 11.90 MiB/15.18 MiB (2s) scadm [################################>-------] 12.19 MiB/15.18 MiB (2s) scadm [################################>-------] 12.45 MiB/15.18 MiB (2s) scadm [#################################>------] 12.79 MiB/15.18 MiB (1s) scadm [##################################>-----] 13.03 MiB/15.18 MiB (1s) scadm [###################################>----] 13.34 MiB/15.18 MiB (1s) scadm [###################################>----] 13.62 MiB/15.18 MiB (1s) scadm [####################################>---] 13.89 MiB/15.18 MiB (1s) scadm [#####################################>--] 14.23 MiB/15.18 MiB (1s) scadm [######################################>-] 14.47 MiB/15.18 MiB (0s) scadm [######################################>-] 14.77 MiB/15.18 MiB (0s) scadm [#######################################>] 15.07 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 (6m) libsidecar_lite.so [>---------------------------------------] 25.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 25.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 25.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 25.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 25.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 25.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 41.29 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 41.29 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 41.29 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 141.69 KiB/11.89 MiB (2m) libsidecar_lite.so [#>--------------------------------------] 352.05 KiB/11.89 MiB (62s) libsidecar_lite.so [##>-------------------------------------] 664.63 KiB/11.89 MiB (35s) libsidecar_lite.so [###>------------------------------------] 970.20 KiB/11.89 MiB (20s) libsidecar_lite.so [####>-----------------------------------] 1.19 MiB/11.89 MiB (15s) libsidecar_lite.so [####>-----------------------------------] 1.44 MiB/11.89 MiB (10s) libsidecar_lite.so [####>-----------------------------------] 1.45 MiB/11.89 MiB (10s) libsidecar_lite.so [#####>----------------------------------] 1.56 MiB/11.89 MiB (9s) libsidecar_lite.so [######>---------------------------------] 1.92 MiB/11.89 MiB (8s) libsidecar_lite.so [#######>--------------------------------] 2.15 MiB/11.89 MiB (7s) libsidecar_lite.so [########>-------------------------------] 2.39 MiB/11.89 MiB (7s) libsidecar_lite.so [#########>------------------------------] 2.75 MiB/11.89 MiB (6s) libsidecar_lite.so [##########>-----------------------------] 2.99 MiB/11.89 MiB (5s) libsidecar_lite.so [##########>-----------------------------] 3.23 MiB/11.89 MiB (5s) libsidecar_lite.so [###########>----------------------------] 3.51 MiB/11.89 MiB (5s) libsidecar_lite.so [############>---------------------------] 3.83 MiB/11.89 MiB (4s) libsidecar_lite.so [#############>--------------------------] 4.10 MiB/11.89 MiB (4s) libsidecar_lite.so [##############>-------------------------] 4.35 MiB/11.89 MiB (4s) libsidecar_lite.so [###############>------------------------] 4.67 MiB/11.89 MiB (3s) libsidecar_lite.so [################>-----------------------] 4.94 MiB/11.89 MiB (3s) libsidecar_lite.so [#################>----------------------] 5.20 MiB/11.89 MiB (3s) libsidecar_lite.so [##################>---------------------] 5.51 MiB/11.89 MiB (3s) libsidecar_lite.so [###################>--------------------] 5.78 MiB/11.89 MiB (2s) libsidecar_lite.so [####################>-------------------] 6.04 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (2s) libsidecar_lite.so [######################>-----------------] 6.62 MiB/11.89 MiB (2s) libsidecar_lite.so [#######################>----------------] 6.89 MiB/11.89 MiB (2s) libsidecar_lite.so [########################>---------------] 7.22 MiB/11.89 MiB (2s) libsidecar_lite.so [#########################>--------------] 7.46 MiB/11.89 MiB (2s) libsidecar_lite.so [##########################>-------------] 7.74 MiB/11.89 MiB (1s) libsidecar_lite.so [###########################>------------] 8.06 MiB/11.89 MiB (1s) libsidecar_lite.so [###########################>------------] 8.31 MiB/11.89 MiB (1s) libsidecar_lite.so [############################>-----------] 8.57 MiB/11.89 MiB (1s) libsidecar_lite.so [#############################>----------] 8.90 MiB/11.89 MiB (1s) libsidecar_lite.so [##############################>---------] 9.17 MiB/11.89 MiB (1s) libsidecar_lite.so [###############################>--------] 9.42 MiB/11.89 MiB (1s) libsidecar_lite.so [################################>-------] 9.74 MiB/11.89 MiB (1s) libsidecar_lite.so [#################################>------] 10.01 MiB/11.89 MiB (1s) libsidecar_lite.so [##################################>-----] 10.27 MiB/11.89 MiB (0s) libsidecar_lite.so [###################################>----] 10.59 MiB/11.89 MiB (0s) libsidecar_lite.so [####################################>---] 10.85 MiB/11.89 MiB (0s) libsidecar_lite.so [#####################################>--] 11.12 MiB/11.89 MiB (0s) libsidecar_lite.so [######################################>-] 11.35 MiB/11.89 MiB (0s) libsidecar_lite.so [#######################################>] 11.70 MiB/11.89 MiB (0s)
8062026-10-21T04:03:57.173ZOct 21 04:03:57.057 DEBG [sc] ox: starting
8072026-10-21T04:03:57.173ZOct 21 04:03:57.057 DEBG [sc] ox: connecting to [::1]:44116
8082026-10-21T04:03:57.173ZOct 21 04:03:57.057 DEBG [sc] ox waiting for prompt
8092026-10-21T04:03:57.173ZOct 21 04:03:57.068 DEBG [sc] ox: logging in
8102026-10-21T04:03:57.197ZOct 21 04:03:57.145 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
8112026-10-21T04:03:57.314ZOct 21 04:03:57.303 DEBG [sc] peer: starting
8122026-10-21T04:03:57.314ZOct 21 04:03:57.303 DEBG [sc] peer: connecting to [::1]:34919
8132026-10-21T04:03:57.337ZOct 21 04:03:57.304 DEBG [sc] peer waiting for prompt
8142026-10-21T04:03:57.337ZOct 21 04:03:57.315 DEBG [sc] peer: logging in
8152026-10-21T04:03:57.404ZOct 21 04:03:57.393 DEBG [sc] peer: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
8162026-10-21T04:03:57.471ZOct 21 04:03:57.460 DEBG client request, body: None, uri: http://10.151.6.170:4676/bgp/config/routers, method: GET, operation_id: read_routers
8172026-10-21T04:03:57.494ZOct 21 04:03:57.461 DEBG client response, result: Err(reqwest::Error { kind: Request, url: "http://10.151.6.170:4676/bgp/config/routers", source: hyper_util::client::legacy::Error(Connect, ConnectError("tcp connect error", 10.151.6.170:4676, Os { code: 146, kind: ConnectionRefused, message: "Connection refused" })) }), operation_id: read_routers
8182026-10-21T04:03:57.494ZOct 21 04:03:57.461 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.6.170:4676/bgp/config/routers)
8192026-10-21T04:03:58.473ZOct 21 04:03:58.462 DEBG client request, body: None, uri: http://10.151.6.170:4676/bgp/config/routers, method: GET, operation_id: read_routers
8202026-10-21T04:03:58.496ZOct 21 04:03:58.466 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "8009977d-a5be-4b93-bd4d-f4175606f55a", "content-length": "2", "date": "Wed, 21 Oct 2026 04:03:57 GMT"} }), operation_id: read_routers
8212026-10-21T04:03:58.628ZOct 21 04:03:58.617 DEBG [sc] ox: starting
8222026-10-21T04:03:58.628ZOct 21 04:03:58.617 DEBG [sc] ox: connecting to [::1]:44116
8232026-10-21T04:03:58.651ZOct 21 04:03:58.618 DEBG [sc] ox waiting for prompt
8242026-10-21T04:03:58.651ZOct 21 04:03:58.628 DEBG [sc] ox: logging in
8252026-10-21T04:03:58.717ZOct 21 04:03:58.705 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
8262026-10-21T04:03:59.741ZOct 21 04:03:59.729 DEBG [sc] ox: starting
8272026-10-21T04:03:59.741ZOct 21 04:03:59.729 DEBG [sc] ox: connecting to [::1]:44116
8282026-10-21T04:03:59.764ZOct 21 04:03:59.731 DEBG [sc] ox waiting for prompt
8292026-10-21T04:03:59.764ZOct 21 04:03:59.731 DEBG [sc] ox: logging in
8302026-10-21T04:03:59.829ZOct 21 04:03:59.818 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
8312026-10-21T04:03:59.896ZOct 21 04:03:59.885 DEBG [sc] ox: starting
8322026-10-21T04:03:59.896ZOct 21 04:03:59.885 DEBG [sc] ox: connecting to [::1]:44116
8332026-10-21T04:03:59.919ZOct 21 04:03:59.886 DEBG [sc] ox waiting for prompt
8342026-10-21T04:03:59.919ZOct 21 04:03:59.896 DEBG [sc] ox: logging in
8352026-10-21T04:03:59.986ZOct 21 04:03:59.975 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
8362026-10-21T04:04:00.052ZOct 21 04:04:00.041 DEBG [sc] ox: starting
8372026-10-21T04:04:00.052ZOct 21 04:04:00.041 DEBG [sc] ox: connecting to [::1]:44116
8382026-10-21T04:04:00.075ZOct 21 04:04:00.041 DEBG [sc] ox waiting for prompt
8392026-10-21T04:04:00.075ZOct 21 04:04:00.052 DEBG [sc] ox: logging in
8402026-10-21T04:04:00.141ZOct 21 04:04:00.130 DEBG [sc] ox: executing command `dladm show-link tfportqsfp2_0 -p -o link`
8412026-10-21T04:04:00.207ZOct 21 04:04:00.196 DEBG [sc] ox: starting
8422026-10-21T04:04:00.207ZOct 21 04:04:00.196 DEBG [sc] ox: connecting to [::1]:44116
8432026-10-21T04:04:00.229ZOct 21 04:04:00.196 DEBG [sc] ox waiting for prompt
8442026-10-21T04:04:00.229ZOct 21 04:04:00.207 DEBG [sc] ox: logging in
8452026-10-21T04:04:00.297ZOct 21 04:04:00.286 DEBG [sc] ox: executing command `dladm show-link tfportqsfp3_0 -p -o link`
8462026-10-21T04:04:00.363ZOct 21 04:04:00.352 DEBG [sc] ox: starting
8472026-10-21T04:04:00.363ZOct 21 04:04:00.352 DEBG [sc] ox: connecting to [::1]:44116
8482026-10-21T04:04:00.386ZOct 21 04:04:00.353 DEBG [sc] ox waiting for prompt
8492026-10-21T04:04:00.386ZOct 21 04:04:00.363 DEBG [sc] ox: logging in
8502026-10-21T04:04:00.453ZOct 21 04:04:00.442 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
8512026-10-21T04:04:00.519ZOct 21 04:04:00.508 DEBG [sc] ox: starting
8522026-10-21T04:04:00.519ZOct 21 04:04:00.508 DEBG [sc] ox: connecting to [::1]:44116
8532026-10-21T04:04:00.541ZOct 21 04:04:00.508 DEBG [sc] ox waiting for prompt
8542026-10-21T04:04:00.541ZOct 21 04:04:00.518 DEBG [sc] ox: logging in
8552026-10-21T04:04:00.607ZOct 21 04:04:00.595 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
8562026-10-21T04:04:00.674ZOct 21 04:04:00.663 DEBG [sc] ox: starting
8572026-10-21T04:04:00.674ZOct 21 04:04:00.663 DEBG [sc] ox: connecting to [::1]:44116
8582026-10-21T04:04:00.696ZOct 21 04:04:00.664 DEBG [sc] ox waiting for prompt
8592026-10-21T04:04:00.696ZOct 21 04:04:00.674 DEBG [sc] ox: logging in
8602026-10-21T04:04:00.762ZOct 21 04:04:00.751 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
8612026-10-21T04:04:00.829ZOct 21 04:04:00.818 DEBG [sc] ox: starting
8622026-10-21T04:04:00.829ZOct 21 04:04:00.818 DEBG [sc] ox: connecting to [::1]:44116
8632026-10-21T04:04:00.852ZOct 21 04:04:00.818 DEBG [sc] ox waiting for prompt
8642026-10-21T04:04:00.852ZOct 21 04:04:00.828 DEBG [sc] ox: logging in
8652026-10-21T04:04:00.917ZOct 21 04:04:00.906 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
8662026-10-21T04:04:00.983ZOct 21 04:04:00.972 DEBG [sc] ox: starting
8672026-10-21T04:04:00.983ZOct 21 04:04:00.972 DEBG [sc] ox: connecting to [::1]:44116
8682026-10-21T04:04:01.006ZOct 21 04:04:00.973 DEBG [sc] ox waiting for prompt
8692026-10-21T04:04:01.006ZOct 21 04:04:00.983 DEBG [sc] ox: logging in
8702026-10-21T04:04:01.071ZOct 21 04:04:01.060 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp2_0/ll`
8712026-10-21T04:04:01.138ZOct 21 04:04:01.127 DEBG [sc] ox: starting
8722026-10-21T04:04:01.138ZOct 21 04:04:01.127 DEBG [sc] ox: connecting to [::1]:44116
8732026-10-21T04:04:01.161ZOct 21 04:04:01.128 DEBG [sc] ox waiting for prompt
8742026-10-21T04:04:01.161ZOct 21 04:04:01.137 DEBG [sc] ox: logging in
8752026-10-21T04:04:01.227ZOct 21 04:04:01.216 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp2_0/ll -p -o addr`
8762026-10-21T04:04:01.294ZOct 21 04:04:01.283 DEBG [sc] ox: starting
8772026-10-21T04:04:01.294ZOct 21 04:04:01.283 DEBG [sc] ox: connecting to [::1]:44116
8782026-10-21T04:04:01.316ZOct 21 04:04:01.283 DEBG [sc] ox waiting for prompt
8792026-10-21T04:04:01.317ZOct 21 04:04:01.294 DEBG [sc] ox: logging in
8802026-10-21T04:04:01.382ZOct 21 04:04:01.371 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp3_0/ll`
8812026-10-21T04:04:01.438ZOct 21 04:04:01.427 DEBG [sc] ox: starting
8822026-10-21T04:04:01.438ZOct 21 04:04:01.427 DEBG [sc] ox: connecting to [::1]:44116
8832026-10-21T04:04:01.461ZOct 21 04:04:01.427 DEBG [sc] ox waiting for prompt
8842026-10-21T04:04:01.461ZOct 21 04:04:01.438 DEBG [sc] ox: logging in
8852026-10-21T04:04:01.527ZOct 21 04:04:01.515 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp3_0/ll -p -o addr`
8862026-10-21T04:04:01.582ZOct 21 04:04:01.571 DEBG [sc] peer: starting
8872026-10-21T04:04:01.582ZOct 21 04:04:01.571 DEBG [sc] peer: connecting to [::1]:34919
8882026-10-21T04:04:01.605ZOct 21 04:04:01.572 DEBG [sc] peer waiting for prompt
8892026-10-21T04:04:01.605ZOct 21 04:04:01.582 DEBG [sc] peer: logging in
8902026-10-21T04:04:01.671ZOct 21 04:04:01.659 DEBG [sc] peer: executing command `ipadm create-addr -T addrconf vioif0/ll`
8912026-10-21T04:04:01.748ZOct 21 04:04:01.737 DEBG [sc] peer: starting
8922026-10-21T04:04:01.748ZOct 21 04:04:01.737 DEBG [sc] peer: connecting to [::1]:34919
8932026-10-21T04:04:01.771ZOct 21 04:04:01.737 DEBG [sc] peer waiting for prompt
8942026-10-21T04:04:01.771ZOct 21 04:04:01.748 DEBG [sc] peer: logging in
8952026-10-21T04:04:01.848ZOct 21 04:04:01.836 DEBG [sc] peer: executing command `ipadm show-addr vioif0/ll -p -o addr`
8962026-10-21T04:04:01.914ZOct 21 04:04:01.903 DEBG [sc] ox: starting
8972026-10-21T04:04:01.914ZOct 21 04:04:01.903 DEBG [sc] ox: connecting to [::1]:44116
8982026-10-21T04:04:01.937ZOct 21 04:04:01.903 DEBG [sc] ox waiting for prompt
8992026-10-21T04:04:01.937ZOct 21 04:04:01.915 DEBG [sc] ox: logging in
9002026-10-21T04:04:02.017ZOct 21 04:04:02.006 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
9012026-10-21T04:04:02.084ZOct 21 04:04:02.073 DEBG [sc] ox: starting
9022026-10-21T04:04:02.084ZOct 21 04:04:02.073 DEBG [sc] ox: connecting to [::1]:44116
9032026-10-21T04:04:02.106ZOct 21 04:04:02.073 DEBG [sc] ox waiting for prompt
9042026-10-21T04:04:02.106ZOct 21 04:04:02.083 DEBG [sc] ox: logging in
9052026-10-21T04:04:02.183ZOct 21 04:04:02.172 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
9062026-10-21T04:04:02.250ZOct 21 04:04:02.238 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/config/routers, method: GET, operation_id: read_routers
9072026-10-21T04:04:02.273ZOct 21 04:04:02.241 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "ce2f3a5d-378b-44d9-9213-a295f93287d8", "content-length": "2", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: read_routers
9082026-10-21T04:04:02.273ZOct 21 04:04:02.241 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/rib/config/bestpath/fanout, method: POST, operation_id: update_bestpath_fanout
9092026-10-21T04:04:02.273ZOct 21 04:04:02.243 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/rib/config/bestpath/fanout", status: 204, headers: {"x-request-id": "1e3dc754-eeaa-427b-96dc-86efb38e2dfc", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: update_bestpath_fanout
9102026-10-21T04:04:02.274ZOct 21 04:04:02.243 INFO adding BGP routers to both mgd nodes
9112026-10-21T04:04:02.274ZOct 21 04:04:02.243 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/router, method: PUT, operation_id: create_router
9122026-10-21T04:04:02.274ZOct 21 04:04:02.245 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/router", status: 204, headers: {"x-request-id": "152a4c2b-4dfc-4faf-a869-8bd62232dddc", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_router
9132026-10-21T04:04:02.274ZOct 21 04:04:02.245 DEBG client request, body: Some(Body), uri: http://10.151.6.170:4676/bgp/config/router, method: PUT, operation_id: create_router
9142026-10-21T04:04:02.274ZOct 21 04:04:02.248 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/bgp/config/router", status: 204, headers: {"x-request-id": "0de1e2bd-6b53-4dc7-a69c-294924eb1e07", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_router
9152026-10-21T04:04:02.274ZOct 21 04:04:02.248 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
9162026-10-21T04:04:02.274ZOct 21 04:04:02.253 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "e293e8bb-ad50-49ad-a738-454b4f48c9bf", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_unnumbered_neighbor
9172026-10-21T04:04:02.274ZOct 21 04:04:02.253 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
9182026-10-21T04:04:02.274ZOct 21 04:04:02.256 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "e666ba9e-c82f-49dd-ad21-dcb05abb7bd5", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_unnumbered_neighbor
9192026-10-21T04:04:02.274ZOct 21 04:04:02.256 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
9202026-10-21T04:04:02.274ZOct 21 04:04:02.257 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "a4d026b5-0ee3-46cc-8ad9-ae76900edd7b", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_unnumbered_neighbor
9212026-10-21T04:04:02.274ZOct 21 04:04:02.257 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
9222026-10-21T04:04:02.274ZOct 21 04:04:02.259 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "df91e249-4da5-48cb-aa27-d377898cd145", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_unnumbered_neighbor
9232026-10-21T04:04:02.274ZOct 21 04:04:02.259 DEBG client request, body: Some(Body), uri: http://10.151.6.170:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
9242026-10-21T04:04:02.298ZOct 21 04:04:02.263 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "7254b516-8a86-4fe5-98da-60f495334025", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_unnumbered_neighbor
9252026-10-21T04:04:02.298ZOct 21 04:04:02.263 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/origin4, method: PUT, operation_id: create_origin4
9262026-10-21T04:04:02.298ZOct 21 04:04:02.265 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/origin4", status: 204, headers: {"x-request-id": "ef56df90-9484-4ad5-800b-e790aedafe6d", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_origin4
9272026-10-21T04:04:02.298ZOct 21 04:04:02.265 DEBG client request, body: Some(Body), uri: http://10.151.6.169:4676/bgp/config/origin6, method: PUT, operation_id: create_origin6
9282026-10-21T04:04:02.298ZOct 21 04:04:02.266 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/config/origin6", status: 204, headers: {"x-request-id": "561b946b-a24a-4251-9789-16562d56fdae", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_origin6
9292026-10-21T04:04:02.298ZOct 21 04:04:02.266 DEBG client request, body: Some(Body), uri: http://10.151.6.170:4676/bgp/config/origin4, method: PUT, operation_id: create_origin4
9302026-10-21T04:04:02.298ZOct 21 04:04:02.267 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/bgp/config/origin4", status: 204, headers: {"x-request-id": "3e9dfc9b-b5e9-484d-b0ab-2f52d35ce7ed", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_origin4
9312026-10-21T04:04:02.298ZOct 21 04:04:02.267 DEBG client request, body: Some(Body), uri: http://10.151.6.170:4676/bgp/config/origin6, method: PUT, operation_id: create_origin6
9322026-10-21T04:04:02.298ZOct 21 04:04:02.268 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/bgp/config/origin6", status: 204, headers: {"x-request-id": "39ff1f51-cc39-4162-b75d-d0690315415b", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: create_origin6
9332026-10-21T04:04:02.298ZOct 21 04:04:02.268 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9342026-10-21T04:04:02.298ZOct 21 04:04:02.269 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "b152ef77-486f-4215-8006-9d94cbaf0cb1", "content-length": "8923", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: get_neighbors
9352026-10-21T04:04:02.298ZOct 21 04:04:02.269 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9362026-10-21T04:04:02.298ZOct 21 04:04:02.269 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "3fa09b39-da59-48ff-92ff-a006ff4127d8", "content-length": "8925", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: get_neighbors
9372026-10-21T04:04:02.298ZOct 21 04:04:02.269 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9382026-10-21T04:04:02.298ZOct 21 04:04:02.270 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "bc81d69b-d493-42a4-9100-306289610e0a", "content-length": "8926", "date": "Wed, 21 Oct 2026 04:04:01 GMT"} }), operation_id: get_neighbors
9392026-10-21T04:04:02.298Zmgd bgp cr2 -> Established: iteration 1/20: expected Some(Established), got Some(Connect)
9402026-10-21T04:04:03.283ZOct 21 04:04:03.271 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9412026-10-21T04:04:03.306ZOct 21 04:04:03.272 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "5ac2e3fa-a160-42fc-bd20-cf5bfb2c8663", "content-length": "8926", "date": "Wed, 21 Oct 2026 04:04:02 GMT"} }), operation_id: get_neighbors
9422026-10-21T04:04:03.306Zmgd bgp cr2 -> Established: iteration 2/20: expected Some(Established), got Some(Connect)
9432026-10-21T04:04:04.285ZOct 21 04:04:04.274 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9442026-10-21T04:04:04.308ZOct 21 04:04:04.275 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "83bf980c-330f-47fd-835a-daa01d7a25a6", "content-length": "9470", "date": "Wed, 21 Oct 2026 04:04:03 GMT"} }), operation_id: get_neighbors
9452026-10-21T04:04:04.309ZOct 21 04:04:04.275 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9462026-10-21T04:04:04.309ZOct 21 04:04:04.276 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "e27b8910-4697-48e0-bb61-ef0afa5cea91", "content-length": "9470", "date": "Wed, 21 Oct 2026 04:04:03 GMT"} }), operation_id: get_neighbors
9472026-10-21T04:04:04.309Zmgd bgp cr3 -> Established: iteration 1/20: expected Some(Established), got Some(OpenConfirm)
9482026-10-21T04:04:05.288ZOct 21 04:04:05.277 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9492026-10-21T04:04:05.311ZOct 21 04:04:05.278 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "ce881237-7051-405a-ba90-a4abede2dd68", "content-length": "9487", "date": "Wed, 21 Oct 2026 04:04:04 GMT"} }), operation_id: get_neighbors
9502026-10-21T04:04:05.311ZOct 21 04:04:05.278 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9512026-10-21T04:04:05.311ZOct 21 04:04:05.279 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "3cc81158-2ad1-456d-8c76-5955d4b002d4", "content-length": "9488", "date": "Wed, 21 Oct 2026 04:04:04 GMT"} }), operation_id: get_neighbors
9522026-10-21T04:04:05.312Zmgd bgp peer -> Established: iteration 1/20: expected Some(Established), got Some(Connect)
9532026-10-21T04:04:06.291ZOct 21 04:04:06.280 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9542026-10-21T04:04:06.314ZOct 21 04:04:06.281 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "67126d67-d05a-4531-89c1-b453fda7b186", "content-length": "9488", "date": "Wed, 21 Oct 2026 04:04:05 GMT"} }), operation_id: get_neighbors
9552026-10-21T04:04:06.314Zmgd bgp peer -> Established: iteration 2/20: expected Some(Established), got Some(Connect)
9562026-10-21T04:04:07.297ZOct 21 04:04:07.283 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9572026-10-21T04:04:07.321ZOct 21 04:04:07.283 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "c04ba4cc-1103-42d2-abc5-190d4983f52d", "content-length": "9731", "date": "Wed, 21 Oct 2026 04:04:06 GMT"} }), operation_id: get_neighbors
9582026-10-21T04:04:07.321Zmgd bgp peer -> Established: iteration 3/20: expected Some(Established), got Some(ConnectionCollision)
9592026-10-21T04:04:08.296ZOct 21 04:04:08.285 DEBG client request, body: None, uri: http://10.151.6.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9602026-10-21T04:04:08.318ZOct 21 04:04:08.286 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "d8aad265-977f-4601-b741-e2856dad5f25", "content-length": "9724", "date": "Wed, 21 Oct 2026 04:04:07 GMT"} }), operation_id: get_neighbors
9612026-10-21T04:04:08.318ZOct 21 04:04:08.286 DEBG client request, body: None, uri: http://10.151.6.170:4676/bgp/status/neighbors?asn=47, method: GET, operation_id: get_neighbors
9622026-10-21T04:04:08.318ZOct 21 04:04:08.287 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/bgp/status/neighbors?asn=47", status: 200, headers: {"content-type": "application/json", "x-request-id": "bbd8fede-b215-45b4-b2e9-6a6176506853", "content-length": "2380", "date": "Wed, 21 Oct 2026 04:04:07 GMT"} }), operation_id: get_neighbors
9632026-10-21T04:04:08.318ZOct 21 04:04:08.287 DEBG client request, body: None, uri: http://10.151.6.169:4676/rib/status/imported?address_family=Ipv4, method: GET, operation_id: get_rib_imported
9642026-10-21T04:04:08.318ZOct 21 04:04:08.287 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/rib/status/imported?address_family=Ipv4", status: 200, headers: {"content-type": "application/json", "x-request-id": "ca7f0d06-ec2e-4484-ac96-c0e37ddf1088", "content-length": "1020", "date": "Wed, 21 Oct 2026 04:04:07 GMT"} }), operation_id: get_rib_imported
9652026-10-21T04:04:08.318ZOct 21 04:04:08.287 DEBG client request, body: None, uri: http://10.151.6.169:4676/rib/status/imported?address_family=Ipv6, method: GET, operation_id: get_rib_imported
9662026-10-21T04:04:08.318ZOct 21 04:04:08.288 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/rib/status/imported?address_family=Ipv6", status: 200, headers: {"content-type": "application/json", "x-request-id": "ab922bb2-6304-41d7-bbe8-e739b8d924f2", "content-length": "1019", "date": "Wed, 21 Oct 2026 04:04:07 GMT"} }), operation_id: get_rib_imported
9672026-10-21T04:04:08.319ZOct 21 04:04:08.288 DEBG client request, body: None, uri: http://10.151.6.169:4676/rib/status/selected?address_family=Ipv4, method: GET, operation_id: get_rib_selected
9682026-10-21T04:04:08.319ZOct 21 04:04:08.288 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/rib/status/selected?address_family=Ipv4", status: 200, headers: {"content-type": "application/json", "x-request-id": "18699560-0244-47b0-a8e3-77a776fe92b7", "content-length": "1020", "date": "Wed, 21 Oct 2026 04:04:07 GMT"} }), operation_id: get_rib_selected
9692026-10-21T04:04:08.319ZOct 21 04:04:08.288 DEBG client request, body: None, uri: http://10.151.6.169:4676/rib/status/selected?address_family=Ipv6, method: GET, operation_id: get_rib_selected
9702026-10-21T04:04:08.319ZOct 21 04:04:08.289 DEBG client response, result: Ok(Response { url: "http://10.151.6.169:4676/rib/status/selected?address_family=Ipv6", status: 200, headers: {"content-type": "application/json", "x-request-id": "06b91d3f-37b7-44dc-887e-54d11fdb0bd0", "content-length": "1019", "date": "Wed, 21 Oct 2026 04:04:07 GMT"} }), operation_id: get_rib_selected
9712026-10-21T04:04:09.163ZOct 21 04:04:09.152 INFO cr1: executing frr script show ip bgp json
9722026-10-21T04:04:09.163ZOct 21 04:04:09.152 DEBG [sc] cr1: starting
9732026-10-21T04:04:09.186ZOct 21 04:04:09.152 DEBG [sc] cr1: connecting to [::1]:42530
9742026-10-21T04:04:09.186ZOct 21 04:04:09.152 INFO cr2: executing eos script show ip bgp | json
9752026-10-21T04:04:09.186ZOct 21 04:04:09.152 DEBG [sc] cr2: starting
9762026-10-21T04:04:09.186ZOct 21 04:04:09.152 DEBG [sc] cr2: connecting to [::1]:32883
9772026-10-21T04:04:09.186ZOct 21 04:04:09.152 INFO cr3: executing juniper script show route 4.5.6.0/24 protocol bgp
9782026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG [sc] cr3: starting
9792026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG [sc] cr3: connecting to [::1]:54502
9802026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG client request, body: None, uri: http://10.151.6.170:4676/rib/status/imported?address_family=Ipv4, method: GET, operation_id: get_rib_imported
9812026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG [sc] cr3 waiting for prompt
9822026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG [sc] cr2 waiting for prompt
9832026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG [sc] cr1 waiting for prompt
9842026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/rib/status/imported?address_family=Ipv4", status: 200, headers: {"content-type": "application/json", "x-request-id": "939acb89-b2f6-4928-815a-1e18eee4e663", "content-length": "253", "date": "Wed, 21 Oct 2026 04:04:08 GMT"} }), operation_id: get_rib_imported
9852026-10-21T04:04:09.186ZOct 21 04:04:09.153 DEBG client request, body: None, uri: http://10.151.6.170:4676/rib/status/imported?address_family=Ipv6, method: GET, operation_id: get_rib_imported
9862026-10-21T04:04:09.186ZOct 21 04:04:09.154 DEBG client response, result: Ok(Response { url: "http://10.151.6.170:4676/rib/status/imported?address_family=Ipv6", status: 200, headers: {"content-type": "application/json", "x-request-id": "3623a047-a8e1-4384-b661-8172ccbf4e1d", "content-length": "252", "date": "Wed, 21 Oct 2026 04:04:08 GMT"} }), operation_id: get_rib_imported
9872026-10-21T04:04:09.186ZOct 21 04:04:09.165 DEBG [sc] cr1: logging in
9882026-10-21T04:04:09.186ZOct 21 04:04:09.165 DEBG [sc] cr2: logging in
9892026-10-21T04:04:09.186ZOct 21 04:04:09.165 DEBG [sc] cr3: logging in
9902026-10-21T04:04:09.549ZOct 21 04:04:09.538 DEBG [sc] cr1: executing command `/usr/bin/vtysh -c 'show ip bgp json'`
9912026-10-21T04:04:09.572ZOct 21 04:04:09.543 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
9922026-10-21T04:04:09.572ZOct 21 04:04:09.558 DEBG [sc] cr3: executing command `docker exec crpd1 cli -c 'show route 4.5.6.0/24 protocol bgp'`
9932026-10-21T04:04:11.681ZOct 21 04:04:11.670 INFO cr1: executing frr script show bgp json
9942026-10-21T04:04:11.681ZOct 21 04:04:11.670 DEBG [sc] cr1: starting
9952026-10-21T04:04:11.681ZOct 21 04:04:11.670 DEBG [sc] cr1: connecting to [::1]:42530
9962026-10-21T04:04:11.704ZOct 21 04:04:11.671 DEBG [sc] cr1 waiting for prompt
9972026-10-21T04:04:11.704ZOct 21 04:04:11.681 DEBG [sc] cr1: logging in
9982026-10-21T04:04:11.759ZOct 21 04:04:11.748 INFO cr3: executing juniper script show route fdee::/64 protocol bgp
9992026-10-21T04:04:11.781ZOct 21 04:04:11.748 DEBG [sc] cr3: starting
10002026-10-21T04:04:11.781ZOct 21 04:04:11.748 DEBG [sc] cr3: connecting to [::1]:54502
10012026-10-21T04:04:11.781ZOct 21 04:04:11.748 DEBG [sc] cr3 waiting for prompt
10022026-10-21T04:04:11.781ZOct 21 04:04:11.759 DEBG [sc] cr3: logging in
10032026-10-21T04:04:11.825ZOct 21 04:04:11.814 DEBG [sc] cr1: executing command `/usr/bin/vtysh -c 'show bgp json'`
10042026-10-21T04:04:11.902ZOct 21 04:04:11.891 DEBG [sc] cr3: executing command `docker exec crpd1 cli -c 'show route fdee::/64 protocol bgp'`
10052026-10-21T04:04:11.998ZOct 21 04:04:11.987 INFO cr2: executing eos script show ipv6 bgp | json
10062026-10-21T04:04:11.998ZOct 21 04:04:11.987 DEBG [sc] cr2: starting
10072026-10-21T04:04:11.998ZOct 21 04:04:11.987 DEBG [sc] cr2: connecting to [::1]:32883
10082026-10-21T04:04:12.021ZOct 21 04:04:11.987 DEBG [sc] cr2 waiting for prompt
10092026-10-21T04:04:12.021ZOct 21 04:04:11.998 DEBG [sc] cr2: logging in
10102026-10-21T04:04:12.174ZOct 21 04:04:12.163 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
10112026-10-21T04:04:14.591ZOct 21 04:04:14.579 INFO interop bgp unnumbered test passed 🎉
10122026-10-21T04:04:14.614Z+ (( status != 0 ))
10132026-10-21T04:04:14.614Z+ pfexec ./falcon-lab cleanup interop bgp-unnumbered
10142026-10-21T04:04:14.637ZOct 21 04:04:14.615 INFO destroying runner for deployment interop_bgpu
10152026-10-21T04:04:14.637ZOct 21 04:04:14.615 INFO destroying deployment interop_bgpu
10162026-10-21T04:04:14.637ZOct 21 04:04:14.615 INFO destroying nodes
10172026-10-21T04:04:14.736ZOct 21 04:04:14.725 INFO destroying links
10182026-10-21T04:04:14.736ZOct 21 04:04:14.725 INFO destroying link interop_bgpu_ox_sn_vnic0
10192026-10-21T04:04:14.797ZOct 21 04:04:14.729 INFO destroying link interop_bgpu_ox_sn_sim0
10202026-10-21T04:04:14.797ZOct 21 04:04:14.731 INFO destroying link interop_bgpu_cr1_vn_vnic0
10212026-10-21T04:04:15.745ZOct 21 04:04:15.734 INFO destroying link interop_bgpu_cr1_vn_sim0
10222026-10-21T04:04:15.768ZOct 21 04:04:15.734 INFO destroying link interop_bgpu_ox_sn_vnic1
10232026-10-21T04:04:15.769ZOct 21 04:04:15.736 INFO destroying link interop_bgpu_ox_sn_sim1
10242026-10-21T04:04:15.769ZOct 21 04:04:15.737 INFO destroying link interop_bgpu_cr2_vn_vnic0
10252026-10-21T04:04:15.769ZOct 21 04:04:15.738 INFO destroying link interop_bgpu_cr2_vn_sim0
10262026-10-21T04:04:15.769ZOct 21 04:04:15.739 INFO destroying link interop_bgpu_ox_sn_vnic2
10272026-10-21T04:04:15.769ZOct 21 04:04:15.740 INFO destroying link interop_bgpu_ox_sn_sim2
10282026-10-21T04:04:15.769ZOct 21 04:04:15.741 INFO destroying link interop_bgpu_cr3_vn_vnic0
10292026-10-21T04:04:15.769ZOct 21 04:04:15.742 INFO destroying link interop_bgpu_cr3_vn_sim0
10302026-10-21T04:04:15.769ZOct 21 04:04:15.743 INFO destroying link interop_bgpu_ox_sn_vnic3
10312026-10-21T04:04:15.769ZOct 21 04:04:15.744 INFO destroying link interop_bgpu_ox_sn_sim3
10322026-10-21T04:04:15.769ZOct 21 04:04:15.745 INFO destroying link interop_bgpu_peer_vn_vnic0
10332026-10-21T04:04:15.769ZOct 21 04:04:15.746 INFO destroying link interop_bgpu_peer_vn_sim0
10342026-10-21T04:04:15.769ZOct 21 04:04:15.747 INFO destroying external links
10352026-10-21T04:04:15.769ZOct 21 04:04:15.747 INFO destroying external link interop_bgpu_ox_vn_vnic4
10362026-10-21T04:04:15.769ZOct 21 04:04:15.748 INFO destroying external link interop_bgpu_cr1_vn_vnic1
10372026-10-21T04:04:15.769ZOct 21 04:04:15.749 INFO destroying external link interop_bgpu_cr2_vn_vnic1
10382026-10-21T04:04:15.769ZOct 21 04:04:15.750 INFO destroying external link interop_bgpu_cr3_vn_vnic1
10392026-10-21T04:04:15.769ZOct 21 04:04:15.751 INFO destroying external link interop_bgpu_peer_vn_vnic1
10402026-10-21T04:04:15.769ZOct 21 04:04:15.753 INFO destroying images
10412026-10-21T04:04:16.341ZOct 21 04:04:16.330 INFO destroying workspace at .falcon
10422026-10-21T04:04:16.364Z+ return 0
10432026-10-21T04:04:16.364Z+ run_test interop bfd-static-routing
10442026-10-21T04:04:16.364Z+ local topology=interop
10452026-10-21T04:04:16.364Z+ local scenario=bfd-static-routing
10462026-10-21T04:04:16.364Z+ local test_name=interop-bfd-static-routing
10472026-10-21T04:04:16.364Z+ local status=0
10482026-10-21T04:04:16.364Z+ clear_falcon_workspace_files
10492026-10-21T04:04:16.364Z+ [[ -d .falcon ]]
10502026-10-21T04:04:16.364Z+ find .falcon -maxdepth 1 -type f -exec rm -f '{}' +
10512026-10-21T04:04:16.364Z+ RUST_LOG=debug
10522026-10-21T04:04:16.364Z+ pfexec ./falcon-lab run interop bfd-static-routing --no-cleanup
10532026-10-21T04:04:16.387ZOct 21 04:04:16.367 DEBG using default route interface igb0
10542026-10-21T04:04:16.387ZOct 21 04:04:16.367 DEBG using default route interface igb0
10552026-10-21T04:04:16.387ZOct 21 04:04:16.367 DEBG using default route interface igb0
10562026-10-21T04:04:16.387ZOct 21 04:04:16.367 DEBG using default route interface igb0
10572026-10-21T04:04:16.387ZOct 21 04:04:16.367 DEBG using default route interface igb0
10582026-10-21T04:04:16.387ZOct 21 04:04:16.367 INFO interop_bfd: launching interop topology
10592026-10-21T04:04:16.387ZOct 21 04:04:16.367 INFO launching runner: deployment(interop_bfd) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
10602026-10-21T04:04:16.387ZOct 21 04:04:16.367 INFO starting preflight for deployment interop_bfd
10612026-10-21T04:04:17.246ZOct 21 04:04:17.235 INFO creating links
10622026-10-21T04:04:17.246ZOct 21 04:04:17.235 DEBG destroying link interop_bfd_ox_sn_vnic0
10632026-10-21T04:04:17.246ZOct 21 04:04:17.235 DEBG destroying link interop_bfd_ox_sn_sim0
10642026-10-21T04:04:17.246ZOct 21 04:04:17.235 INFO creating simnet link 'interop_bfd_ox_sn_sim0'
10652026-10-21T04:04:17.269ZOct 21 04:04:17.238 INFO creating vnic link 'interop_bfd_ox_sn_vnic0'
10662026-10-21T04:04:17.269ZOct 21 04:04:17.245 DEBG link pair created
10672026-10-21T04:04:17.269ZOct 21 04:04:17.245 DEBG destroying link interop_bfd_cr1_vn_vnic0
10682026-10-21T04:04:17.269ZOct 21 04:04:17.245 DEBG destroying link interop_bfd_cr1_vn_sim0
10692026-10-21T04:04:17.269ZOct 21 04:04:17.245 INFO creating simnet link 'interop_bfd_cr1_vn_sim0'
10702026-10-21T04:04:17.270ZOct 21 04:04:17.246 INFO creating vnic link 'interop_bfd_cr1_vn_vnic0'
10712026-10-21T04:04:17.270ZOct 21 04:04:17.252 DEBG link pair created
10722026-10-21T04:04:17.270ZOct 21 04:04:17.252 DEBG destroying link interop_bfd_ox_sn_vnic1
10732026-10-21T04:04:17.270ZOct 21 04:04:17.252 DEBG destroying link interop_bfd_ox_sn_sim1
10742026-10-21T04:04:17.270ZOct 21 04:04:17.252 INFO creating simnet link 'interop_bfd_ox_sn_sim1'
10752026-10-21T04:04:17.270ZOct 21 04:04:17.254 INFO creating vnic link 'interop_bfd_ox_sn_vnic1'
10762026-10-21T04:04:17.293ZOct 21 04:04:17.260 DEBG link pair created
10772026-10-21T04:04:17.293ZOct 21 04:04:17.260 DEBG destroying link interop_bfd_cr2_vn_vnic0
10782026-10-21T04:04:17.293ZOct 21 04:04:17.260 DEBG destroying link interop_bfd_cr2_vn_sim0
10792026-10-21T04:04:17.293ZOct 21 04:04:17.260 INFO creating simnet link 'interop_bfd_cr2_vn_sim0'
10802026-10-21T04:04:17.293ZOct 21 04:04:17.261 INFO creating vnic link 'interop_bfd_cr2_vn_vnic0'
10812026-10-21T04:04:17.293ZOct 21 04:04:17.268 DEBG link pair created
10822026-10-21T04:04:17.293ZOct 21 04:04:17.268 DEBG destroying link interop_bfd_ox_sn_vnic2
10832026-10-21T04:04:17.293ZOct 21 04:04:17.268 DEBG destroying link interop_bfd_ox_sn_sim2
10842026-10-21T04:04:17.293ZOct 21 04:04:17.268 INFO creating simnet link 'interop_bfd_ox_sn_sim2'
10852026-10-21T04:04:17.293ZOct 21 04:04:17.269 INFO creating vnic link 'interop_bfd_ox_sn_vnic2'
10862026-10-21T04:04:17.293ZOct 21 04:04:17.276 DEBG link pair created
10872026-10-21T04:04:17.293ZOct 21 04:04:17.276 DEBG destroying link interop_bfd_cr3_vn_vnic0
10882026-10-21T04:04:17.293ZOct 21 04:04:17.276 DEBG destroying link interop_bfd_cr3_vn_sim0
10892026-10-21T04:04:17.293ZOct 21 04:04:17.276 INFO creating simnet link 'interop_bfd_cr3_vn_sim0'
10902026-10-21T04:04:17.293ZOct 21 04:04:17.277 INFO creating vnic link 'interop_bfd_cr3_vn_vnic0'
10912026-10-21T04:04:17.316ZOct 21 04:04:17.284 DEBG link pair created
10922026-10-21T04:04:17.316ZOct 21 04:04:17.284 DEBG destroying link interop_bfd_ox_sn_vnic3
10932026-10-21T04:04:17.316ZOct 21 04:04:17.284 DEBG destroying link interop_bfd_ox_sn_sim3
10942026-10-21T04:04:17.316ZOct 21 04:04:17.284 INFO creating simnet link 'interop_bfd_ox_sn_sim3'
10952026-10-21T04:04:17.316ZOct 21 04:04:17.285 INFO creating vnic link 'interop_bfd_ox_sn_vnic3'
10962026-10-21T04:04:17.316ZOct 21 04:04:17.292 DEBG link pair created
10972026-10-21T04:04:17.316ZOct 21 04:04:17.292 DEBG destroying link interop_bfd_peer_vn_vnic0
10982026-10-21T04:04:17.316ZOct 21 04:04:17.292 DEBG destroying link interop_bfd_peer_vn_sim0
10992026-10-21T04:04:17.316ZOct 21 04:04:17.292 INFO creating simnet link 'interop_bfd_peer_vn_sim0'
11002026-10-21T04:04:17.316ZOct 21 04:04:17.293 INFO creating vnic link 'interop_bfd_peer_vn_vnic0'
11012026-10-21T04:04:17.316ZOct 21 04:04:17.300 DEBG link pair created
11022026-10-21T04:04:17.316ZOct 21 04:04:17.300 INFO creating external links
11032026-10-21T04:04:17.316ZOct 21 04:04:17.300 DEBG destroying external link interop_bfd_ox_vn_vnic4
11042026-10-21T04:04:17.317ZOct 21 04:04:17.300 INFO creating external link interop_bfd_ox_vn_vnic4
11052026-10-21T04:04:17.317ZOct 21 04:04:17.301 DEBG external link interop_bfd_ox_vn_vnic4@igb0 created
11062026-10-21T04:04:17.317ZOct 21 04:04:17.301 DEBG destroying external link interop_bfd_cr1_vn_vnic1
11072026-10-21T04:04:17.317ZOct 21 04:04:17.301 INFO creating external link interop_bfd_cr1_vn_vnic1
11082026-10-21T04:04:17.317ZOct 21 04:04:17.302 DEBG external link interop_bfd_cr1_vn_vnic1@igb0 created
11092026-10-21T04:04:17.317ZOct 21 04:04:17.302 DEBG destroying external link interop_bfd_cr2_vn_vnic1
11102026-10-21T04:04:17.317ZOct 21 04:04:17.302 INFO creating external link interop_bfd_cr2_vn_vnic1
11112026-10-21T04:04:17.317ZOct 21 04:04:17.303 DEBG external link interop_bfd_cr2_vn_vnic1@igb0 created
11122026-10-21T04:04:17.317ZOct 21 04:04:17.303 DEBG destroying external link interop_bfd_cr3_vn_vnic1
11132026-10-21T04:04:17.317ZOct 21 04:04:17.303 INFO creating external link interop_bfd_cr3_vn_vnic1
11142026-10-21T04:04:17.317ZOct 21 04:04:17.305 DEBG external link interop_bfd_cr3_vn_vnic1@igb0 created
11152026-10-21T04:04:17.317ZOct 21 04:04:17.305 DEBG destroying external link interop_bfd_peer_vn_vnic1
11162026-10-21T04:04:17.317ZOct 21 04:04:17.305 INFO creating external link interop_bfd_peer_vn_vnic1
11172026-10-21T04:04:17.340ZOct 21 04:04:17.306 DEBG external link interop_bfd_peer_vn_vnic1@igb0 created
11182026-10-21T04:04:17.340ZOct 21 04:04:17.306 INFO creating nodes
11192026-10-21T04:04:17.340ZOct 21 04:04:17.306 INFO ox: launching node
11202026-10-21T04:04:17.340ZOct 21 04:04:17.308 INFO cr1: launching node
11212026-10-21T04:04:17.340ZOct 21 04:04:17.310 INFO cr2: launching node
11222026-10-21T04:04:17.340ZOct 21 04:04:17.312 INFO cr3: launching node
11232026-10-21T04:04:17.340ZOct 21 04:04:17.314 INFO peer: launching node
11242026-10-21T04:04:17.340ZOct 21 04:04:17.328 INFO launched instance ox with pid 987 on port 47315
11252026-10-21T04:04:17.340ZOct 21 04:04:17.328 INFO ox: instance ensure
11262026-10-21T04:04:17.340ZOct 21 04:04:17.329 INFO launched instance cr1 with pid 988 on port 40095
11272026-10-21T04:04:17.340ZOct 21 04:04:17.329 INFO cr1: instance ensure
11282026-10-21T04:04:17.363ZOct 21 04:04:17.329 INFO launched instance cr2 with pid 989 on port 39069
11292026-10-21T04:04:17.363ZOct 21 04:04:17.329 INFO cr2: instance ensure
11302026-10-21T04:04:17.363ZOct 21 04:04:17.330 INFO launched instance cr3 with pid 990 on port 58753
11312026-10-21T04:04:17.363ZOct 21 04:04:17.330 INFO cr3: instance ensure
11322026-10-21T04:04:17.363ZOct 21 04:04:17.330 INFO launched instance peer with pid 991 on port 45801
11332026-10-21T04:04:17.363ZOct 21 04:04:17.330 INFO peer: instance ensure
11342026-10-21T04:04:20.802ZOct 21 04:04:20.791 INFO peer: instance ensure completed after 0 retries
11352026-10-21T04:04:20.802ZOct 21 04:04:20.791 INFO peer: instance run
11362026-10-21T04:04:20.825ZOct 21 04:04:20.792 DEBG [sc] peer: starting
11372026-10-21T04:04:20.825ZOct 21 04:04:20.792 DEBG [sc] peer: connecting to [::1]:45801
11382026-10-21T04:04:20.825ZOct 21 04:04:20.792 DEBG [sc] peer waiting for prompt
11392026-10-21T04:04:21.122ZOct 21 04:04:21.110 INFO cr2: instance ensure completed after 0 retries
11402026-10-21T04:04:21.122ZOct 21 04:04:21.110 INFO cr2: instance run
11412026-10-21T04:04:21.144ZOct 21 04:04:21.110 DEBG [sc] cr2: starting
11422026-10-21T04:04:21.144ZOct 21 04:04:21.110 DEBG [sc] cr2: connecting to [::1]:39069
11432026-10-21T04:04:21.144ZOct 21 04:04:21.111 DEBG [sc] cr2 waiting for prompt
11442026-10-21T04:04:21.167ZOct 21 04:04:21.136 INFO cr3: instance ensure completed after 0 retries
11452026-10-21T04:04:21.167ZOct 21 04:04:21.136 INFO cr3: instance run
11462026-10-21T04:04:21.190ZOct 21 04:04:21.167 INFO ox: instance ensure completed after 0 retries
11472026-10-21T04:04:21.190ZOct 21 04:04:21.167 INFO ox: instance run
11482026-10-21T04:04:21.190ZOct 21 04:04:21.168 DEBG [sc] ox: starting
11492026-10-21T04:04:21.190ZOct 21 04:04:21.168 DEBG [sc] ox: connecting to [::1]:47315
11502026-10-21T04:04:21.190ZOct 21 04:04:21.169 DEBG [sc] ox waiting for prompt
11512026-10-21T04:04:21.219ZOct 21 04:04:21.208 INFO cr1: instance ensure completed after 0 retries
11522026-10-21T04:04:21.219ZOct 21 04:04:21.208 INFO cr1: instance run
11532026-10-21T04:04:21.242ZOct 21 04:04:21.208 DEBG [sc] cr1: starting
11542026-10-21T04:04:21.242ZOct 21 04:04:21.208 DEBG [sc] cr1: connecting to [::1]:40095
11552026-10-21T04:04:21.242ZOct 21 04:04:21.209 DEBG [sc] cr1 waiting for prompt
11562026-10-21T04:04:38.474ZOct 21 04:04:38.463 DEBG [sc] cr1: logging in
11572026-10-21T04:04:38.852ZOct 21 04:04:38.841 INFO cr1: mounting /opt/cargo-bay
11582026-10-21T04:04:38.852ZOct 21 04:04:38.841 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; mount -t 9p -o ro,msize=65536 /opt/cargo-bay /opt/cargo-bay`
11592026-10-21T04:04:38.886ZOct 21 04:04:38.875 DEBG [sc] cr1: executing command `cd`
11602026-10-21T04:04:38.909ZOct 21 04:04:38.886 INFO cr1: finished mounting /opt/cargo-bay
11612026-10-21T04:04:38.909ZOct 21 04:04:38.886 DEBG [sc] cr1: executing command `hostname cr1`
11622026-10-21T04:04:40.482ZOct 21 04:04:40.471 DEBG [sc] peer: logging in
11632026-10-21T04:04:40.505ZOct 21 04:04:40.481 DEBG [sc] ox: logging in
11642026-10-21T04:04:40.695ZOct 21 04:04:40.684 INFO peer: mounting /opt/cargo-bay
11652026-10-21T04:04:40.695ZOct 21 04:04:40.684 DEBG [sc] peer: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
11662026-10-21T04:04:40.721ZOct 21 04:04:40.710 INFO ox: mounting /opt/cargo-bay
11672026-10-21T04:04:40.721ZOct 21 04:04:40.710 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
11682026-10-21T04:04:41.936ZOct 21 04:04:41.925 DEBG [sc] peer: executing command `cd`
11692026-10-21T04:04:41.959ZOct 21 04:04:41.936 INFO peer: finished mounting /opt/cargo-bay
11702026-10-21T04:04:41.959ZOct 21 04:04:41.936 DEBG [sc] peer: executing command `hostname peer`
11712026-10-21T04:04:41.959ZOct 21 04:04:41.938 DEBG [sc] ox: executing command `cd`
11722026-10-21T04:04:41.959ZOct 21 04:04:41.946 DEBG [sc] peer: executing command `echo 'peer' > /etc/nodename`
11732026-10-21T04:04:41.981ZOct 21 04:04:41.949 INFO ox: finished mounting /opt/cargo-bay
11742026-10-21T04:04:41.982ZOct 21 04:04:41.949 DEBG [sc] ox: executing command `hostname ox`
11752026-10-21T04:04:41.982ZOct 21 04:04:41.957 DEBG [sc] peer: executing command `echo '::1 peer.local peer' >> /etc/hosts`
11762026-10-21T04:04:41.982ZOct 21 04:04:41.960 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
11772026-10-21T04:04:41.982ZOct 21 04:04:41.968 DEBG [sc] peer: executing command `echo '127.0.0.1 peer.local peer' >> /etc/hosts`
11782026-10-21T04:04:42.004ZOct 21 04:04:41.971 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
11792026-10-21T04:04:42.005ZOct 21 04:04:41.979 INFO peer: logging out
11802026-10-21T04:04:42.005ZOct 21 04:04:41.982 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
11812026-10-21T04:04:42.005ZOct 21 04:04:41.993 INFO ox: logging out
11822026-10-21T04:04:42.057ZOct 21 04:04:42.046 INFO peer: logged out
11832026-10-21T04:04:42.104ZOct 21 04:04:42.093 INFO ox: logged out
11842026-10-21T04:04:43.043ZOct 21 04:04:43.032 DEBG [sc] cr2: logging in
11852026-10-21T04:04:43.443ZOct 21 04:04:43.431 INFO cr2: mounting /opt/cargo-bay
11862026-10-21T04:04:43.443ZOct 21 04:04:43.431 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
11872026-10-21T04:04:43.465ZOct 21 04:04:43.443 DEBG [sc] cr2: executing command `cd`
11882026-10-21T04:04:43.465ZOct 21 04:04:43.454 INFO cr2: finished mounting /opt/cargo-bay
11892026-10-21T04:04:43.465ZOct 21 04:04:43.454 DEBG [sc] cr2: executing command `hostname cr2`
11902026-10-21T04:04:43.488ZOct 21 04:04:43.464 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
11912026-10-21T04:04:43.488ZOct 21 04:04:43.475 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
11922026-10-21T04:04:43.511ZOct 21 04:04:43.486 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
11932026-10-21T04:04:43.511ZOct 21 04:04:43.497 INFO cr2: logging out
11942026-10-21T04:04:46.575ZOct 21 04:04:46.564 INFO cr2: logged out
11952026-10-21T04:14:16.416ZError: launch timed out
11962026-10-21T04:14:16.416Z
11972026-10-21T04:14:16.416ZCaused by:
11982026-10-21T04:14:16.416Z deadline has elapsed
11992026-10-21T04:14:16.439Z+ status=1
12002026-10-21T04:14:16.439Z+ (( status != 0 ))
12012026-10-21T04:14:16.439Z+ collect_falcon_artifacts interop-bfd-static-routing
12022026-10-21T04:14:16.439Z+ local test_name=interop-bfd-static-routing
12032026-10-21T04:14:16.439Z+ local artifact_dir=/work/falcon-interop-bfd-static-routing
12042026-10-21T04:14:16.439Z+ local path
12052026-10-21T04:14:16.439Z+ [[ ! -d .falcon ]]
12062026-10-21T04:14:16.439Z+ rm -rf /work/falcon-interop-bfd-static-routing
12072026-10-21T04:14:16.439Z+ mkdir -p /work/falcon-interop-bfd-static-routing
12082026-10-21T04:14:16.440Z+ for path in .falcon/*.{out,err}
12092026-10-21T04:14:16.440Z+ [[ -e .falcon/cr1.out ]]
12102026-10-21T04:14:16.440Z+ cp -p .falcon/cr1.out /work/falcon-interop-bfd-static-routing/
12112026-10-21T04:14:16.440Z+ for path in .falcon/*.{out,err}
12122026-10-21T04:14:16.440Z+ [[ -e .falcon/cr2.out ]]
12132026-10-21T04:14:16.440Z+ cp -p .falcon/cr2.out /work/falcon-interop-bfd-static-routing/
12142026-10-21T04:14:16.463Z+ for path in .falcon/*.{out,err}
12152026-10-21T04:14:16.463Z+ [[ -e .falcon/cr3.out ]]
12162026-10-21T04:14:16.463Z+ cp -p .falcon/cr3.out /work/falcon-interop-bfd-static-routing/
12172026-10-21T04:14:16.463Z+ for path in .falcon/*.{out,err}
12182026-10-21T04:14:16.463Z+ [[ -e .falcon/ox.out ]]
12192026-10-21T04:14:16.463Z+ cp -p .falcon/ox.out /work/falcon-interop-bfd-static-routing/
12202026-10-21T04:14:16.463Z+ for path in .falcon/*.{out,err}
12212026-10-21T04:14:16.463Z+ [[ -e .falcon/peer.out ]]
12222026-10-21T04:14:16.463Z+ cp -p .falcon/peer.out /work/falcon-interop-bfd-static-routing/
12232026-10-21T04:14:16.463Z+ for path in .falcon/*.{out,err}
12242026-10-21T04:14:16.463Z+ [[ -e .falcon/cr1.err ]]
12252026-10-21T04:14:16.463Z+ cp -p .falcon/cr1.err /work/falcon-interop-bfd-static-routing/
12262026-10-21T04:14:16.463Z+ for path in .falcon/*.{out,err}
12272026-10-21T04:14:16.463Z+ [[ -e .falcon/cr2.err ]]
12282026-10-21T04:14:16.463Z+ cp -p .falcon/cr2.err /work/falcon-interop-bfd-static-routing/
12292026-10-21T04:14:16.486Z+ for path in .falcon/*.{out,err}
12302026-10-21T04:14:16.486Z+ [[ -e .falcon/cr3.err ]]
12312026-10-21T04:14:16.486Z+ cp -p .falcon/cr3.err /work/falcon-interop-bfd-static-routing/
12322026-10-21T04:14:16.486Z+ for path in .falcon/*.{out,err}
12332026-10-21T04:14:16.486Z+ [[ -e .falcon/ox.err ]]
12342026-10-21T04:14:16.486Z+ cp -p .falcon/ox.err /work/falcon-interop-bfd-static-routing/
12352026-10-21T04:14:16.486Z+ for path in .falcon/*.{out,err}
12362026-10-21T04:14:16.486Z+ [[ -e .falcon/peer.err ]]
12372026-10-21T04:14:16.486Z+ cp -p .falcon/peer.err /work/falcon-interop-bfd-static-routing/
12382026-10-21T04:14:16.486Z+ tar cvfz /work/falcon-interop-bfd-static-routing.tar.gz -C /work falcon-interop-bfd-static-routing
12392026-10-21T04:14:16.509ZCompressing '/work/falcon-interop-bfd-static-routing.tar.gz' with '/usr/bin/gzip'...
12402026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/ 0K
12412026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/peer.err 1K
12422026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/cr2.out 51K
12432026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/ox.out 54K
12442026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/cr1.err 1K
12452026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/cr3.out 127K
12462026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/cr2.err 1K
12472026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/ox.err 1K
12482026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/peer.out 51K
12492026-10-21T04:14:16.509Za falcon-interop-bfd-static-routing/cr1.out 51K
12502026-10-21T04:14:16.510Za falcon-interop-bfd-static-routing/cr3.err 1K
12512026-10-21T04:14:16.510Z+ rm -rf /work/falcon-interop-bfd-static-routing
12522026-10-21T04:14:16.510Z+ pfexec ./falcon-lab cleanup interop bfd-static-routing
12532026-10-21T04:14:16.532ZOct 21 04:14:16.500 INFO destroying runner for deployment interop_bfd
12542026-10-21T04:14:16.532ZOct 21 04:14:16.500 INFO destroying deployment interop_bfd
12552026-10-21T04:14:16.532ZOct 21 04:14:16.500 INFO destroying nodes
12562026-10-21T04:14:16.621ZOct 21 04:14:16.610 INFO destroying links
12572026-10-21T04:14:16.621ZOct 21 04:14:16.610 INFO destroying link interop_bfd_ox_sn_vnic0
12582026-10-21T04:14:16.644ZOct 21 04:14:16.613 INFO destroying link interop_bfd_ox_sn_sim0
12592026-10-21T04:14:16.644ZOct 21 04:14:16.615 INFO destroying link interop_bfd_cr1_vn_vnic0
12602026-10-21T04:14:16.644ZOct 21 04:14:16.616 INFO destroying link interop_bfd_cr1_vn_sim0
12612026-10-21T04:14:16.644ZOct 21 04:14:16.617 INFO destroying link interop_bfd_ox_sn_vnic1
12622026-10-21T04:14:16.644ZOct 21 04:14:16.618 INFO destroying link interop_bfd_ox_sn_sim1
12632026-10-21T04:14:16.644ZOct 21 04:14:16.619 INFO destroying link interop_bfd_cr2_vn_vnic0
12642026-10-21T04:14:17.634ZOct 21 04:14:17.623 INFO destroying link interop_bfd_cr2_vn_sim0
12652026-10-21T04:14:17.657ZOct 21 04:14:17.624 INFO destroying link interop_bfd_ox_sn_vnic2
12662026-10-21T04:14:17.657ZOct 21 04:14:17.625 INFO destroying link interop_bfd_ox_sn_sim2
12672026-10-21T04:14:17.657ZOct 21 04:14:17.626 INFO destroying link interop_bfd_cr3_vn_vnic0
12682026-10-21T04:14:17.657ZOct 21 04:14:17.627 INFO destroying link interop_bfd_cr3_vn_sim0
12692026-10-21T04:14:17.657ZOct 21 04:14:17.628 INFO destroying link interop_bfd_ox_sn_vnic3
12702026-10-21T04:14:17.657ZOct 21 04:14:17.629 INFO destroying link interop_bfd_ox_sn_sim3
12712026-10-21T04:14:17.657ZOct 21 04:14:17.630 INFO destroying link interop_bfd_peer_vn_vnic0
12722026-10-21T04:14:17.657ZOct 21 04:14:17.631 INFO destroying link interop_bfd_peer_vn_sim0
12732026-10-21T04:14:17.657ZOct 21 04:14:17.632 INFO destroying external links
12742026-10-21T04:14:17.657ZOct 21 04:14:17.632 INFO destroying external link interop_bfd_ox_vn_vnic4
12752026-10-21T04:14:17.657ZOct 21 04:14:17.633 INFO destroying external link interop_bfd_cr1_vn_vnic1
12762026-10-21T04:14:17.657ZOct 21 04:14:17.634 INFO destroying external link interop_bfd_cr2_vn_vnic1
12772026-10-21T04:14:17.657ZOct 21 04:14:17.635 INFO destroying external link interop_bfd_cr3_vn_vnic1
12782026-10-21T04:14:17.657ZOct 21 04:14:17.636 INFO destroying external link interop_bfd_peer_vn_vnic1
12792026-10-21T04:14:17.657ZOct 21 04:14:17.638 INFO destroying images
12802026-10-21T04:14:18.040ZOct 21 04:14:18.029 INFO destroying workspace at .falcon
12812026-10-21T04:14:18.064Z+ return 1
12822026-10-21T04:14:18.064Zprocess exited: duration 1192062 ms, exit code 1
 
12832026-10-21T04:14:18.139Zfound 2 output files
12842026-10-21T04:14:18.139Zuploading: /work/dhcp-server.log (38258 bytes)
12852026-10-21T04:14:19.271Zuploaded: /work/dhcp-server.log
12862026-10-21T04:14:19.271Zuploading: /work/falcon-interop-bfd-static-routing.tar.gz (26974 bytes)
12872026-10-21T04:14:20.400Zuploaded: /work/falcon-interop-bfd-static-routing.tar.gz