01M4YSVR12WRS5C9BVFJ59X138: falcon

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

Buildomat Job: 01M4YSXTVP2JD4KYDMMCB6D24A

Tags:

Artefacts:

Output:

SEQ GLOBAL TIME DETAILS
12026-10-15T03:49:13.466Zjob dependencies complete; ready to run (waiting for 16 m 5 s)
22026-10-15T04:09:26.342Zjob assigned to worker 01M4YVT918F68MGS2R8PAK7J5V [factory edgar, gimlet/BRM42220060/1274] (queued for 20 m 12 s)
32026-10-15T04:09:27.126Zdownloading input: /input/build-dhcp-server/work/dhcp-server
42026-10-15T04:09:27.458Zdownloaded input: /input/build-dhcp-server/work/dhcp-server
52026-10-15T04:09:27.518Zdownloading input: /input/build/work/debug/ddmadm
62026-10-15T04:09:56.614Zdownloaded input: /input/build/work/debug/ddmadm
72026-10-15T04:09:56.615Zdownloading input: /input/build/work/debug/ddmd
82026-10-15T04:10:29.508Zdownloaded input: /input/build/work/debug/ddmd
92026-10-15T04:10:29.508Zdownloading input: /input/build/work/debug/mgadm
102026-10-15T04:11:16.181Zdownloaded input: /input/build/work/debug/mgadm
112026-10-15T04:11:16.181Zdownloading input: /input/build/work/debug/mgd
122026-10-15T04:11:57.609Zdownloaded input: /input/build/work/debug/mgd
132026-10-15T04:11:57.656Zdownloading input: /input/build/work/release/ddmadm
142026-10-15T04:12:00.890Zdownloaded input: /input/build/work/release/ddmadm
152026-10-15T04:12:00.891Zdownloading input: /input/build/work/release/ddmd
162026-10-15T04:12:03.965Zdownloaded input: /input/build/work/release/ddmd
172026-10-15T04:12:03.965Zdownloading input: /input/build/work/release/falcon-lab
182026-10-15T04:12:06.493Zdownloaded input: /input/build/work/release/falcon-lab
192026-10-15T04:12:06.493Zdownloading input: /input/build/work/release/mgadm
202026-10-15T04:12:11.728Zdownloaded input: /input/build/work/release/mgadm
212026-10-15T04:12:11.728Zdownloading input: /input/build/work/release/mgd
222026-10-15T04:12:18.030Zdownloaded input: /input/build/work/release/mgd
 
232026-10-15T04:12:18.030Zstarting task 0: "setup"
242026-10-15T04:12:18.076Z++ uname -s
252026-10-15T04:12:18.076Z+ kern=SunOS
262026-10-15T04:12:18.076Z+ build_user=build
272026-10-15T04:12:18.076Z+ build_uid=12345
282026-10-15T04:12:18.076Z+ work_dir=/work
292026-10-15T04:12:18.087Z+ input_dir=/input
302026-10-15T04:12:18.087Z+ [[ 0 == 12345 ]]
312026-10-15T04:12:18.087Z+ case "$kern" in
322026-10-15T04:12:18.088Z+ groupadd -g 12345 build
332026-10-15T04:12:18.088Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
342026-10-15T04:12:20.068Z+ zfs create -o mountpoint=/work rpool/work
352026-10-15T04:12:20.647Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
362026-10-15T04:12:20.677Z+ home_fs=
372026-10-15T04:12:20.677Z+ [[ '' == autofs ]]
382026-10-15T04:12:20.677Z+ mkdir -p /home/build
392026-10-15T04:12:20.677Z+ chown build:build /home/build /work
402026-10-15T04:12:22.664Z+ chmod 0700 /home/build /work
412026-10-15T04:12:22.694Zprocess exited: duration 4636 ms, exit code 0
 
422026-10-15T04:12:22.754Zstarting task 1: "authentication"
432026-10-15T04:12:22.851Zprocess exited: duration 96 ms, exit code 0
 
442026-10-15T04:12:22.910Zstarting task 2: "build"
452026-10-15T04:12:22.939Z+ set -e
462026-10-15T04:12:22.939Z+ set -o pipefail
472026-10-15T04:12:22.939Z+ banner zpool
482026-10-15T04:12:22.939Z
492026-10-15T04:12:22.939Z ###### ##### #### #### #
502026-10-15T04:12:22.939Z # # # # # # # #
512026-10-15T04:12:22.939Z # # # # # # # #
522026-10-15T04:12:22.939Z # ##### # # # # #
532026-10-15T04:12:22.939Z # # # # # # #
542026-10-15T04:12:22.939Z ###### # #### #### ######
552026-10-15T04:12:22.939Z
562026-10-15T04:12:22.939Z++ pfexec diskinfo -pH
572026-10-15T04:12:22.939Z++ sort -k8 -n -r
582026-10-15T04:12:22.968Z++ head -1
592026-10-15T04:12:22.968Z++ awk '{print $2}'
602026-10-15T04:12:22.999Z+ DISK=c9t0014EE81000BC57Cd0
612026-10-15T04:12:22.999Z+ export DISK
622026-10-15T04:12:23.029Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
632026-10-15T04:12:23.332Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
642026-10-15T04:12:23.382Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
652026-10-15T04:12:23.425Z+ trim_response=true
662026-10-15T04:12:23.457Z+ [[ true =~ true ]]
672026-10-15T04:12:23.457Z+ pfexec zpool trim cpool
682026-10-15T04:12:23.457Z+ true
692026-10-15T04:12:23.457Z++ zpool status -t cpool
702026-10-15T04:12:23.458Z+ trim_status=' pool: cpool
712026-10-15T04:12:23.458Z state: ONLINE
722026-10-15T04:12:23.458Z scan: none requested
732026-10-15T04:12:23.458Zconfig:
742026-10-15T04:12:23.458Z
752026-10-15T04:12:23.458Z NAME STATE READ WRITE CKSUM
762026-10-15T04:12:23.458Z cpool ONLINE 0 0 0
772026-10-15T04:12:23.458Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at October 15, 2026 at 04:12:23 AM UTC)
782026-10-15T04:12:23.458Z
792026-10-15T04:12:23.458Zerrors: No known data errors'
802026-10-15T04:12:23.458Z+ [[ pool: cpool
812026-10-15T04:12:23.458Z state: ONLINE
822026-10-15T04:12:23.458Z scan: none requested
832026-10-15T04:12:23.458Zconfig:
842026-10-15T04:12:23.458Z
852026-10-15T04:12:23.458Z NAME STATE READ WRITE CKSUM
862026-10-15T04:12:23.458Z cpool ONLINE 0 0 0
872026-10-15T04:12:23.458Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (1% trimmed, started at October 15, 2026 at 04:12:23 AM UTC)
882026-10-15T04:12:23.458Z
892026-10-15T04:12:23.458Zerrors: No known data errors =~ 100% ]]
902026-10-15T04:12:23.458Z+ sleep 10
912026-10-15T04:12:33.988Z+ true
922026-10-15T04:12:34.143Z++ zpool status -t cpool
932026-10-15T04:12:34.143Z+ trim_status=' pool: cpool
942026-10-15T04:12:34.143Z state: ONLINE
952026-10-15T04:12:34.143Z scan: none requested
962026-10-15T04:12:34.143Zconfig:
972026-10-15T04:12:34.143Z
982026-10-15T04:12:34.143Z NAME STATE READ WRITE CKSUM
992026-10-15T04:12:34.143Z cpool ONLINE 0 0 0
1002026-10-15T04:12:34.143Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (69% trimmed, started at October 15, 2026 at 04:12:23 AM UTC)
1012026-10-15T04:12:34.144Z
1022026-10-15T04:12:34.144Zerrors: No known data errors'
1032026-10-15T04:12:34.144Z+ [[ pool: cpool
1042026-10-15T04:12:34.144Z state: ONLINE
1052026-10-15T04:12:34.144Z scan: none requested
1062026-10-15T04:12:34.144Zconfig:
1072026-10-15T04:12:34.144Z
1082026-10-15T04:12:34.144Z NAME STATE READ WRITE CKSUM
1092026-10-15T04:12:34.144Z cpool ONLINE 0 0 0
1102026-10-15T04:12:34.144Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (69% trimmed, started at October 15, 2026 at 04:12:23 AM UTC)
1112026-10-15T04:12:34.144Z
1122026-10-15T04:12:34.144Zerrors: No known data errors =~ 100% ]]
1132026-10-15T04:12:34.144Z+ sleep 10
1142026-10-15T04:12:43.469Z+ true
1152026-10-15T04:12:43.495Z++ zpool status -t cpool
1162026-10-15T04:12:43.495Z+ trim_status=' pool: cpool
1172026-10-15T04:12:43.495Z state: ONLINE
1182026-10-15T04:12:43.495Z scan: none requested
1192026-10-15T04:12:43.495Zconfig:
1202026-10-15T04:12:43.495Z
1212026-10-15T04:12:43.495Z NAME STATE READ WRITE CKSUM
1222026-10-15T04:12:43.495Z cpool ONLINE 0 0 0
1232026-10-15T04:12:43.495Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at October 15, 2026 at 04:12:38 AM UTC)
1242026-10-15T04:12:43.495Z
1252026-10-15T04:12:43.495Zerrors: No known data errors'
1262026-10-15T04:12:43.495Z+ [[ pool: cpool
1272026-10-15T04:12:43.495Z state: ONLINE
1282026-10-15T04:12:43.495Z scan: none requested
1292026-10-15T04:12:43.495Zconfig:
1302026-10-15T04:12:43.495Z
1312026-10-15T04:12:43.495Z NAME STATE READ WRITE CKSUM
1322026-10-15T04:12:43.495Z cpool ONLINE 0 0 0
1332026-10-15T04:12:43.495Z c9t0014EE81000BC57Cd0 ONLINE 0 0 0 (100% trimmed, completed at October 15, 2026 at 04:12:38 AM UTC)
1342026-10-15T04:12:43.495Z
1352026-10-15T04:12:43.495Zerrors: No known data errors =~ 100% ]]
1362026-10-15T04:12:43.495Z+ break
1372026-10-15T04:12:43.495Z+ pfexec chown 12345 /ci
1382026-10-15T04:12:43.495Z+ cd /ci
1392026-10-15T04:12:43.495Z+ export FALCON_DATASET=cpool/falcon
1402026-10-15T04:12:43.495Z+ FALCON_DATASET=cpool/falcon
1412026-10-15T04:12:43.495Z+ banner setup
1422026-10-15T04:12:43.495Z
1432026-10-15T04:12:43.495Z #### ###### ##### # # #####
1442026-10-15T04:12:43.496Z # # # # # # #
1452026-10-15T04:12:43.496Z #### ##### # # # # #
1462026-10-15T04:12:43.496Z # # # # # #####
1472026-10-15T04:12:43.496Z # # # # # # #
1482026-10-15T04:12:43.496Z #### ###### # #### #
1492026-10-15T04:12:43.496Z
1502026-10-15T04:12:43.496Z+ cp /input/build-dhcp-server/work/dhcp-server .
1512026-10-15T04:12:43.520Z+ cp /input/build/work/release/falcon-lab .
1522026-10-15T04:12:43.546Z+ cp /input/build/work/release/mgd .
1532026-10-15T04:12:43.617Z+ cp /input/build/work/release/ddmd .
1542026-10-15T04:12:43.678Z+ chmod +x dhcp-server falcon-lab mgd ddmd
1552026-10-15T04:12:43.702Z+ mkdir -p cargo-bay
1562026-10-15T04:12:43.702Z+ mv mgd cargo-bay/
1572026-10-15T04:12:43.702Z+ mv ddmd cargo-bay/
1582026-10-15T04:12:43.702Z+ curl -sSfL --retry 10 --retry-all-errors -o cargo-bay/falcon-juniper-license.key http://catacomb.eng.oxide.computer:12346/falcon/jl
1592026-10-15T04:12:43.755Z+ chmod 0600 cargo-bay/falcon-juniper-license.key
1602026-10-15T04:12:43.779Z+ export EXT_INTERFACE=igb0
1612026-10-15T04:12:43.779Z+ EXT_INTERFACE=igb0
1622026-10-15T04:12:43.779Z++ bmat address ls -f extra -Ho first
1632026-10-15T04:12:43.805Z+ first=10.151.7.164
1642026-10-15T04:12:43.805Z++ bmat address ls -f extra -Ho last
1652026-10-15T04:12:43.829Z+ last=10.151.7.227
1662026-10-15T04:12:43.829Z++ bmat address ls -f extra -Ho gateway
1672026-10-15T04:12:43.829Z+ gw=10.151.7.1
1682026-10-15T04:12:43.853Z++ ipadm show-addr igb0/dhcp -po ADDR
1692026-10-15T04:12:43.853Z++ sed 's#/.*##g'
1702026-10-15T04:12:43.853Z+ server=10.151.7.100
1712026-10-15T04:12:43.853Z+ run_test mgd-duo bgp-unnumbered
1722026-10-15T04:12:43.853Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1732026-10-15T04:12:43.853Z+ local topology=mgd-duo
1742026-10-15T04:12:43.854Z+ local scenario=bgp-unnumbered
1752026-10-15T04:12:43.854Z+ local test_name=mgd-duo-bgp-unnumbered
1762026-10-15T04:12:43.854Z+ local status=0
1772026-10-15T04:12:43.854Z+ clear_falcon_workspace_files
1782026-10-15T04:12:43.854Z+ [[ -d .falcon ]]
1792026-10-15T04:12:43.854Z+ RUST_LOG=debug
1802026-10-15T04:12:43.854Z+ pfexec ./falcon-lab run mgd-duo bgp-unnumbered --no-cleanup
1812026-10-15T04:12:43.878ZOct 15 04:12:43.845 DEBG using default route interface igb0
1822026-10-15T04:12:43.878ZOct 15 04:12:43.846 DEBG using default route interface igb0
1832026-10-15T04:12:43.878ZOct 15 04:12:43.846 INFO launching runner: deployment(mgdduo_bgpu) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1842026-10-15T04:12:43.878ZOct 15 04:12:43.846 INFO starting preflight for deployment mgdduo_bgpu
1852026-10-15T04:12:43.878ZOct 15 04:12:43.846 INFO propolis-server binary not found
1862026-10-15T04:12:43.878ZOct 15 04:12:43.846 INFO downloading propolis server rev 36f20be9bb4c3b362029237f5feb6377c982395f, writing to .falcon/bin/propolis-server
1872026-10-15T04:13:04.335ZOct 15 04:13:04.320 INFO ovmf fd not found
1882026-10-15T04:13:04.335ZOct 15 04:13:04.320 INFO downloading ovmf
1892026-10-15T04:13:04.675ZOct 15 04:13:04.660 INFO base image for helios-3.0 does not exist, attempting to install
1902026-10-15T04:13:04.675ZOct 15 04:13:04.660 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-3.0_0.raw.xz
1912026-10-15T04:13:32.738ZOct 15 04:13:32.722 INFO extracting image to /tmp/helios-3.0_0.raw
1922026-10-15T04:13:55.180ZOct 15 04:13:55.162 INFO creating zvol cpool/falcon/img/helios-3.0 of size 4508880896
1932026-10-15T04:13:55.234ZOct 15 04:13:55.217 INFO copying image data to zvol
1942026-10-15T04:14:05.915ZOct 15 04:14:05.898 INFO creating zfs snapshot cpool/falcon/img/helios-3.0@base
1952026-10-15T04:14:09.281ZOct 15 04:14:09.263 INFO creating links
1962026-10-15T04:14:09.281ZOct 15 04:14:09.263 DEBG destroying link mgdduo_bgpu_ox1_vn_vnic0
1972026-10-15T04:14:09.306ZOct 15 04:14:09.263 DEBG destroying link mgdduo_bgpu_ox1_vn_sim0
1982026-10-15T04:14:09.306ZOct 15 04:14:09.263 INFO creating simnet link 'mgdduo_bgpu_ox1_vn_sim0'
1992026-10-15T04:14:09.306ZOct 15 04:14:09.265 INFO creating vnic link 'mgdduo_bgpu_ox1_vn_vnic0'
2002026-10-15T04:14:09.306ZOct 15 04:14:09.273 DEBG link pair created
2012026-10-15T04:14:09.306ZOct 15 04:14:09.273 DEBG destroying link mgdduo_bgpu_ox2_vn_vnic0
2022026-10-15T04:14:09.306ZOct 15 04:14:09.273 DEBG destroying link mgdduo_bgpu_ox2_vn_sim0
2032026-10-15T04:14:09.306ZOct 15 04:14:09.273 INFO creating simnet link 'mgdduo_bgpu_ox2_vn_sim0'
2042026-10-15T04:14:09.306ZOct 15 04:14:09.274 INFO creating vnic link 'mgdduo_bgpu_ox2_vn_vnic0'
2052026-10-15T04:14:09.306ZOct 15 04:14:09.282 DEBG link pair created
2062026-10-15T04:14:09.306ZOct 15 04:14:09.282 INFO creating external links
2072026-10-15T04:14:09.306ZOct 15 04:14:09.282 DEBG destroying external link mgdduo_bgpu_ox1_vn_vnic1
2082026-10-15T04:14:09.306ZOct 15 04:14:09.282 INFO creating external link mgdduo_bgpu_ox1_vn_vnic1
2092026-10-15T04:14:09.306ZOct 15 04:14:09.284 DEBG external link mgdduo_bgpu_ox1_vn_vnic1@igb0 created
2102026-10-15T04:14:09.306ZOct 15 04:14:09.284 DEBG destroying external link mgdduo_bgpu_ox2_vn_vnic1
2112026-10-15T04:14:09.306ZOct 15 04:14:09.284 INFO creating external link mgdduo_bgpu_ox2_vn_vnic1
2122026-10-15T04:14:09.306ZOct 15 04:14:09.285 DEBG external link mgdduo_bgpu_ox2_vn_vnic1@igb0 created
2132026-10-15T04:14:09.306ZOct 15 04:14:09.285 INFO creating nodes
2142026-10-15T04:14:09.307ZOct 15 04:14:09.285 INFO ox1: launching node
2152026-10-15T04:14:09.307ZOct 15 04:14:09.288 INFO ox2: launching node
2162026-10-15T04:14:09.331ZOct 15 04:14:09.313 INFO launched instance ox1 with pid 863 on port 53341
2172026-10-15T04:14:09.331ZOct 15 04:14:09.313 INFO ox1: instance ensure
2182026-10-15T04:14:09.331ZOct 15 04:14:09.313 INFO launched instance ox2 with pid 864 on port 46203
2192026-10-15T04:14:09.331ZOct 15 04:14:09.314 INFO ox2: instance ensure
2202026-10-15T04:14:11.153ZOct 15 04:14:11.136 INFO ox1: instance ensure completed after 0 retries
2212026-10-15T04:14:11.153ZOct 15 04:14:11.136 INFO ox1: instance run
2222026-10-15T04:14:11.178ZOct 15 04:14:11.136 DEBG [sc] ox1: starting
2232026-10-15T04:14:11.178ZOct 15 04:14:11.136 DEBG [sc] ox1: connecting to [::1]:53341
2242026-10-15T04:14:11.178ZOct 15 04:14:11.138 DEBG [sc] ox1 waiting for prompt
2252026-10-15T04:14:11.178ZOct 15 04:14:11.138 INFO ox2: instance ensure completed after 0 retries
2262026-10-15T04:14:11.178ZOct 15 04:14:11.138 INFO ox2: instance run
2272026-10-15T04:14:11.178ZOct 15 04:14:11.138 DEBG [sc] ox2: starting
2282026-10-15T04:14:11.178ZOct 15 04:14:11.138 DEBG [sc] ox2: connecting to [::1]:46203
2292026-10-15T04:14:11.178ZOct 15 04:14:11.139 DEBG [sc] ox2 waiting for prompt
2302026-10-15T04:14:34.553ZOct 15 04:14:34.536 DEBG [sc] ox2: logging in
2312026-10-15T04:14:34.609ZOct 15 04:14:34.592 DEBG [sc] ox1: logging in
2322026-10-15T04:14:34.900ZOct 15 04:14:34.882 INFO ox2: mounting /opt/cargo-bay
2332026-10-15T04:14:34.900ZOct 15 04:14:34.882 DEBG [sc] ox2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2342026-10-15T04:14:34.924ZOct 15 04:14:34.902 INFO ox1: mounting /opt/cargo-bay
2352026-10-15T04:14:34.925ZOct 15 04:14:34.902 DEBG [sc] ox1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2362026-10-15T04:14:36.221ZOct 15 04:14:36.204 DEBG [sc] ox1: executing command `cd`
2372026-10-15T04:14:36.246ZOct 15 04:14:36.215 INFO ox1: finished mounting /opt/cargo-bay
2382026-10-15T04:14:36.247ZOct 15 04:14:36.215 DEBG [sc] ox1: executing command `hostname ox1`
2392026-10-15T04:14:36.248ZOct 15 04:14:36.226 DEBG [sc] ox1: executing command `echo 'ox1' > /etc/nodename`
2402026-10-15T04:14:36.272ZOct 15 04:14:36.237 DEBG [sc] ox1: executing command `echo '::1 ox1.local ox1' >> /etc/hosts`
2412026-10-15T04:14:36.273ZOct 15 04:14:36.245 DEBG [sc] ox2: executing command `cd`
2422026-10-15T04:14:36.273ZOct 15 04:14:36.247 DEBG [sc] ox1: executing command `echo '127.0.0.1 ox1.local ox1' >> /etc/hosts`
2432026-10-15T04:14:36.297ZOct 15 04:14:36.256 INFO ox2: finished mounting /opt/cargo-bay
2442026-10-15T04:14:36.297ZOct 15 04:14:36.256 DEBG [sc] ox2: executing command `hostname ox2`
2452026-10-15T04:14:36.298ZOct 15 04:14:36.259 INFO ox1: logging out
2462026-10-15T04:14:36.298ZOct 15 04:14:36.268 DEBG [sc] ox2: executing command `echo 'ox2' > /etc/nodename`
2472026-10-15T04:14:36.298ZOct 15 04:14:36.279 DEBG [sc] ox2: executing command `echo '::1 ox2.local ox2' >> /etc/hosts`
2482026-10-15T04:14:36.322ZOct 15 04:14:36.289 DEBG [sc] ox2: executing command `echo '127.0.0.1 ox2.local ox2' >> /etc/hosts`
2492026-10-15T04:14:36.322ZOct 15 04:14:36.300 INFO ox2: logging out
2502026-10-15T04:14:36.353ZOct 15 04:14:36.336 INFO ox1: logged out
2512026-10-15T04:14:36.394ZOct 15 04:14:36.377 INFO ox2: logged out
2522026-10-15T04:14:36.419ZOct 15 04:14:36.378 DEBG [sc] ox1: starting
2532026-10-15T04:14:36.419ZOct 15 04:14:36.378 DEBG [sc] ox1: connecting to [::1]:53341
2542026-10-15T04:14:36.419ZOct 15 04:14:36.378 DEBG [sc] ox2: starting
2552026-10-15T04:14:36.419ZOct 15 04:14:36.378 DEBG [sc] ox2: connecting to [::1]:46203
2562026-10-15T04:14:36.419ZOct 15 04:14:36.378 DEBG [sc] ox1 waiting for prompt
2572026-10-15T04:14:36.419ZOct 15 04:14:36.378 DEBG [sc] ox2 waiting for prompt
2582026-10-15T04:14:36.419ZOct 15 04:14:36.381 DEBG [sc] ox1: logging in
2592026-10-15T04:14:36.419ZOct 15 04:14:36.389 DEBG [sc] ox2: logging in
2602026-10-15T04:14:36.486ZOct 15 04:14:36.469 DEBG [sc] ox1: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2612026-10-15T04:14:36.515ZOct 15 04:14:36.477 DEBG [sc] ox2: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2622026-10-15T04:14:42.286ZOct 15 04:14:42.273 DEBG [sc] ox1: starting
2632026-10-15T04:14:42.286ZOct 15 04:14:42.273 DEBG [sc] ox1: connecting to [::1]:53341
2642026-10-15T04:14:42.311ZOct 15 04:14:42.273 DEBG [sc] ox1 waiting for prompt
2652026-10-15T04:14:42.311ZOct 15 04:14:42.274 DEBG [sc] ox2: starting
2662026-10-15T04:14:42.311ZOct 15 04:14:42.274 DEBG [sc] ox2: connecting to [::1]:46203
2672026-10-15T04:14:42.311ZOct 15 04:14:42.275 DEBG [sc] ox2 waiting for prompt
2682026-10-15T04:14:42.311ZOct 15 04:14:42.284 DEBG [sc] ox1: logging in
2692026-10-15T04:14:42.312ZOct 15 04:14:42.286 DEBG [sc] ox2: logging in
2702026-10-15T04:14:42.377ZOct 15 04:14:42.363 DEBG [sc] ox2: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2712026-10-15T04:14:42.401ZOct 15 04:14:42.373 DEBG [sc] ox1: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2722026-10-15T04:14:42.475ZOct 15 04:14:42.461 DEBG [sc] ox1: starting
2732026-10-15T04:14:42.475ZOct 15 04:14:42.461 DEBG [sc] ox1: connecting to [::1]:53341
2742026-10-15T04:14:42.499ZOct 15 04:14:42.462 DEBG [sc] ox1 waiting for prompt
2752026-10-15T04:14:42.499ZOct 15 04:14:42.463 DEBG [sc] ox2: starting
2762026-10-15T04:14:42.501ZOct 15 04:14:42.463 DEBG [sc] ox2: connecting to [::1]:46203
2772026-10-15T04:14:42.501ZOct 15 04:14:42.463 DEBG [sc] ox2 waiting for prompt
2782026-10-15T04:14:42.501ZOct 15 04:14:42.472 DEBG [sc] ox1: logging in
2792026-10-15T04:14:42.501ZOct 15 04:14:42.474 DEBG [sc] ox2: logging in
2802026-10-15T04:14:42.574ZOct 15 04:14:42.560 DEBG [sc] ox1: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2812026-10-15T04:14:42.598ZOct 15 04:14:42.563 DEBG [sc] ox2: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2822026-10-15T04:14:42.646ZOct 15 04:14:42.633 DEBG [sc] ox1: starting
2832026-10-15T04:14:42.647ZOct 15 04:14:42.633 DEBG [sc] ox1: connecting to [::1]:53341
2842026-10-15T04:14:42.671ZOct 15 04:14:42.633 DEBG [sc] ox1 waiting for prompt
2852026-10-15T04:14:42.671ZOct 15 04:14:42.637 DEBG [sc] ox1: logging in
2862026-10-15T04:14:42.729ZOct 15 04:14:42.716 DEBG [sc] ox1: executing command `ipadm create-addr -T addrconf vioif0/ll`
2872026-10-15T04:14:42.828ZOct 15 04:14:42.815 DEBG [sc] ox1: starting
2882026-10-15T04:14:42.828ZOct 15 04:14:42.815 DEBG [sc] ox1: connecting to [::1]:53341
2892026-10-15T04:14:42.853ZOct 15 04:14:42.815 DEBG [sc] ox1 waiting for prompt
2902026-10-15T04:14:42.853ZOct 15 04:14:42.826 DEBG [sc] ox1: logging in
2912026-10-15T04:14:42.927ZOct 15 04:14:42.913 DEBG [sc] ox1: executing command `ipadm show-addr vioif0/ll -p -o addr`
2922026-10-15T04:14:42.993ZOct 15 04:14:42.980 DEBG [sc] ox2: starting
2932026-10-15T04:14:42.993ZOct 15 04:14:42.980 DEBG [sc] ox2: connecting to [::1]:46203
2942026-10-15T04:14:43.017ZOct 15 04:14:42.981 DEBG [sc] ox2 waiting for prompt
2952026-10-15T04:14:43.018ZOct 15 04:14:42.992 DEBG [sc] ox2: logging in
2962026-10-15T04:14:43.094ZOct 15 04:14:43.081 DEBG [sc] ox2: executing command `ipadm create-addr -T addrconf vioif0/ll`
2972026-10-15T04:14:43.215ZOct 15 04:14:43.202 DEBG [sc] ox2: starting
2982026-10-15T04:14:43.215ZOct 15 04:14:43.202 DEBG [sc] ox2: connecting to [::1]:46203
2992026-10-15T04:14:43.240ZOct 15 04:14:43.203 DEBG [sc] ox2 waiting for prompt
3002026-10-15T04:14:43.240ZOct 15 04:14:43.213 DEBG [sc] ox2: logging in
3012026-10-15T04:14:43.314ZOct 15 04:14:43.302 DEBG [sc] ox2: executing command `ipadm show-addr vioif0/ll -p -o addr`
3022026-10-15T04:14:43.391ZOct 15 04:14:43.378 DEBG [sc] ox1: starting
3032026-10-15T04:14:43.391ZOct 15 04:14:43.378 DEBG [sc] ox1: connecting to [::1]:53341
3042026-10-15T04:14:43.415ZOct 15 04:14:43.378 DEBG [sc] ox2: starting
3052026-10-15T04:14:43.415ZOct 15 04:14:43.378 DEBG [sc] ox2: connecting to [::1]:46203
3062026-10-15T04:14:43.415ZOct 15 04:14:43.379 DEBG [sc] ox1 waiting for prompt
3072026-10-15T04:14:43.415ZOct 15 04:14:43.379 DEBG [sc] ox2 waiting for prompt
3082026-10-15T04:14:43.415ZOct 15 04:14:43.389 DEBG [sc] ox2: logging in
3092026-10-15T04:14:43.415ZOct 15 04:14:43.390 DEBG [sc] ox1: logging in
3102026-10-15T04:14:43.490ZOct 15 04:14:43.477 DEBG [sc] ox2: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3112026-10-15T04:14:43.515ZOct 15 04:14:43.478 DEBG [sc] ox1: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3122026-10-15T04:14:43.568ZOct 15 04:14:43.555 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/config/routers, method: GET, operation_id: read_routers
3132026-10-15T04:14:43.593ZOct 15 04:14:43.555 DEBG client request, body: None, uri: http://10.151.7.164:4676/bgp/config/routers, method: GET, operation_id: read_routers
3142026-10-15T04:14:43.593ZOct 15 04:14:43.556 DEBG client response, result: Err(reqwest::Error { kind: Request, url: "http://10.151.7.165:4676/bgp/config/routers", source: hyper_util::client::legacy::Error(Connect, ConnectError("tcp connect error", 10.151.7.165:4676, Os { code: 146, kind: ConnectionRefused, message: "Connection refused" })) }), operation_id: read_routers
3152026-10-15T04:14:43.593ZOct 15 04:14:43.556 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.7.165:4676/bgp/config/routers)
3162026-10-15T04:14:43.593ZOct 15 04:14:43.556 DEBG client response, result: Err(reqwest::Error { kind: Request, url: "http://10.151.7.164:4676/bgp/config/routers", source: hyper_util::client::legacy::Error(Connect, ConnectError("tcp connect error", 10.151.7.164:4676, Os { code: 146, kind: ConnectionRefused, message: "Connection refused" })) }), operation_id: read_routers
3172026-10-15T04:14:43.593ZOct 15 04:14:43.556 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.7.164:4676/bgp/config/routers)
3182026-10-15T04:14:44.570ZOct 15 04:14:44.557 DEBG client request, body: None, uri: http://10.151.7.164:4676/bgp/config/routers, method: GET, operation_id: read_routers
3192026-10-15T04:14:44.616ZOct 15 04:14:44.557 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/config/routers, method: GET, operation_id: read_routers
3202026-10-15T04:14:44.616ZOct 15 04:14:44.561 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "f93ff055-11c6-4fdc-a810-187e4bda7a8d", "content-length": "2", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: read_routers
3212026-10-15T04:14:44.616ZOct 15 04:14:44.561 DEBG client response, result: Ok(Response { url: "http://10.151.7.164:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "12fb0c3b-d9a0-4476-a3a6-c3bd26c011c2", "content-length": "2", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: read_routers
3222026-10-15T04:14:44.616ZOct 15 04:14:44.561 INFO adding BGP routers to both mgd nodes
3232026-10-15T04:14:44.616ZOct 15 04:14:44.561 DEBG client request, body: Some(Body), uri: http://10.151.7.165:4676/bgp/config/router, method: PUT, operation_id: create_router
3242026-10-15T04:14:44.616ZOct 15 04:14:44.561 DEBG client request, body: Some(Body), uri: http://10.151.7.164:4676/bgp/config/router, method: PUT, operation_id: create_router
3252026-10-15T04:14:44.616ZOct 15 04:14:44.564 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/config/router", status: 204, headers: {"x-request-id": "82e4508a-f719-46c0-9966-ea6847afe344", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: create_router
3262026-10-15T04:14:44.616ZOct 15 04:14:44.564 DEBG client response, result: Ok(Response { url: "http://10.151.7.164:4676/bgp/config/router", status: 204, headers: {"x-request-id": "72d03c74-4fa1-4a2e-bb28-edf65332d1f4", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: create_router
3272026-10-15T04:14:44.616ZOct 15 04:14:44.564 INFO adding unnumbered BGP neighbors to both mgd nodes
3282026-10-15T04:14:44.617ZOct 15 04:14:44.564 DEBG client request, body: Some(Body), uri: http://10.151.7.165:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
3292026-10-15T04:14:44.617ZOct 15 04:14:44.564 DEBG client request, body: Some(Body), uri: http://10.151.7.164:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
3302026-10-15T04:14:44.617ZOct 15 04:14:44.571 DEBG client response, result: Ok(Response { url: "http://10.151.7.164:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "b33f3beb-6da4-423b-924e-e408e1d2ed81", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: create_unnumbered_neighbor
3312026-10-15T04:14:44.617ZOct 15 04:14:44.571 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "63148e36-3dd7-43b4-9ace-b9df2e77daa9", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: create_unnumbered_neighbor
3322026-10-15T04:14:44.617ZOct 15 04:14:44.571 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3332026-10-15T04:14:44.617ZOct 15 04:14:44.573 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "ffc2ee9d-7727-44e7-a018-279f81c728af", "content-length": "2140", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: get_neighbors
3342026-10-15T04:14:44.617ZOct 15 04:14:44.573 DEBG client request, body: None, uri: http://10.151.7.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3352026-10-15T04:14:44.617ZOct 15 04:14:44.574 DEBG client response, result: Ok(Response { url: "http://10.151.7.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "96360c57-5895-4afe-9da9-ed910becc68f", "content-length": "2140", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: get_neighbors
3362026-10-15T04:14:44.618ZOct 15 04:14:44.574 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3372026-10-15T04:14:44.618ZOct 15 04:14:44.575 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "97df7185-fa72-462a-b900-f8609789862f", "content-length": "2140", "date": "Thu, 15 Oct 2026 04:14:44 GMT"} }), operation_id: get_neighbors
3382026-10-15T04:14:44.618Zmgd1 bgp ox2 established: iteration 1/20: expected Some(Established), got Some(Connect)
3392026-10-15T04:14:45.589ZOct 15 04:14:45.576 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3402026-10-15T04:14:45.614Zmgd1 bgp ox2 established: iteration 2/20: expected Some(Established), got Some(Connect)
3412026-10-15T04:14:45.614ZOct 15 04:14:45.577 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "bbe8be2a-945a-443a-9290-76c4bab57eb5", "content-length": "2140", "date": "Thu, 15 Oct 2026 04:14:45 GMT"} }), operation_id: get_neighbors
3422026-10-15T04:14:46.596ZOct 15 04:14:46.578 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3432026-10-15T04:14:46.659ZOct 15 04:14:46.579 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "7d356235-08f1-4fd5-a0e7-47811af02e7a", "content-length": "2140", "date": "Thu, 15 Oct 2026 04:14:46 GMT"} }), operation_id: get_neighbors
3442026-10-15T04:14:46.659Zmgd1 bgp ox2 established: iteration 3/20: expected Some(Established), got Some(Connect)
3452026-10-15T04:14:47.594ZOct 15 04:14:47.580 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3462026-10-15T04:14:47.619ZOct 15 04:14:47.581 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "1007c168-4883-4cb3-b04e-f82b4e1bdab8", "content-length": "2141", "date": "Thu, 15 Oct 2026 04:14:47 GMT"} }), operation_id: get_neighbors
3472026-10-15T04:14:47.619Zmgd1 bgp ox2 established: iteration 4/20: expected Some(Established), got Some(Connect)
3482026-10-15T04:14:48.599ZOct 15 04:14:48.583 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3492026-10-15T04:14:48.625Zmgd1 bgp ox2 established: iteration 5/20: expected Some(Established), got Some(Connect)
3502026-10-15T04:14:48.625ZOct 15 04:14:48.584 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "d1e5a226-074c-463c-9904-ce076e127ef8", "content-length": "2141", "date": "Thu, 15 Oct 2026 04:14:48 GMT"} }), operation_id: get_neighbors
3512026-10-15T04:14:49.600ZOct 15 04:14:49.585 DEBG client request, body: None, uri: http://10.151.7.165:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
3522026-10-15T04:14:49.626ZOct 15 04:14:49.586 DEBG client response, result: Ok(Response { url: "http://10.151.7.165:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "5d7df291-74fe-4e14-9abf-28f9e0ec8f1d", "content-length": "2371", "date": "Thu, 15 Oct 2026 04:14:49 GMT"} }), operation_id: get_neighbors
3532026-10-15T04:14:49.626ZOct 15 04:14:49.586 DEBG client request, body: None, uri: http://10.151.7.164:4676/bgp/status/neighbors?asn=44, method: GET, operation_id: get_neighbors
3542026-10-15T04:14:49.626ZOct 15 04:14:49.587 DEBG client response, result: Ok(Response { url: "http://10.151.7.164:4676/bgp/status/neighbors?asn=44", status: 200, headers: {"content-type": "application/json", "x-request-id": "489e2384-763d-4af1-829d-c6a8a5964851", "content-length": "2371", "date": "Thu, 15 Oct 2026 04:14:49 GMT"} }), operation_id: get_neighbors
3552026-10-15T04:14:49.626ZOct 15 04:14:49.587 INFO mgd-to-mgd bgp unnumbered test passed 🎉
3562026-10-15T04:14:49.626Z+ (( status != 0 ))
3572026-10-15T04:14:49.626Z+ pfexec ./falcon-lab cleanup mgd-duo bgp-unnumbered
3582026-10-15T04:14:49.662ZOct 15 04:14:49.616 INFO destroying runner for deployment mgdduo_bgpu
3592026-10-15T04:14:49.662ZOct 15 04:14:49.616 INFO destroying deployment mgdduo_bgpu
3602026-10-15T04:14:49.662ZOct 15 04:14:49.616 INFO destroying nodes
3612026-10-15T04:14:49.662ZOct 15 04:14:49.624 INFO destroying links
3622026-10-15T04:14:49.662ZOct 15 04:14:49.624 INFO destroying link mgdduo_bgpu_ox1_vn_vnic0
3632026-10-15T04:14:50.643ZOct 15 04:14:50.630 INFO destroying link mgdduo_bgpu_ox1_vn_sim0
3642026-10-15T04:14:50.668ZOct 15 04:14:50.631 INFO destroying link mgdduo_bgpu_ox2_vn_vnic0
3652026-10-15T04:14:50.668ZOct 15 04:14:50.632 INFO destroying link mgdduo_bgpu_ox2_vn_sim0
3662026-10-15T04:14:50.668ZOct 15 04:14:50.633 INFO destroying external links
3672026-10-15T04:14:50.669ZOct 15 04:14:50.633 INFO destroying external link mgdduo_bgpu_ox1_vn_vnic1
3682026-10-15T04:14:50.669ZOct 15 04:14:50.634 INFO destroying external link mgdduo_bgpu_ox2_vn_vnic1
3692026-10-15T04:14:50.669ZOct 15 04:14:50.635 INFO destroying images
3702026-10-15T04:14:50.799ZOct 15 04:14:50.786 INFO destroying workspace at .falcon
3712026-10-15T04:14:50.826Z+ return 0
3722026-10-15T04:14:50.826Z+ run_test interop bgp-unnumbered
3732026-10-15T04:14:50.840Z+ local topology=interop
3742026-10-15T04:14:50.841Z+ local scenario=bgp-unnumbered
3752026-10-15T04:14:50.841Z+ local test_name=interop-bgp-unnumbered
3762026-10-15T04:14:50.841Z+ local status=0
3772026-10-15T04:14:50.841Z+ clear_falcon_workspace_files
3782026-10-15T04:14:50.841Z+ [[ -d .falcon ]]
3792026-10-15T04:14:50.841Z+ find .falcon -maxdepth 1 -type f -exec rm -f '{}' +
3802026-10-15T04:14:50.841Z+ RUST_LOG=debug
3812026-10-15T04:14:50.841Z+ pfexec ./falcon-lab run interop bgp-unnumbered --no-cleanup
3822026-10-15T04:14:50.865ZOct 15 04:14:50.818 DEBG using default route interface igb0
3832026-10-15T04:14:50.865ZOct 15 04:14:50.818 DEBG using default route interface igb0
3842026-10-15T04:14:50.865ZOct 15 04:14:50.818 DEBG using default route interface igb0
3852026-10-15T04:14:50.866ZOct 15 04:14:50.818 DEBG using default route interface igb0
3862026-10-15T04:14:50.867ZOct 15 04:14:50.818 DEBG using default route interface igb0
3872026-10-15T04:14:50.867ZOct 15 04:14:50.818 INFO interop_bgpu: launching interop topology
3882026-10-15T04:14:50.867ZOct 15 04:14:50.818 INFO launching runner: deployment(interop_bgpu) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
3892026-10-15T04:14:50.867ZOct 15 04:14:50.818 INFO starting preflight for deployment interop_bgpu
3902026-10-15T04:14:51.359ZOct 15 04:14:51.346 INFO base image for debian-13.2 does not exist, attempting to install
3912026-10-15T04:14:51.359ZOct 15 04:14:51.346 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
3922026-10-15T04:15:11.507ZOct 15 04:15:11.494 INFO extracting image to /tmp/debian-13.2_0.raw
3932026-10-15T04:15:25.142ZOct 15 04:15:25.128 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
3942026-10-15T04:15:25.167ZOct 15 04:15:25.154 INFO copying image data to zvol
3952026-10-15T04:15:32.106ZOct 15 04:15:32.092 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
3962026-10-15T04:15:35.366ZOct 15 04:15:35.353 INFO base image for eos-4.35 does not exist, attempting to install
3972026-10-15T04:15:35.366ZOct 15 04:15:35.353 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz
3982026-10-15T04:16:21.500ZOct 15 04:16:21.487 INFO extracting image to /tmp/eos-4.35_0.raw
3992026-10-15T04:17:25.775ZOct 15 04:17:25.761 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576
4002026-10-15T04:17:25.808ZOct 15 04:17:25.793 INFO copying image data to zvol
4012026-10-15T04:18:14.816ZOct 15 04:18:14.803 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base
4022026-10-15T04:18:18.264ZOct 15 04:18:18.251 INFO base image for junos-23.2 does not exist, attempting to install
4032026-10-15T04:18:18.264ZOct 15 04:18:18.251 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/junos-23.2_0.raw.xz
4042026-10-15T04:18:50.587ZOct 15 04:18:50.574 INFO extracting image to /tmp/junos-23.2_0.raw
4052026-10-15T04:19:48.746ZOct 15 04:19:48.732 INFO creating zvol cpool/falcon/img/junos-23.2 of size 21474840576
4062026-10-15T04:19:48.772ZOct 15 04:19:48.758 INFO copying image data to zvol
4072026-10-15T04:20:36.673ZOct 15 04:20:36.661 INFO creating zfs snapshot cpool/falcon/img/junos-23.2@base
4082026-10-15T04:20:40.219ZOct 15 04:20:40.207 INFO creating links
4092026-10-15T04:20:40.220ZOct 15 04:20:40.207 DEBG destroying link interop_bgpu_ox_sn_vnic0
4102026-10-15T04:20:40.220ZOct 15 04:20:40.207 DEBG destroying link interop_bgpu_ox_sn_sim0
4112026-10-15T04:20:40.220ZOct 15 04:20:40.207 INFO creating simnet link 'interop_bgpu_ox_sn_sim0'
4122026-10-15T04:20:40.244ZOct 15 04:20:40.209 INFO creating vnic link 'interop_bgpu_ox_sn_vnic0'
4132026-10-15T04:20:40.244ZOct 15 04:20:40.216 DEBG link pair created
4142026-10-15T04:20:40.245ZOct 15 04:20:40.216 DEBG destroying link interop_bgpu_cr1_vn_vnic0
4152026-10-15T04:20:40.245ZOct 15 04:20:40.216 DEBG destroying link interop_bgpu_cr1_vn_sim0
4162026-10-15T04:20:40.245ZOct 15 04:20:40.216 INFO creating simnet link 'interop_bgpu_cr1_vn_sim0'
4172026-10-15T04:20:40.245ZOct 15 04:20:40.217 INFO creating vnic link 'interop_bgpu_cr1_vn_vnic0'
4182026-10-15T04:20:40.245ZOct 15 04:20:40.224 DEBG link pair created
4192026-10-15T04:20:40.245ZOct 15 04:20:40.224 DEBG destroying link interop_bgpu_ox_sn_vnic1
4202026-10-15T04:20:40.245ZOct 15 04:20:40.224 DEBG destroying link interop_bgpu_ox_sn_sim1
4212026-10-15T04:20:40.245ZOct 15 04:20:40.224 INFO creating simnet link 'interop_bgpu_ox_sn_sim1'
4222026-10-15T04:20:40.245ZOct 15 04:20:40.225 INFO creating vnic link 'interop_bgpu_ox_sn_vnic1'
4232026-10-15T04:20:40.270ZOct 15 04:20:40.232 DEBG link pair created
4242026-10-15T04:20:40.270ZOct 15 04:20:40.232 DEBG destroying link interop_bgpu_cr2_vn_vnic0
4252026-10-15T04:20:40.270ZOct 15 04:20:40.232 DEBG destroying link interop_bgpu_cr2_vn_sim0
4262026-10-15T04:20:40.270ZOct 15 04:20:40.232 INFO creating simnet link 'interop_bgpu_cr2_vn_sim0'
4272026-10-15T04:20:40.270ZOct 15 04:20:40.235 INFO creating vnic link 'interop_bgpu_cr2_vn_vnic0'
4282026-10-15T04:20:40.270ZOct 15 04:20:40.242 DEBG link pair created
4292026-10-15T04:20:40.270ZOct 15 04:20:40.242 DEBG destroying link interop_bgpu_ox_sn_vnic2
4302026-10-15T04:20:40.270ZOct 15 04:20:40.242 DEBG destroying link interop_bgpu_ox_sn_sim2
4312026-10-15T04:20:40.270ZOct 15 04:20:40.242 INFO creating simnet link 'interop_bgpu_ox_sn_sim2'
4322026-10-15T04:20:40.270ZOct 15 04:20:40.244 INFO creating vnic link 'interop_bgpu_ox_sn_vnic2'
4332026-10-15T04:20:40.270ZOct 15 04:20:40.250 DEBG link pair created
4342026-10-15T04:20:40.270ZOct 15 04:20:40.250 DEBG destroying link interop_bgpu_cr3_vn_vnic0
4352026-10-15T04:20:40.270ZOct 15 04:20:40.250 DEBG destroying link interop_bgpu_cr3_vn_sim0
4362026-10-15T04:20:40.270ZOct 15 04:20:40.250 INFO creating simnet link 'interop_bgpu_cr3_vn_sim0'
4372026-10-15T04:20:40.271ZOct 15 04:20:40.251 INFO creating vnic link 'interop_bgpu_cr3_vn_vnic0'
4382026-10-15T04:20:40.296ZOct 15 04:20:40.258 DEBG link pair created
4392026-10-15T04:20:40.296ZOct 15 04:20:40.258 DEBG destroying link interop_bgpu_ox_sn_vnic3
4402026-10-15T04:20:40.296ZOct 15 04:20:40.258 DEBG destroying link interop_bgpu_ox_sn_sim3
4412026-10-15T04:20:40.296ZOct 15 04:20:40.258 INFO creating simnet link 'interop_bgpu_ox_sn_sim3'
4422026-10-15T04:20:40.296ZOct 15 04:20:40.260 INFO creating vnic link 'interop_bgpu_ox_sn_vnic3'
4432026-10-15T04:20:40.297ZOct 15 04:20:40.266 DEBG link pair created
4442026-10-15T04:20:40.297ZOct 15 04:20:40.266 DEBG destroying link interop_bgpu_peer_vn_vnic0
4452026-10-15T04:20:40.297ZOct 15 04:20:40.266 DEBG destroying link interop_bgpu_peer_vn_sim0
4462026-10-15T04:20:40.297ZOct 15 04:20:40.266 INFO creating simnet link 'interop_bgpu_peer_vn_sim0'
4472026-10-15T04:20:40.297ZOct 15 04:20:40.268 INFO creating vnic link 'interop_bgpu_peer_vn_vnic0'
4482026-10-15T04:20:40.297ZOct 15 04:20:40.275 DEBG link pair created
4492026-10-15T04:20:40.297ZOct 15 04:20:40.275 INFO creating external links
4502026-10-15T04:20:40.297ZOct 15 04:20:40.275 DEBG destroying external link interop_bgpu_ox_vn_vnic4
4512026-10-15T04:20:40.297ZOct 15 04:20:40.275 INFO creating external link interop_bgpu_ox_vn_vnic4
4522026-10-15T04:20:40.297ZOct 15 04:20:40.276 DEBG external link interop_bgpu_ox_vn_vnic4@igb0 created
4532026-10-15T04:20:40.298ZOct 15 04:20:40.276 DEBG destroying external link interop_bgpu_cr1_vn_vnic1
4542026-10-15T04:20:40.298ZOct 15 04:20:40.276 INFO creating external link interop_bgpu_cr1_vn_vnic1
4552026-10-15T04:20:40.298ZOct 15 04:20:40.278 DEBG external link interop_bgpu_cr1_vn_vnic1@igb0 created
4562026-10-15T04:20:40.298ZOct 15 04:20:40.278 DEBG destroying external link interop_bgpu_cr2_vn_vnic1
4572026-10-15T04:20:40.298ZOct 15 04:20:40.278 INFO creating external link interop_bgpu_cr2_vn_vnic1
4582026-10-15T04:20:40.298ZOct 15 04:20:40.279 DEBG external link interop_bgpu_cr2_vn_vnic1@igb0 created
4592026-10-15T04:20:40.298ZOct 15 04:20:40.279 DEBG destroying external link interop_bgpu_cr3_vn_vnic1
4602026-10-15T04:20:40.298ZOct 15 04:20:40.279 INFO creating external link interop_bgpu_cr3_vn_vnic1
4612026-10-15T04:20:40.298ZOct 15 04:20:40.280 DEBG external link interop_bgpu_cr3_vn_vnic1@igb0 created
4622026-10-15T04:20:40.298ZOct 15 04:20:40.280 DEBG destroying external link interop_bgpu_peer_vn_vnic1
4632026-10-15T04:20:40.298ZOct 15 04:20:40.280 INFO creating external link interop_bgpu_peer_vn_vnic1
4642026-10-15T04:20:40.298ZOct 15 04:20:40.281 DEBG external link interop_bgpu_peer_vn_vnic1@igb0 created
4652026-10-15T04:20:40.298ZOct 15 04:20:40.281 INFO creating nodes
4662026-10-15T04:20:40.298ZOct 15 04:20:40.281 INFO ox: launching node
4672026-10-15T04:20:40.323ZOct 15 04:20:40.284 INFO cr1: launching node
4682026-10-15T04:20:40.323ZOct 15 04:20:40.286 INFO cr2: launching node
4692026-10-15T04:20:40.323ZOct 15 04:20:40.288 INFO cr3: launching node
4702026-10-15T04:20:40.323ZOct 15 04:20:40.290 INFO peer: launching node
4712026-10-15T04:20:40.323ZOct 15 04:20:40.303 INFO launched instance ox with pid 920 on port 45930
4722026-10-15T04:20:40.323ZOct 15 04:20:40.304 INFO ox: instance ensure
4732026-10-15T04:20:40.324ZOct 15 04:20:40.304 INFO launched instance cr1 with pid 921 on port 56556
4742026-10-15T04:20:40.324ZOct 15 04:20:40.304 INFO cr1: instance ensure
4752026-10-15T04:20:40.324ZOct 15 04:20:40.304 INFO launched instance cr2 with pid 922 on port 65340
4762026-10-15T04:20:40.324ZOct 15 04:20:40.304 INFO cr2: instance ensure
4772026-10-15T04:20:40.324ZOct 15 04:20:40.305 INFO launched instance cr3 with pid 923 on port 36580
4782026-10-15T04:20:40.324ZOct 15 04:20:40.305 INFO cr3: instance ensure
4792026-10-15T04:20:40.324ZOct 15 04:20:40.305 INFO launched instance peer with pid 924 on port 59125
4802026-10-15T04:20:40.324ZOct 15 04:20:40.305 INFO peer: instance ensure
4812026-10-15T04:20:44.004ZOct 15 04:20:43.992 INFO cr3: instance ensure completed after 0 retries
4822026-10-15T04:20:44.005ZOct 15 04:20:43.992 INFO cr3: instance run
4832026-10-15T04:20:44.489ZOct 15 04:20:44.477 INFO cr2: instance ensure completed after 0 retries
4842026-10-15T04:20:44.490ZOct 15 04:20:44.477 INFO cr2: instance run
4852026-10-15T04:20:44.514ZOct 15 04:20:44.477 DEBG [sc] cr2: starting
4862026-10-15T04:20:44.514ZOct 15 04:20:44.477 DEBG [sc] cr2: connecting to [::1]:65340
4872026-10-15T04:20:44.514ZOct 15 04:20:44.478 DEBG [sc] cr2 waiting for prompt
4882026-10-15T04:20:44.514ZOct 15 04:20:44.490 INFO ox: instance ensure completed after 0 retries
4892026-10-15T04:20:44.514ZOct 15 04:20:44.490 INFO ox: instance run
4902026-10-15T04:20:44.514ZOct 15 04:20:44.490 DEBG [sc] ox: starting
4912026-10-15T04:20:44.514ZOct 15 04:20:44.490 DEBG [sc] ox: connecting to [::1]:45930
4922026-10-15T04:20:44.514ZOct 15 04:20:44.491 DEBG [sc] ox waiting for prompt
4932026-10-15T04:20:44.539ZOct 15 04:20:44.507 INFO peer: instance ensure completed after 0 retries
4942026-10-15T04:20:44.539ZOct 15 04:20:44.507 INFO peer: instance run
4952026-10-15T04:20:44.539ZOct 15 04:20:44.507 DEBG [sc] peer: starting
4962026-10-15T04:20:44.539ZOct 15 04:20:44.507 DEBG [sc] peer: connecting to [::1]:59125
4972026-10-15T04:20:44.539ZOct 15 04:20:44.508 DEBG [sc] peer waiting for prompt
4982026-10-15T04:20:44.595ZOct 15 04:20:44.583 INFO cr1: instance ensure completed after 0 retries
4992026-10-15T04:20:44.595ZOct 15 04:20:44.583 INFO cr1: instance run
5002026-10-15T04:20:44.620ZOct 15 04:20:44.583 DEBG [sc] cr1: starting
5012026-10-15T04:20:44.620ZOct 15 04:20:44.583 DEBG [sc] cr1: connecting to [::1]:56556
5022026-10-15T04:20:44.620ZOct 15 04:20:44.584 DEBG [sc] cr1 waiting for prompt
5032026-10-15T04:21:01.719ZOct 15 04:21:01.706 DEBG [sc] cr1: logging in
5042026-10-15T04:21:02.082ZOct 15 04:21:02.069 INFO cr1: mounting /opt/cargo-bay
5052026-10-15T04:21:02.082ZOct 15 04:21:02.069 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; mount -t 9p -o ro,msize=65536 /opt/cargo-bay /opt/cargo-bay`
5062026-10-15T04:21:02.131ZOct 15 04:21:02.102 DEBG [sc] cr1: executing command `cd`
5072026-10-15T04:21:02.131ZOct 15 04:21:02.113 INFO cr1: finished mounting /opt/cargo-bay
5082026-10-15T04:21:02.131ZOct 15 04:21:02.113 DEBG [sc] cr1: executing command `hostname cr1`
5092026-10-15T04:21:02.156ZOct 15 04:21:02.123 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
5102026-10-15T04:21:02.156ZOct 15 04:21:02.134 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
5112026-10-15T04:21:02.180ZOct 15 04:21:02.145 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
5122026-10-15T04:21:02.181ZOct 15 04:21:02.156 INFO cr1: logging out
5132026-10-15T04:21:03.465ZOct 15 04:21:03.452 DEBG [sc] peer: logging in
5142026-10-15T04:21:03.489ZOct 15 04:21:03.468 DEBG [sc] ox: logging in
5152026-10-15T04:21:03.672ZOct 15 04:21:03.658 INFO ox: mounting /opt/cargo-bay
5162026-10-15T04:21:03.672ZOct 15 04:21:03.658 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
5172026-10-15T04:21:03.699ZOct 15 04:21:03.671 INFO peer: mounting /opt/cargo-bay
5182026-10-15T04:21:03.699ZOct 15 04:21:03.671 DEBG [sc] peer: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
5192026-10-15T04:21:04.235ZOct 15 04:21:04.222 INFO cr1: logged out
5202026-10-15T04:21:04.919ZOct 15 04:21:04.905 DEBG [sc] ox: executing command `cd`
5212026-10-15T04:21:04.942ZOct 15 04:21:04.910 DEBG [sc] peer: executing command `cd`
5222026-10-15T04:21:04.942ZOct 15 04:21:04.916 INFO ox: finished mounting /opt/cargo-bay
5232026-10-15T04:21:04.942ZOct 15 04:21:04.916 DEBG [sc] ox: executing command `hostname ox`
5242026-10-15T04:21:04.943ZOct 15 04:21:04.922 INFO peer: finished mounting /opt/cargo-bay
5252026-10-15T04:21:04.943ZOct 15 04:21:04.922 DEBG [sc] peer: executing command `hostname peer`
5262026-10-15T04:21:04.943ZOct 15 04:21:04.927 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
5272026-10-15T04:21:04.966ZOct 15 04:21:04.932 DEBG [sc] peer: executing command `echo 'peer' > /etc/nodename`
5282026-10-15T04:21:04.966ZOct 15 04:21:04.939 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
5292026-10-15T04:21:04.966ZOct 15 04:21:04.943 DEBG [sc] peer: executing command `echo '::1 peer.local peer' >> /etc/hosts`
5302026-10-15T04:21:04.966ZOct 15 04:21:04.950 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
5312026-10-15T04:21:04.990ZOct 15 04:21:04.954 DEBG [sc] peer: executing command `echo '127.0.0.1 peer.local peer' >> /etc/hosts`
5322026-10-15T04:21:04.990ZOct 15 04:21:04.960 INFO ox: logging out
5332026-10-15T04:21:04.990ZOct 15 04:21:04.965 INFO peer: logging out
5342026-10-15T04:21:05.050ZOct 15 04:21:05.037 INFO ox: logged out
5352026-10-15T04:21:05.074ZOct 15 04:21:05.054 INFO peer: logged out
5362026-10-15T04:21:06.257ZOct 15 04:21:06.244 DEBG [sc] cr2: logging in
5372026-10-15T04:21:06.662ZOct 15 04:21:06.649 INFO cr2: mounting /opt/cargo-bay
5382026-10-15T04:21:06.662ZOct 15 04:21:06.649 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
5392026-10-15T04:21:06.686ZOct 15 04:21:06.660 DEBG [sc] cr2: executing command `cd`
5402026-10-15T04:21:06.686ZOct 15 04:21:06.671 INFO cr2: finished mounting /opt/cargo-bay
5412026-10-15T04:21:06.686ZOct 15 04:21:06.671 DEBG [sc] cr2: executing command `hostname cr2`
5422026-10-15T04:21:06.709ZOct 15 04:21:06.682 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
5432026-10-15T04:21:06.710ZOct 15 04:21:06.693 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
5442026-10-15T04:21:06.734ZOct 15 04:21:06.704 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
5452026-10-15T04:21:06.735ZOct 15 04:21:06.715 INFO cr2: logging out
5462026-10-15T04:21:09.679ZOct 15 04:21:09.665 INFO cr2: logged out
5472026-10-15T04:21:09.679ZOct 15 04:21:09.666 INFO interop_bgpu: interop topology launch complete
5482026-10-15T04:21:09.702ZOct 15 04:21:09.666 DEBG [sc] ox: starting
5492026-10-15T04:21:09.702ZOct 15 04:21:09.666 DEBG [sc] ox: connecting to [::1]:45930
5502026-10-15T04:21:09.703ZOct 15 04:21:09.666 DEBG [sc] peer: starting
5512026-10-15T04:21:09.703ZOct 15 04:21:09.666 DEBG [sc] peer: connecting to [::1]:59125
5522026-10-15T04:21:09.703ZOct 15 04:21:09.666 DEBG [sc] ox waiting for prompt
5532026-10-15T04:21:09.703ZOct 15 04:21:09.666 DEBG [sc] peer waiting for prompt
5542026-10-15T04:21:09.703ZOct 15 04:21:09.678 DEBG [sc] peer: logging in
5552026-10-15T04:21:09.703ZOct 15 04:21:09.678 DEBG [sc] ox: logging in
5562026-10-15T04:21:09.778ZOct 15 04:21:09.766 DEBG [sc] peer: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
5572026-10-15T04:21:09.802ZOct 15 04:21:09.766 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
5582026-10-15T04:21:15.006ZOct 15 04:21:14.993 DEBG [sc] ox: starting
5592026-10-15T04:21:15.007ZOct 15 04:21:14.993 DEBG [sc] ox: connecting to [::1]:45930
5602026-10-15T04:21:15.030ZOct 15 04:21:14.994 DEBG [sc] ox waiting for prompt
5612026-10-15T04:21:15.030ZOct 15 04:21:15.004 DEBG [sc] ox: logging in
5622026-10-15T04:21:15.106ZOct 15 04:21:15.093 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
5632026-10-15T04:21:15.152ZOct 15 04:21:15.139 DEBG [sc] peer: starting
5642026-10-15T04:21:15.152ZOct 15 04:21:15.139 DEBG [sc] peer: connecting to [::1]:59125
5652026-10-15T04:21:15.176ZOct 15 04:21:15.139 DEBG [sc] peer waiting for prompt
5662026-10-15T04:21:15.176ZOct 15 04:21:15.150 DEBG [sc] peer: logging in
5672026-10-15T04:21:15.200ZOct 15 04:21:15.181 DEBG [sc] ox: starting
5682026-10-15T04:21:15.200ZOct 15 04:21:15.181 DEBG [sc] ox: connecting to [::1]:45930
5692026-10-15T04:21:15.200ZOct 15 04:21:15.182 DEBG [sc] ox waiting for prompt
5702026-10-15T04:21:15.223ZOct 15 04:21:15.192 DEBG [sc] ox: logging in
5712026-10-15T04:21:15.253ZOct 15 04:21:15.238 DEBG [sc] peer: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
5722026-10-15T04:21:15.293ZOct 15 04:21:15.280 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
5732026-10-15T04:21:15.350ZOct 15 04:21:15.337 DEBG [sc] peer: starting
5742026-10-15T04:21:15.350ZOct 15 04:21:15.337 DEBG [sc] peer: connecting to [::1]:59125
5752026-10-15T04:21:15.373ZOct 15 04:21:15.338 DEBG [sc] peer waiting for prompt
5762026-10-15T04:21:15.373ZOct 15 04:21:15.348 DEBG [sc] peer: logging in
5772026-10-15T04:21:15.449ZOct 15 04:21:15.436 DEBG [sc] peer: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
5782026-10-15T04:21:15.516ZOct 15 04:21:15.503 INFO cr1: installing frr
5792026-10-15T04:21:15.516ZOct 15 04:21:15.503 INFO ox: setting up npuvm
5802026-10-15T04:21:15.516ZOct 15 04:21:15.503 INFO cr3: starting juniper unnumbered setup
5812026-10-15T04:21:15.516ZOct 15 04:21:15.503 INFO waiting for ceos to initialize
5822026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] cr2: starting
5832026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] cr2: connecting to [::1]:65340
5842026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] cr1: starting
5852026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] cr1: connecting to [::1]:56556
5862026-10-15T04:21:15.539ZOct 15 04:21:15.503 INFO cr3: staged Juniper config and license for guest systemd services
5872026-10-15T04:21:15.539ZOct 15 04:21:15.503 INFO cr3: waiting for Juniper guest config apply
5882026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] ox: starting
5892026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] ox: connecting to [::1]:45930
5902026-10-15T04:21:15.539ZOct 15 04:21:15.503 DEBG [sc] cr3: starting
5912026-10-15T04:21:15.540ZOct 15 04:21:15.503 DEBG [sc] cr3: connecting to [::1]:36580
5922026-10-15T04:21:15.540ZOct 15 04:21:15.503 DEBG [sc] cr2 waiting for prompt
5932026-10-15T04:21:15.540ZOct 15 04:21:15.503 DEBG [sc] cr1 waiting for prompt
5942026-10-15T04:21:15.540ZOct 15 04:21:15.504 DEBG [sc] ox waiting for prompt
5952026-10-15T04:21:15.540ZOct 15 04:21:15.504 DEBG [sc] cr3 waiting for prompt
5962026-10-15T04:21:15.540ZOct 15 04:21:15.504 DEBG [sc] cr3: logging in
5972026-10-15T04:21:15.540ZOct 15 04:21:15.515 DEBG [sc] cr2: logging in
5982026-10-15T04:21:15.540ZOct 15 04:21:15.515 DEBG [sc] cr1: logging in
5992026-10-15T04:21:15.540ZOct 15 04:21:15.515 DEBG [sc] ox: logging in
6002026-10-15T04:21:15.616ZOct 15 04:21:15.603 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`
6012026-10-15T04:21:15.661ZOct 15 04:21:15.648 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
6022026-10-15T04:21:15.911ZOct 15 04:21:15.898 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
6032026-10-15T04:21:15.941ZOct 15 04:21:15.927 DEBG [sc] cr3: executing command `cat /run/falcon-junos-apply.status 2>/dev/null || true`
6042026-10-15T04:21:17.908ZOct 15 04:21:17.894 INFO cr2: executing eos script show version
6052026-10-15T04:21:17.908ZOct 15 04:21:17.894 DEBG [sc] cr2: starting
6062026-10-15T04:21:17.908ZOct 15 04:21:17.894 DEBG [sc] cr2: connecting to [::1]:65340
6072026-10-15T04:21:17.931ZOct 15 04:21:17.895 DEBG [sc] cr2 waiting for prompt
6082026-10-15T04:21:17.931ZOct 15 04:21:17.905 DEBG [sc] cr2: logging in
6092026-10-15T04:21:18.028ZOct 15 04:21:18.015 INFO cr3: Juniper apply status: waiting for staged Juniper license and config
6102026-10-15T04:21:18.082ZOct 15 04:21:18.069 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
6112026-10-15T04:21:19.029ZOct 15 04:21:19.016 DEBG [sc] cr3: starting
6122026-10-15T04:21:19.029ZOct 15 04:21:19.016 DEBG [sc] cr3: connecting to [::1]:36580
6132026-10-15T04:21:19.052ZOct 15 04:21:19.017 DEBG [sc] cr3 waiting for prompt
6142026-10-15T04:21:19.052ZOct 15 04:21:19.028 DEBG [sc] cr3: logging in
6152026-10-15T04:21:19.194ZOct 15 04:21:19.181 DEBG [sc] cr3: executing command `cat /run/falcon-junos-apply.status 2>/dev/null || true`
6162026-10-15T04:21:21.268ZOct 15 04:21:21.254 INFO cr3: Juniper guest config applied
6172026-10-15T04:21:21.396ZOct 15 04:21:21.383 DEBG [sc] ox: starting
6182026-10-15T04:21:21.396ZOct 15 04:21:21.383 DEBG [sc] ox: connecting to [::1]:45930
6192026-10-15T04:21:21.419ZOct 15 04:21:21.383 DEBG [sc] ox waiting for prompt
6202026-10-15T04:21:21.419ZOct 15 04:21:21.393 DEBG [sc] ox: logging in
6212026-10-15T04:21:21.495ZOct 15 04:21:21.482 DEBG [sc] ox: executing command `cat npuvm.sha256.txt`
6222026-10-15T04:21:21.561ZOct 15 04:21:21.548 DEBG [sc] ox: starting
6232026-10-15T04:21:21.562ZOct 15 04:21:21.548 DEBG [sc] ox: connecting to [::1]:45930
6242026-10-15T04:21:21.585ZOct 15 04:21:21.549 DEBG [sc] ox waiting for prompt
6252026-10-15T04:21:21.585ZOct 15 04:21:21.559 DEBG [sc] ox: logging in
6262026-10-15T04:21:21.649ZOct 15 04:21:21.636 DEBG [sc] ox: executing command `digest -a sha256 npuvm`
6272026-10-15T04:21:21.760ZOct 15 04:21:21.747 INFO ox: npuvm downloaded and checksum verified (attempt 1/5)
6282026-10-15T04:21:21.760ZOct 15 04:21:21.747 DEBG [sc] ox: starting
6292026-10-15T04:21:21.760ZOct 15 04:21:21.747 DEBG [sc] ox: connecting to [::1]:45930
6302026-10-15T04:21:21.784ZOct 15 04:21:21.748 DEBG [sc] ox waiting for prompt
6312026-10-15T04:21:21.784ZOct 15 04:21:21.758 DEBG [sc] ox: logging in
6322026-10-15T04:21:21.848ZOct 15 04:21:21.835 DEBG [sc] ox: executing command `chmod +x npuvm`
6332026-10-15T04:21:21.914ZOct 15 04:21:21.902 DEBG [sc] ox: starting
6342026-10-15T04:21:21.914ZOct 15 04:21:21.902 DEBG [sc] ox: connecting to [::1]:45930
6352026-10-15T04:21:21.938ZOct 15 04:21:21.902 DEBG [sc] ox waiting for prompt
6362026-10-15T04:21:21.938ZOct 15 04:21:21.912 DEBG [sc] ox: logging in
6372026-10-15T04:21:22.002ZOct 15 04:21:21.989 DEBG [sc] ox: executing command `./npuvm install --front-ports 4 --rear-ports 0 --pkt-source vioif0 `
6382026-10-15T04:21:27.449ZOct 15 04:21:27.436 DEBG [sc] cr2: starting
6392026-10-15T04:21:27.449ZOct 15 04:21:27.436 DEBG [sc] cr2: connecting to [::1]:65340
6402026-10-15T04:21:27.472ZOct 15 04:21:27.437 DEBG [sc] cr2 waiting for prompt
6412026-10-15T04:21:27.472ZOct 15 04:21:27.447 DEBG [sc] cr2: logging in
6422026-10-15T04:21:27.614ZOct 15 04:21:27.601 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'`
6432026-10-15T04:21:29.726ZOct 15 04:21:29.714 INFO cr2: executing eos script show version
6442026-10-15T04:21:29.727ZOct 15 04:21:29.714 DEBG [sc] cr2: starting
6452026-10-15T04:21:29.727ZOct 15 04:21:29.714 DEBG [sc] cr2: connecting to [::1]:65340
6462026-10-15T04:21:29.750ZOct 15 04:21:29.714 DEBG [sc] cr2 waiting for prompt
6472026-10-15T04:21:29.750ZOct 15 04:21:29.725 DEBG [sc] cr2: logging in
6482026-10-15T04:21:29.881ZOct 15 04:21:29.868 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'`
6492026-10-15T04:21:32.236ZOct 15 04:21:32.223 INFO cr2: executing eos script
6502026-10-15T04:21:32.236Z enable
6512026-10-15T04:21:32.236Z configure
6522026-10-15T04:21:32.238Z ipv6 unicast-routing
6532026-10-15T04:21:32.238Z ip routing ipv6 interfaces
6542026-10-15T04:21:32.238Z ip routing
6552026-10-15T04:21:32.238Z ip route 1.2.3.0/24 null0
6562026-10-15T04:21:32.238Z ipv6 route fd99::/64 null0
6572026-10-15T04:21:32.238Z interface et1
6582026-10-15T04:21:32.238Z no switchport
6592026-10-15T04:21:32.238Z ipv6 enable
6602026-10-15T04:21:32.238Z
6612026-10-15T04:21:32.238Z router bgp 45
6622026-10-15T04:21:32.238Z router-id 1.2.3.1
6632026-10-15T04:21:32.238Z no bgp default ipv4-unicast
6642026-10-15T04:21:32.238Z timers bgp 2 6
6652026-10-15T04:21:32.238Z neighbor ebgp peer group
6662026-10-15T04:21:32.238Z neighbor ebgp remote-as 33
6672026-10-15T04:21:32.238Z neighbor interface Et1 peer-group ebgp
6682026-10-15T04:21:32.238Z address-family ipv4
6692026-10-15T04:21:32.239Z neighbor ebgp activate
6702026-10-15T04:21:32.239Z neighbor ebgp next-hop address-family ipv6 originate
6712026-10-15T04:21:32.239Z network 1.2.3.0/24
6722026-10-15T04:21:32.239Z exit
6732026-10-15T04:21:32.239Z address-family ipv6
6742026-10-15T04:21:32.239Z neighbor ebgp activate
6752026-10-15T04:21:32.239Z neighbor ebgp next-hop address-family ipv6 originate
6762026-10-15T04:21:32.239Z network fd99::/64
6772026-10-15T04:21:32.239Z exit
6782026-10-15T04:21:32.239Z exit
6792026-10-15T04:21:32.239Z
6802026-10-15T04:21:32.239ZOct 15 04:21:32.223 DEBG [sc] cr2: starting
6812026-10-15T04:21:32.240ZOct 15 04:21:32.223 DEBG [sc] cr2: connecting to [::1]:65340
6822026-10-15T04:21:32.264ZOct 15 04:21:32.223 DEBG [sc] cr2 waiting for prompt
6832026-10-15T04:21:32.264ZOct 15 04:21:32.234 DEBG [sc] cr2: logging in
6842026-10-15T04:21:32.412ZOct 15 04:21:32.399 DEBG [sc] cr2: executing command `docker exec ceos Cli -c '
6852026-10-15T04:21:32.413Z enable
6862026-10-15T04:21:32.413Z configure
6872026-10-15T04:21:32.413Z ipv6 unicast-routing
6882026-10-15T04:21:32.413Z ip routing ipv6 interfaces
6892026-10-15T04:21:32.413Z ip routing
6902026-10-15T04:21:32.413Z ip route 1.2.3.0/24 null0
6912026-10-15T04:21:32.413Z ipv6 route fd99::/64 null0
6922026-10-15T04:21:32.413Z interface et1
6932026-10-15T04:21:32.413Z no switchport
6942026-10-15T04:21:32.413Z ipv6 enable
6952026-10-15T04:21:32.413Z
6962026-10-15T04:21:32.413Z router bgp 45
6972026-10-15T04:21:32.413Z router-id 1.2.3.1
6982026-10-15T04:21:32.413Z no bgp default ipv4-unicast
6992026-10-15T04:21:32.413Z timers bgp 2 6
7002026-10-15T04:21:32.413Z neighbor ebgp peer group
7012026-10-15T04:21:32.413Z neighbor ebgp remote-as 33
7022026-10-15T04:21:32.413Z neighbor interface Et1 peer-group ebgp
7032026-10-15T04:21:32.413Z address-family ipv4
7042026-10-15T04:21:32.413Z neighbor ebgp activate
7052026-10-15T04:21:32.413Z neighbor ebgp next-hop address-family ipv6 originate
7062026-10-15T04:21:32.413Z network 1.2.3.0/24
7072026-10-15T04:21:32.413Z exit
7082026-10-15T04:21:32.413Z address-family ipv6
7092026-10-15T04:21:32.413Z neighbor ebgp activate
7102026-10-15T04:21:32.414Z neighbor ebgp next-hop address-family ipv6 originate
7112026-10-15T04:21:32.414Z network fd99::/64
7122026-10-15T04:21:32.414Z exit
7132026-10-15T04:21:32.414Z exit
7142026-10-15T04:21:32.414Z '`
7152026-10-15T04:21:54.735ZOct 15 04:21:54.722 INFO cr1: enabling frr daemon bgpd
7162026-10-15T04:21:54.735ZOct 15 04:21:54.722 DEBG [sc] cr1: starting
7172026-10-15T04:21:54.735ZOct 15 04:21:54.722 DEBG [sc] cr1: connecting to [::1]:56556
7182026-10-15T04:21:54.758ZOct 15 04:21:54.722 DEBG [sc] cr1 waiting for prompt
7192026-10-15T04:21:54.758ZOct 15 04:21:54.733 DEBG [sc] cr1: logging in
7202026-10-15T04:21:54.866ZOct 15 04:21:54.854 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
7212026-10-15T04:21:56.935ZOct 15 04:21:56.922 INFO cr1: stopping frr
7222026-10-15T04:21:56.935ZOct 15 04:21:56.922 DEBG [sc] cr1: starting
7232026-10-15T04:21:56.935ZOct 15 04:21:56.922 DEBG [sc] cr1: connecting to [::1]:56556
7242026-10-15T04:21:56.959ZOct 15 04:21:56.923 DEBG [sc] cr1 waiting for prompt
7252026-10-15T04:21:56.959ZOct 15 04:21:56.933 DEBG [sc] cr1: logging in
7262026-10-15T04:21:57.080ZOct 15 04:21:57.066 DEBG [sc] cr1: executing command `systemctl stop frr`
7272026-10-15T04:21:59.455ZOct 15 04:21:59.442 INFO cr1: starting frr
7282026-10-15T04:21:59.455ZOct 15 04:21:59.442 DEBG [sc] cr1: starting
7292026-10-15T04:21:59.455ZOct 15 04:21:59.442 DEBG [sc] cr1: connecting to [::1]:56556
7302026-10-15T04:21:59.480ZOct 15 04:21:59.443 DEBG [sc] cr1 waiting for prompt
7312026-10-15T04:21:59.480ZOct 15 04:21:59.453 DEBG [sc] cr1: logging in
7322026-10-15T04:21:59.599ZOct 15 04:21:59.586 DEBG [sc] cr1: executing command `systemctl start frr`
7332026-10-15T04:22:06.954ZOct 15 04:22:06.939 INFO cr1: executing frr script
7342026-10-15T04:22:06.954Z configure
7352026-10-15T04:22:06.954Z ip forwarding
7362026-10-15T04:22:06.954Z ipv6 forwarding
7372026-10-15T04:22:06.954Z ip route 1.2.3.0/24 null0
7382026-10-15T04:22:06.954Z ipv6 route fd99::/64 null0
7392026-10-15T04:22:06.954Z router bgp 44
7402026-10-15T04:22:06.954Z no bgp ebgp-requires-policy
7412026-10-15T04:22:06.954Z timers bgp 2 6
7422026-10-15T04:22:06.954Z neighbor enp0s8 interface remote-as external
7432026-10-15T04:22:06.954Z neighbor enp0s8 timers connect 1
7442026-10-15T04:22:06.954Z address-family ipv4 unicast
7452026-10-15T04:22:06.954Z network 1.2.3.0/24
7462026-10-15T04:22:06.954Z neighbor enp0s8 activate
7472026-10-15T04:22:06.954Z exit-address-family
7482026-10-15T04:22:06.954Z address-family ipv6 unicast
7492026-10-15T04:22:06.955Z network fd99::/64
7502026-10-15T04:22:06.955Z neighbor enp0s8 activate
7512026-10-15T04:22:06.955Z exit-address-family
7522026-10-15T04:22:06.955Z exit
7532026-10-15T04:22:06.955Z
7542026-10-15T04:22:06.955ZOct 15 04:22:06.939 DEBG [sc] cr1: starting
7552026-10-15T04:22:06.955ZOct 15 04:22:06.939 DEBG [sc] cr1: connecting to [::1]:56556
7562026-10-15T04:22:06.980ZOct 15 04:22:06.940 DEBG [sc] cr1 waiting for prompt
7572026-10-15T04:22:06.980ZOct 15 04:22:06.951 DEBG [sc] cr1: logging in
7582026-10-15T04:22:07.095ZOct 15 04:22:07.083 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 ' '`
7592026-10-15T04:22:11.190ZOct 15 04:22:11.177 INFO ox: npuvm install output:
7602026-10-15T04:22:11.190Zdendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) dendrite-softnpu.tar.gz [########################################] 0 B/0 B (0s) WARNING: vioif0: need 3 MSI-X interrupts, but only 2 available
7612026-10-15T04:22:11.300Z dendrite-softnpu.tar.gz [----------------------------------------] 0 B/82.16 MiB (0s) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 3.82 KiB/82.16 MiB (11h) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 7.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 15.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 15.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 15.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 15.81 KiB/82.16 MiB (10h) dendrite-softnpu.tar.gz [>---------------------------------------] 67.22 KiB/82.16 MiB (7h) dendrite-softnpu.tar.gz [>---------------------------------------] 161.69 KiB/82.16 MiB (4h) dendrite-softnpu.tar.gz [>---------------------------------------] 370.58 KiB/82.16 MiB (2h) dendrite-softnpu.tar.gz [>---------------------------------------] 739.46 KiB/82.16 MiB (63m) dendrite-softnpu.tar.gz [>---------------------------------------] 984.20 KiB/82.16 MiB (41m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.20 MiB/82.16 MiB (29m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.56 MiB/82.16 MiB (19m) dendrite-softnpu.tar.gz [>---------------------------------------] 1.80 MiB/82.16 MiB (15m) dendrite-softnpu.tar.gz [>---------------------------------------] 2.04 MiB/82.16 MiB (12m) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.40 MiB/82.16 MiB (9m) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.64 MiB/82.16 MiB (8m) dendrite-softnpu.tar.gz [#>--------------------------------------] 2.88 MiB/82.16 MiB (7m) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.12 MiB/82.16 MiB (6m) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.46 MiB/82.16 MiB (5m) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.72 MiB/82.16 MiB (5m) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.72 MiB/82.16 MiB (4m) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.73 MiB/82.16 MiB (4m) dendrite-softnpu.tar.gz [#>--------------------------------------] 3.96 MiB/82.16 MiB (3m) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.20 MiB/82.16 MiB (3m) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.44 MiB/82.16 MiB (3m) dendrite-softnpu.tar.gz [##>-------------------------------------] 4.78 MiB/82.16 MiB (3m) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.04 MiB/82.16 MiB (3m) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.28 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.57 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [##>-------------------------------------] 5.88 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [##>-------------------------------------] 6.12 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 6.37 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 6.72 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 6.96 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 7.21 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 7.49 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 7.80 MiB/82.16 MiB (2m) dendrite-softnpu.tar.gz [###>------------------------------------] 8.04 MiB/82.16 MiB (88s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.29 MiB/82.16 MiB (85s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.58 MiB/82.16 MiB (81s) dendrite-softnpu.tar.gz [####>-----------------------------------] 8.88 MiB/82.16 MiB (78s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.13 MiB/82.16 MiB (75s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.42 MiB/82.16 MiB (72s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.72 MiB/82.16 MiB (70s) dendrite-softnpu.tar.gz [####>-----------------------------------] 9.97 MiB/82.16 MiB (67s) dendrite-softnpu.tar.gz [####>-----------------------------------] 10.26 MiB/82.16 MiB (65s) dendrite-softnpu.tar.gz [#####>----------------------------------] 10.55 MiB/82.16 MiB (62s) dendrite-softnpu.tar.gz [#####>----------------------------------] 10.79 MiB/82.16 MiB (61s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.04 MiB/82.16 MiB (59s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.29 MiB/82.16 MiB (57s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.59 MiB/82.16 MiB (56s) dendrite-softnpu.tar.gz [#####>----------------------------------] 11.88 MiB/82.16 MiB (54s) dendrite-softnpu.tar.gz [#####>----------------------------------] 12.13 MiB/82.16 MiB (53s) dendrite-softnpu.tar.gz [######>---------------------------------] 12.41 MiB/82.16 MiB (51s) dendrite-softnpu.tar.gz [######>---------------------------------] 12.71 MiB/82.16 MiB (50s) dendrite-softnpu.tar.gz [######>---------------------------------] 12.95 MiB/82.16 MiB (49s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.24 MiB/82.16 MiB (48s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.55 MiB/82.16 MiB (46s) dendrite-softnpu.tar.gz [######>---------------------------------] 13.79 MiB/82.16 MiB (45s) dendrite-softnpu.tar.gz [######>---------------------------------] 14.06 MiB/82.16 MiB (44s) dendrite-softnpu.tar.gz [######>---------------------------------] 14.33 MiB/82.16 MiB (43s) dendrite-softnpu.tar.gz [#######>--------------------------------] 14.58 MiB/82.16 MiB (42s) dendrite-softnpu.tar.gz [#######>--------------------------------] 14.89 MiB/82.16 MiB (41s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.16 MiB/82.16 MiB (40s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.39 MiB/82.16 MiB (40s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.66 MiB/82.16 MiB (39s) dendrite-softnpu.tar.gz [#######>--------------------------------] 15.97 MiB/82.16 MiB (38s) dendrite-softnpu.tar.gz [#######>--------------------------------] 16.22 MiB/82.16 MiB (37s) dendrite-softnpu.tar.gz [########>-------------------------------] 16.49 MiB/82.16 MiB (37s) dendrite-softnpu.tar.gz [########>-------------------------------] 16.81 MiB/82.16 MiB (36s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.06 MiB/82.16 MiB (35s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.33 MiB/82.16 MiB (34s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.65 MiB/82.16 MiB (34s) dendrite-softnpu.tar.gz [########>-------------------------------] 17.90 MiB/82.16 MiB (33s) dendrite-softnpu.tar.gz [########>-------------------------------] 18.17 MiB/82.16 MiB (33s) dendrite-softnpu.tar.gz [########>-------------------------------] 18.46 MiB/82.16 MiB (32s) dendrite-softnpu.tar.gz [#########>------------------------------] 18.74 MiB/82.16 MiB (31s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.00 MiB/82.16 MiB (31s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.28 MiB/82.16 MiB (30s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.57 MiB/82.16 MiB (30s) dendrite-softnpu.tar.gz [#########>------------------------------] 19.82 MiB/82.16 MiB (29s) dendrite-softnpu.tar.gz [#########>------------------------------] 20.09 MiB/82.16 MiB (29s) dendrite-softnpu.tar.gz [#########>------------------------------] 20.40 MiB/82.16 MiB (29s) dendrite-softnpu.tar.gz [##########>-----------------------------] 20.66 MiB/82.16 MiB (28s) dendrite-softnpu.tar.gz [##########>-----------------------------] 20.92 MiB/82.16 MiB (28s) dendrite-softnpu.tar.gz [##########>-----------------------------] 21.17 MiB/82.16 MiB (27s) dendrite-softnpu.tar.gz [##########>-----------------------------] 21.49 MiB/82.16 MiB (27s) dendrite-softnpu.tar.gz [##########>-----------------------------] 21.76 MiB/82.16 MiB (26s) dendrite-softnpu.tar.gz [##########>-----------------------------] 22.01 MiB/82.16 MiB (26s) dendrite-softnpu.tar.gz [##########>-----------------------------] 22.33 MiB/82.16 MiB (26s) dendrite-softnpu.tar.gz [##########>-----------------------------] 22.58 MiB/82.16 MiB (25s) dendrite-softnpu.tar.gz [###########>----------------------------] 22.84 MiB/82.16 MiB (25s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.16 MiB/82.16 MiB (25s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.42 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [###########>----------------------------] 23.68 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [###########>----------------------------] 24.00 MiB/82.16 MiB (24s) dendrite-softnpu.tar.gz [###########>----------------------------] 24.25 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [###########>----------------------------] 24.51 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [############>---------------------------] 24.84 MiB/82.16 MiB (23s) dendrite-softnpu.tar.gz [############>---------------------------] 25.09 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [############>---------------------------] 25.35 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [############>---------------------------] 25.65 MiB/82.16 MiB (22s) dendrite-softnpu.tar.gz [############>---------------------------] 25.92 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [############>---------------------------] 26.20 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [############>---------------------------] 26.43 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [#############>--------------------------] 26.76 MiB/82.16 MiB (21s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.03 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.27 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.59 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [#############>--------------------------] 27.87 MiB/82.16 MiB (20s) dendrite-softnpu.tar.gz [#############>--------------------------] 28.11 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 28.42 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [#############>--------------------------] 28.71 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [##############>-------------------------] 28.95 MiB/82.16 MiB (19s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.26 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.54 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 29.79 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 30.09 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 30.38 MiB/82.16 MiB (18s) dendrite-softnpu.tar.gz [##############>-------------------------] 30.63 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 30.92 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.19 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.47 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 31.74 MiB/82.16 MiB (17s) dendrite-softnpu.tar.gz [###############>------------------------] 32.02 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [###############>------------------------] 32.31 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [###############>------------------------] 32.55 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [###############>------------------------] 32.84 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.12 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.39 MiB/82.16 MiB (16s) dendrite-softnpu.tar.gz [################>-----------------------] 33.68 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [################>-----------------------] 33.96 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [################>-----------------------] 34.22 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [################>-----------------------] 34.46 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [################>-----------------------] 34.74 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 35.06 MiB/82.16 MiB (15s) dendrite-softnpu.tar.gz [#################>----------------------] 35.30 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 35.55 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 35.90 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 36.14 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 36.38 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [#################>----------------------] 36.74 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 36.98 MiB/82.16 MiB (14s) dendrite-softnpu.tar.gz [##################>---------------------] 37.22 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 37.53 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 37.82 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.06 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.35 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.66 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.80 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.81 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.82 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.85 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [##################>---------------------] 38.91 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 39.20 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 39.44 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 39.68 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 40.04 MiB/82.16 MiB (13s) dendrite-softnpu.tar.gz [###################>--------------------] 40.28 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [###################>--------------------] 40.52 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [###################>--------------------] 40.88 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.12 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.36 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.64 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 41.96 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.20 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.44 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 42.80 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [####################>-------------------] 43.04 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [#####################>------------------] 43.28 MiB/82.16 MiB (12s) dendrite-softnpu.tar.gz [#####################>------------------] 43.54 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 43.88 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.12 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.37 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.72 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [#####################>------------------] 44.95 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 45.19 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 45.55 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 45.79 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 46.03 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 46.39 MiB/82.16 MiB (11s) dendrite-softnpu.tar.gz [######################>-----------------] 46.63 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 46.87 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [######################>-----------------] 47.22 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 47.48 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 47.72 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 48.05 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 48.31 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 48.56 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 48.88 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [#######################>----------------] 49.15 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [########################>---------------] 49.39 MiB/82.16 MiB (10s) dendrite-softnpu.tar.gz [########################>---------------] 49.68 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.00 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.23 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.49 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 50.83 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 51.07 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [########################>---------------] 51.32 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#########################>--------------] 51.67 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#########################>--------------] 51.91 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#########################>--------------] 52.15 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#########################>--------------] 52.51 MiB/82.16 MiB (9s) dendrite-softnpu.tar.gz [#########################>--------------] 52.75 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 52.99 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [#########################>--------------] 53.31 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 53.55 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 53.79 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.04 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.30 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.63 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 54.87 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [##########################>-------------] 55.14 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [###########################>------------] 55.46 MiB/82.16 MiB (8s) dendrite-softnpu.tar.gz [###########################>------------] 55.71 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 55.98 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.30 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.54 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 56.82 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 57.14 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [###########################>------------] 57.39 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 57.66 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 57.98 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.22 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.50 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 58.74 MiB/82.16 MiB (7s) dendrite-softnpu.tar.gz [############################>-----------] 59.06 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [############################>-----------] 59.32 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 59.57 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 59.90 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.16 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.41 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.74 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 60.98 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 61.25 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [#############################>----------] 61.58 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 61.82 MiB/82.16 MiB (6s) dendrite-softnpu.tar.gz [##############################>---------] 62.09 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 62.41 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 62.66 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 62.93 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 63.17 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [##############################>---------] 63.47 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 63.73 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 63.99 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.29 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.58 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 64.83 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 65.10 MiB/82.16 MiB (5s) dendrite-softnpu.tar.gz [###############################>--------] 65.41 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [###############################>--------] 65.65 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 65.91 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 66.24 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 66.49 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 66.75 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 67.07 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 67.33 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [################################>-------] 67.58 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 67.83 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.06 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.38 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.66 MiB/82.16 MiB (4s) dendrite-softnpu.tar.gz [#################################>------] 68.90 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 69.15 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 69.46 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [#################################>------] 69.74 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 69.98 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.24 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.58 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 70.82 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 71.06 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 71.37 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [##################################>-----] 71.66 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 71.90 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 72.19 MiB/82.16 MiB (3s) dendrite-softnpu.tar.gz [###################################>----] 72.50 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 72.74 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 73.00 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 73.24 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 73.54 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [###################################>----] 73.83 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.08 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.34 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.67 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 74.91 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.16 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.44 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.75 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [####################################>---] 75.99 MiB/82.16 MiB (2s) dendrite-softnpu.tar.gz [#####################################>--] 76.27 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 76.59 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 76.83 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#####################################>--] 77.11 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.94 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.78 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.89 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.90 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.92 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 78.97 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.17 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.41 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.65 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [######################################>-] 79.96 MiB/82.16 MiB (1s) dendrite-softnpu.tar.gz [#######################################>] 80.25 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 80.49 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 80.74 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.09 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.33 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.57 MiB/82.16 MiB (0s) dendrite-softnpu.tar.gz [#######################################>] 81.93 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 (Solver setup): - Creating Plan (Solver setup): \ Creating Plan (Solver setup): | Creating Plan (Solver setup): | Creating Plan (Running solver): / Creating Plan (Running solver): / Creating Plan (Finding local manifests): - Creating Plan (Finding local manifests): - Creating Plan (Download Manifests 0/1) \ Creating Plan (Download Manifests 1/1) \ Creating Plan (Download Manifests 1/1) \ Creating Plan (Package planning: 1/1): | Creating Plan (Package planning: 1/1): | Creating Plan (Merging actions): / Creating Plan (Merging actions): / Creating Plan (Checking for conflicting actions): - Creating Plan (Checking for conflicting actions): \ Creating Plan (Checking for conflicting actions): | Creating Plan (Checking for conflicting actions): | Creating Plan (Consolidating action changes): / Creating Plan (Consolidating action changes): / Creating Plan (Evaluating mediators): - Creating Plan (Evaluating mediators): \ Creating Plan (Evaluating mediators): \ Packages to install: 1
7622026-10-15T04:22:11.370Z Create boot environment: No
7632026-10-15T04:22:11.370ZCreate backup boot environment: No
7642026-10-15T04:22:11.370Z
7652026-10-15T04:22:11.371ZDOWNLOAD PKGS FILES XFER (MB) SPEED
7662026-10-15T04:22:11.371Z driver/misc/tofino 0/1 0/6 0.0/0.0 -- driver/misc/tofino 0/1 4/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 330k/s
7672026-10-15T04:22:11.371Z
7682026-10-15T04:22:11.371ZPHASE ITEMS
7692026-10-15T04:22:11.371Z Installing new actions 1/27 Installing new actions 24/27 Installing new actions 27/27
7702026-10-15T04:22:11.371Z Updating package state database working | Updating package state database working / Updating package state database Done
7712026-10-15T04:22:11.371Z Updating package cache 0/0 - Updating package cache 0/0
7722026-10-15T04:22:11.371Z Updating image state working \ Updating image state working | Updating image state Done
7732026-10-15T04:22:11.371Z 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
7742026-10-15T04:22:11.371Z Reading search index working \ Reading search index Done
7752026-10-15T04:22:11.371Z Updating search index 0/1 | Updating search index 1/1
7762026-10-15T04:22:11.371Z Updating package cache 0/1 / Updating package cache 1/1
7772026-10-15T04:22:11.371Zscadm [########################################] 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 (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 7.81 KiB/15.18 MiB (9m) scadm [>---------------------------------------] 11.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 11.81 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 24.46 KiB/15.18 MiB (8m) scadm [>---------------------------------------] 139.95 KiB/15.18 MiB (3m) scadm [>---------------------------------------] 351.86 KiB/15.18 MiB (2m) scadm [#>--------------------------------------] 717.84 KiB/15.18 MiB (57s) scadm [##>-------------------------------------] 969.62 KiB/15.18 MiB (32s) scadm [###>------------------------------------] 1.19 MiB/15.18 MiB (24s) scadm [####>-----------------------------------] 1.55 MiB/15.18 MiB (18s) scadm [####>-----------------------------------] 1.79 MiB/15.18 MiB (15s) scadm [#####>----------------------------------] 2.03 MiB/15.18 MiB (10s) scadm [#####>----------------------------------] 2.03 MiB/15.18 MiB (10s) scadm [#####>----------------------------------] 2.03 MiB/15.18 MiB (10s) scadm [#####>----------------------------------] 2.15 MiB/15.18 MiB (10s) scadm [######>---------------------------------] 2.39 MiB/15.18 MiB (9s) scadm [#######>--------------------------------] 2.75 MiB/15.18 MiB (8s) scadm [#######>--------------------------------] 2.99 MiB/15.18 MiB (8s) scadm [########>-------------------------------] 3.26 MiB/15.18 MiB (7s) scadm [#########>------------------------------] 3.59 MiB/15.18 MiB (7s) scadm [##########>-----------------------------] 3.83 MiB/15.18 MiB (6s) scadm [###########>----------------------------] 4.19 MiB/15.18 MiB (6s) scadm [###########>----------------------------] 4.43 MiB/15.18 MiB (6s) scadm [############>---------------------------] 4.67 MiB/15.18 MiB (5s) scadm [#############>--------------------------] 5.02 MiB/15.18 MiB (5s) scadm [#############>--------------------------] 5.26 MiB/15.18 MiB (5s) scadm [##############>-------------------------] 5.62 MiB/15.18 MiB (4s) scadm [###############>------------------------] 5.86 MiB/15.18 MiB (4s) scadm [################>-----------------------] 6.17 MiB/15.18 MiB (4s) scadm [#################>----------------------] 6.46 MiB/15.18 MiB (4s) scadm [#################>----------------------] 6.72 MiB/15.18 MiB (3s) scadm [##################>---------------------] 7.06 MiB/15.18 MiB (3s) scadm [###################>--------------------] 7.30 MiB/15.18 MiB (3s) scadm [####################>-------------------] 7.66 MiB/15.18 MiB (3s) scadm [####################>-------------------] 7.90 MiB/15.18 MiB (3s) scadm [#####################>------------------] 8.18 MiB/15.18 MiB (3s) scadm [######################>-----------------] 8.50 MiB/15.18 MiB (2s) scadm [#######################>----------------] 8.77 MiB/15.18 MiB (2s) scadm [#######################>----------------] 9.10 MiB/15.18 MiB (2s) scadm [########################>---------------] 9.36 MiB/15.18 MiB (2s) scadm [#########################>--------------] 9.67 MiB/15.18 MiB (2s) scadm [##########################>-------------] 9.94 MiB/15.18 MiB (2s) scadm [##########################>-------------] 10.22 MiB/15.18 MiB (2s) scadm [###########################>------------] 10.54 MiB/15.18 MiB (1s) scadm [############################>-----------] 10.81 MiB/15.18 MiB (1s) scadm [#############################>----------] 11.14 MiB/15.18 MiB (1s) scadm [##############################>---------] 11.41 MiB/15.18 MiB (1s) scadm [##############################>---------] 11.68 MiB/15.18 MiB (1s) scadm [###############################>--------] 12.00 MiB/15.18 MiB (1s) scadm [################################>-------] 12.27 MiB/15.18 MiB (1s) scadm [#################################>------] 12.57 MiB/15.18 MiB (1s) scadm [#################################>------] 12.87 MiB/15.18 MiB (1s) scadm [##################################>-----] 13.11 MiB/15.18 MiB (1s) scadm [###################################>----] 13.42 MiB/15.18 MiB (1s) scadm [####################################>---] 13.71 MiB/15.18 MiB (0s) scadm [####################################>---] 13.95 MiB/15.18 MiB (0s) scadm [#####################################>--] 14.31 MiB/15.18 MiB (0s) scadm [######################################>-] 14.55 MiB/15.18 MiB (0s) scadm [#######################################>] 14.91 MiB/15.18 MiB (0s) scadm [#######################################>] 15.15 MiB/15.18 MiB (0s) libsidecar_lite.so [########################################] 0 B/0 B (0s) libsidecar_lite.so [########################################] 0 B/0 B (0s) libsidecar_lite.so [----------------------------------------] 0 B/11.89 MiB (0s) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 7.81 KiB/11.89 MiB (5m) libsidecar_lite.so [>---------------------------------------] 24.46 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.46 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.46 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.46 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.46 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 24.46 KiB/11.89 MiB (4m) libsidecar_lite.so [>---------------------------------------] 40.46 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 40.46 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 40.46 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 40.46 KiB/11.89 MiB (3m) libsidecar_lite.so [>---------------------------------------] 139.95 KiB/11.89 MiB (2m) libsidecar_lite.so [#>--------------------------------------] 472.84 KiB/11.89 MiB (44s) libsidecar_lite.so [##>-------------------------------------] 719.70 KiB/11.89 MiB (28s) libsidecar_lite.so [###>------------------------------------] 1.04 MiB/11.89 MiB (19s) libsidecar_lite.so [####>-----------------------------------] 1.30 MiB/11.89 MiB (13s) libsidecar_lite.so [#####>----------------------------------] 1.54 MiB/11.89 MiB (11s) libsidecar_lite.so [######>---------------------------------] 1.90 MiB/11.89 MiB (8s) libsidecar_lite.so [#######>--------------------------------] 2.14 MiB/11.89 MiB (7s) libsidecar_lite.so [########>-------------------------------] 2.39 MiB/11.89 MiB (7s) libsidecar_lite.so [#########>------------------------------] 2.74 MiB/11.89 MiB (6s) libsidecar_lite.so [##########>-----------------------------] 2.98 MiB/11.89 MiB (5s) libsidecar_lite.so [###########>----------------------------] 3.34 MiB/11.89 MiB (4s) libsidecar_lite.so [############>---------------------------] 3.58 MiB/11.89 MiB (4s) libsidecar_lite.so [############>---------------------------] 3.82 MiB/11.89 MiB (4s) libsidecar_lite.so [##############>-------------------------] 4.18 MiB/11.89 MiB (3s) libsidecar_lite.so [##############>-------------------------] 4.41 MiB/11.89 MiB (3s) libsidecar_lite.so [################>-----------------------] 4.76 MiB/11.89 MiB (3s) libsidecar_lite.so [################>-----------------------] 5.02 MiB/11.89 MiB (3s) libsidecar_lite.so [#################>----------------------] 5.27 MiB/11.89 MiB (3s) libsidecar_lite.so [##################>---------------------] 5.61 MiB/11.89 MiB (2s) libsidecar_lite.so [###################>--------------------] 5.85 MiB/11.89 MiB (2s) libsidecar_lite.so [####################>-------------------] 6.16 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.36 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.37 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.38 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.38 MiB/11.89 MiB (3s) libsidecar_lite.so [#####################>------------------] 6.43 MiB/11.89 MiB (3s) libsidecar_lite.so [######################>-----------------] 6.54 MiB/11.89 MiB (3s) libsidecar_lite.so [######################>-----------------] 6.77 MiB/11.89 MiB (2s) libsidecar_lite.so [#######################>----------------] 7.13 MiB/11.89 MiB (2s) libsidecar_lite.so [########################>---------------] 7.36 MiB/11.89 MiB (2s) libsidecar_lite.so [#########################>--------------] 7.63 MiB/11.89 MiB (2s) libsidecar_lite.so [##########################>-------------] 7.96 MiB/11.89 MiB (2s) libsidecar_lite.so [###########################>------------] 8.20 MiB/11.89 MiB (2s) libsidecar_lite.so [############################>-----------] 8.56 MiB/11.89 MiB (2s) libsidecar_lite.so [#############################>----------] 8.80 MiB/11.89 MiB (1s) libsidecar_lite.so [##############################>---------] 9.09 MiB/11.89 MiB (1s) libsidecar_lite.so [###############################>--------] 9.40 MiB/11.89 MiB (1s) libsidecar_lite.so [################################>-------] 9.64 MiB/11.89 MiB (1s) libsidecar_lite.so [#################################>------] 9.96 MiB/11.89 MiB (1s) libsidecar_lite.so [##################################>-----] 10.24 MiB/11.89 MiB (1s) libsidecar_lite.so [###################################>----] 10.48 MiB/11.89 MiB (1s) libsidecar_lite.so [####################################>---] 10.80 MiB/11.89 MiB (1s) libsidecar_lite.so [#####################################>--] 11.08 MiB/11.89 MiB (0s) libsidecar_lite.so [######################################>-] 11.38 MiB/11.89 MiB (0s) libsidecar_lite.so [#######################################>] 11.68 MiB/11.89 MiB (0s)
7782026-10-15T04:22:11.374ZOct 15 04:22:11.177 DEBG [sc] ox: starting
7792026-10-15T04:22:11.374ZOct 15 04:22:11.177 DEBG [sc] ox: connecting to [::1]:45930
7802026-10-15T04:22:11.374ZOct 15 04:22:11.178 DEBG [sc] ox waiting for prompt
7812026-10-15T04:22:11.374ZOct 15 04:22:11.187 DEBG [sc] ox: logging in
7822026-10-15T04:22:11.399ZOct 15 04:22:11.264 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
7832026-10-15T04:22:11.436ZOct 15 04:22:11.423 DEBG [sc] peer: starting
7842026-10-15T04:22:11.436ZOct 15 04:22:11.423 DEBG [sc] peer: connecting to [::1]:59125
7852026-10-15T04:22:11.460ZOct 15 04:22:11.424 DEBG [sc] peer waiting for prompt
7862026-10-15T04:22:11.460ZOct 15 04:22:11.434 DEBG [sc] peer: logging in
7872026-10-15T04:22:11.540ZOct 15 04:22:11.524 DEBG [sc] peer: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
7882026-10-15T04:22:11.602ZOct 15 04:22:11.589 DEBG client request, body: None, uri: http://10.151.7.170:4676/bgp/config/routers, method: GET, operation_id: read_routers
7892026-10-15T04:22:11.626ZOct 15 04:22:11.590 DEBG client response, result: Err(reqwest::Error { kind: Request, url: "http://10.151.7.170:4676/bgp/config/routers", source: hyper_util::client::legacy::Error(Connect, ConnectError("tcp connect error", 10.151.7.170:4676, Os { code: 146, kind: ConnectionRefused, message: "Connection refused" })) }), operation_id: read_routers
7902026-10-15T04:22:11.626ZOct 15 04:22:11.590 DEBG wait for mgd: Communication Error: error sending request for url (http://10.151.7.170:4676/bgp/config/routers)
7912026-10-15T04:22:12.604ZOct 15 04:22:12.591 DEBG client request, body: None, uri: http://10.151.7.170:4676/bgp/config/routers, method: GET, operation_id: read_routers
7922026-10-15T04:22:12.628ZOct 15 04:22:12.594 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "ce3daea1-a4fd-40e4-82a5-414fafd89da0", "content-length": "2", "date": "Thu, 15 Oct 2026 04:22:11 GMT"} }), operation_id: read_routers
7932026-10-15T04:22:12.756ZOct 15 04:22:12.743 DEBG [sc] ox: starting
7942026-10-15T04:22:12.756ZOct 15 04:22:12.743 DEBG [sc] ox: connecting to [::1]:45930
7952026-10-15T04:22:12.780ZOct 15 04:22:12.744 DEBG [sc] ox waiting for prompt
7962026-10-15T04:22:12.780ZOct 15 04:22:12.755 DEBG [sc] ox: logging in
7972026-10-15T04:22:12.847ZOct 15 04:22:12.834 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
7982026-10-15T04:22:13.870ZOct 15 04:22:13.857 DEBG [sc] ox: starting
7992026-10-15T04:22:13.870ZOct 15 04:22:13.857 DEBG [sc] ox: connecting to [::1]:45930
8002026-10-15T04:22:13.894ZOct 15 04:22:13.858 DEBG [sc] ox waiting for prompt
8012026-10-15T04:22:13.894ZOct 15 04:22:13.858 DEBG [sc] ox: logging in
8022026-10-15T04:22:13.947ZOct 15 04:22:13.934 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
8032026-10-15T04:22:14.003ZOct 15 04:22:13.990 DEBG [sc] ox: starting
8042026-10-15T04:22:14.003ZOct 15 04:22:13.990 DEBG [sc] ox: connecting to [::1]:45930
8052026-10-15T04:22:14.026ZOct 15 04:22:13.991 DEBG [sc] ox waiting for prompt
8062026-10-15T04:22:14.026ZOct 15 04:22:14.001 DEBG [sc] ox: logging in
8072026-10-15T04:22:14.090ZOct 15 04:22:14.077 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
8082026-10-15T04:22:14.146ZOct 15 04:22:14.133 DEBG [sc] ox: starting
8092026-10-15T04:22:14.146ZOct 15 04:22:14.133 DEBG [sc] ox: connecting to [::1]:45930
8102026-10-15T04:22:14.169ZOct 15 04:22:14.134 DEBG [sc] ox waiting for prompt
8112026-10-15T04:22:14.169ZOct 15 04:22:14.144 DEBG [sc] ox: logging in
8122026-10-15T04:22:14.233ZOct 15 04:22:14.221 DEBG [sc] ox: executing command `dladm show-link tfportqsfp2_0 -p -o link`
8132026-10-15T04:22:14.290ZOct 15 04:22:14.276 DEBG [sc] ox: starting
8142026-10-15T04:22:14.290ZOct 15 04:22:14.276 DEBG [sc] ox: connecting to [::1]:45930
8152026-10-15T04:22:14.313ZOct 15 04:22:14.277 DEBG [sc] ox waiting for prompt
8162026-10-15T04:22:14.313ZOct 15 04:22:14.287 DEBG [sc] ox: logging in
8172026-10-15T04:22:14.378ZOct 15 04:22:14.365 DEBG [sc] ox: executing command `dladm show-link tfportqsfp3_0 -p -o link`
8182026-10-15T04:22:14.443ZOct 15 04:22:14.431 DEBG [sc] ox: starting
8192026-10-15T04:22:14.444ZOct 15 04:22:14.431 DEBG [sc] ox: connecting to [::1]:45930
8202026-10-15T04:22:14.467ZOct 15 04:22:14.431 DEBG [sc] ox waiting for prompt
8212026-10-15T04:22:14.467ZOct 15 04:22:14.442 DEBG [sc] ox: logging in
8222026-10-15T04:22:14.531ZOct 15 04:22:14.519 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
8232026-10-15T04:22:14.598ZOct 15 04:22:14.585 DEBG [sc] ox: starting
8242026-10-15T04:22:14.598ZOct 15 04:22:14.585 DEBG [sc] ox: connecting to [::1]:45930
8252026-10-15T04:22:14.622ZOct 15 04:22:14.586 DEBG [sc] ox waiting for prompt
8262026-10-15T04:22:14.622ZOct 15 04:22:14.597 DEBG [sc] ox: logging in
8272026-10-15T04:22:14.688ZOct 15 04:22:14.675 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
8282026-10-15T04:22:14.743ZOct 15 04:22:14.730 DEBG [sc] ox: starting
8292026-10-15T04:22:14.743ZOct 15 04:22:14.730 DEBG [sc] ox: connecting to [::1]:45930
8302026-10-15T04:22:14.767ZOct 15 04:22:14.731 DEBG [sc] ox waiting for prompt
8312026-10-15T04:22:14.767ZOct 15 04:22:14.741 DEBG [sc] ox: logging in
8322026-10-15T04:22:14.830ZOct 15 04:22:14.818 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
8332026-10-15T04:22:14.897ZOct 15 04:22:14.884 DEBG [sc] ox: starting
8342026-10-15T04:22:14.897ZOct 15 04:22:14.884 DEBG [sc] ox: connecting to [::1]:45930
8352026-10-15T04:22:14.921ZOct 15 04:22:14.885 DEBG [sc] ox waiting for prompt
8362026-10-15T04:22:14.921ZOct 15 04:22:14.896 DEBG [sc] ox: logging in
8372026-10-15T04:22:14.985ZOct 15 04:22:14.972 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
8382026-10-15T04:22:15.052ZOct 15 04:22:15.039 DEBG [sc] ox: starting
8392026-10-15T04:22:15.052ZOct 15 04:22:15.039 DEBG [sc] ox: connecting to [::1]:45930
8402026-10-15T04:22:15.075ZOct 15 04:22:15.040 DEBG [sc] ox waiting for prompt
8412026-10-15T04:22:15.076ZOct 15 04:22:15.051 DEBG [sc] ox: logging in
8422026-10-15T04:22:15.140ZOct 15 04:22:15.127 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp2_0/ll`
8432026-10-15T04:22:15.196ZOct 15 04:22:15.183 DEBG [sc] ox: starting
8442026-10-15T04:22:15.196ZOct 15 04:22:15.183 DEBG [sc] ox: connecting to [::1]:45930
8452026-10-15T04:22:15.220ZOct 15 04:22:15.184 DEBG [sc] ox waiting for prompt
8462026-10-15T04:22:15.220ZOct 15 04:22:15.193 DEBG [sc] ox: logging in
8472026-10-15T04:22:15.294ZOct 15 04:22:15.281 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp2_0/ll -p -o addr`
8482026-10-15T04:22:15.362ZOct 15 04:22:15.348 DEBG [sc] ox: starting
8492026-10-15T04:22:15.362ZOct 15 04:22:15.348 DEBG [sc] ox: connecting to [::1]:45930
8502026-10-15T04:22:15.385ZOct 15 04:22:15.348 DEBG [sc] ox waiting for prompt
8512026-10-15T04:22:15.386ZOct 15 04:22:15.359 DEBG [sc] ox: logging in
8522026-10-15T04:22:15.448ZOct 15 04:22:15.436 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp3_0/ll`
8532026-10-15T04:22:15.515ZOct 15 04:22:15.502 DEBG [sc] ox: starting
8542026-10-15T04:22:15.515ZOct 15 04:22:15.502 DEBG [sc] ox: connecting to [::1]:45930
8552026-10-15T04:22:15.538ZOct 15 04:22:15.503 DEBG [sc] ox waiting for prompt
8562026-10-15T04:22:15.538ZOct 15 04:22:15.513 DEBG [sc] ox: logging in
8572026-10-15T04:22:15.604ZOct 15 04:22:15.592 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp3_0/ll -p -o addr`
8582026-10-15T04:22:15.671ZOct 15 04:22:15.658 DEBG [sc] peer: starting
8592026-10-15T04:22:15.671ZOct 15 04:22:15.658 DEBG [sc] peer: connecting to [::1]:59125
8602026-10-15T04:22:15.695ZOct 15 04:22:15.659 DEBG [sc] peer waiting for prompt
8612026-10-15T04:22:15.695ZOct 15 04:22:15.671 DEBG [sc] peer: logging in
8622026-10-15T04:22:15.771ZOct 15 04:22:15.758 DEBG [sc] peer: executing command `ipadm create-addr -T addrconf vioif0/ll`
8632026-10-15T04:22:15.850ZOct 15 04:22:15.837 DEBG [sc] peer: starting
8642026-10-15T04:22:15.850ZOct 15 04:22:15.837 DEBG [sc] peer: connecting to [::1]:59125
8652026-10-15T04:22:15.873ZOct 15 04:22:15.838 DEBG [sc] peer waiting for prompt
8662026-10-15T04:22:15.873ZOct 15 04:22:15.848 DEBG [sc] peer: logging in
8672026-10-15T04:22:15.939ZOct 15 04:22:15.926 DEBG [sc] peer: executing command `ipadm show-addr vioif0/ll -p -o addr`
8682026-10-15T04:22:15.994ZOct 15 04:22:15.982 DEBG [sc] ox: starting
8692026-10-15T04:22:15.994ZOct 15 04:22:15.982 DEBG [sc] ox: connecting to [::1]:45930
8702026-10-15T04:22:16.018ZOct 15 04:22:15.982 DEBG [sc] ox waiting for prompt
8712026-10-15T04:22:16.018ZOct 15 04:22:15.993 DEBG [sc] ox: logging in
8722026-10-15T04:22:16.083ZOct 15 04:22:16.070 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
8732026-10-15T04:22:16.139ZOct 15 04:22:16.126 DEBG [sc] ox: starting
8742026-10-15T04:22:16.139ZOct 15 04:22:16.126 DEBG [sc] ox: connecting to [::1]:45930
8752026-10-15T04:22:16.162ZOct 15 04:22:16.126 DEBG [sc] ox waiting for prompt
8762026-10-15T04:22:16.162ZOct 15 04:22:16.137 DEBG [sc] ox: logging in
8772026-10-15T04:22:16.227ZOct 15 04:22:16.214 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
8782026-10-15T04:22:16.292ZOct 15 04:22:16.279 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/config/routers, method: GET, operation_id: read_routers
8792026-10-15T04:22:16.316ZOct 15 04:22:16.282 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/routers", status: 200, headers: {"content-type": "application/json", "x-request-id": "da1ccef0-f596-48ea-bd89-eed764ea4601", "content-length": "2", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: read_routers
8802026-10-15T04:22:16.316ZOct 15 04:22:16.282 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/rib/config/bestpath/fanout, method: POST, operation_id: update_bestpath_fanout
8812026-10-15T04:22:16.316ZOct 15 04:22:16.284 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/rib/config/bestpath/fanout", status: 204, headers: {"x-request-id": "2a7cfc4c-53c9-47d4-9546-b0ffb0a0351f", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: update_bestpath_fanout
8822026-10-15T04:22:16.316ZOct 15 04:22:16.284 INFO adding BGP routers to both mgd nodes
8832026-10-15T04:22:16.316ZOct 15 04:22:16.284 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/router, method: PUT, operation_id: create_router
8842026-10-15T04:22:16.316ZOct 15 04:22:16.285 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/router", status: 204, headers: {"x-request-id": "30db96c9-bfe0-4dd5-b830-7cad9a19022e", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_router
8852026-10-15T04:22:16.316ZOct 15 04:22:16.285 DEBG client request, body: Some(Body), uri: http://10.151.7.170:4676/bgp/config/router, method: PUT, operation_id: create_router
8862026-10-15T04:22:16.316ZOct 15 04:22:16.287 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/bgp/config/router", status: 204, headers: {"x-request-id": "ac85691b-8b69-47fb-876c-18e8bd1a4da1", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_router
8872026-10-15T04:22:16.316ZOct 15 04:22:16.287 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
8882026-10-15T04:22:16.316ZOct 15 04:22:16.293 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "93e3b550-c064-418c-9912-630989fb3781", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_unnumbered_neighbor
8892026-10-15T04:22:16.316ZOct 15 04:22:16.293 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
8902026-10-15T04:22:16.316ZOct 15 04:22:16.295 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "faa3adc8-e468-4583-8fc7-b42447611453", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_unnumbered_neighbor
8912026-10-15T04:22:16.317ZOct 15 04:22:16.295 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
8922026-10-15T04:22:16.317ZOct 15 04:22:16.297 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "16c7b73f-e66d-493b-897d-208ffd9d64de", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_unnumbered_neighbor
8932026-10-15T04:22:16.317ZOct 15 04:22:16.297 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
8942026-10-15T04:22:16.317ZOct 15 04:22:16.299 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "9d057414-95e4-42cb-994f-cfff0af763b2", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_unnumbered_neighbor
8952026-10-15T04:22:16.317ZOct 15 04:22:16.299 DEBG client request, body: Some(Body), uri: http://10.151.7.170:4676/bgp/config/unnumbered-neighbor, method: PUT, operation_id: create_unnumbered_neighbor
8962026-10-15T04:22:16.341ZOct 15 04:22:16.304 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/bgp/config/unnumbered-neighbor", status: 204, headers: {"x-request-id": "9393d919-6b6f-4dff-abb2-f3f760a8e2c1", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_unnumbered_neighbor
8972026-10-15T04:22:16.341ZOct 15 04:22:16.305 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/origin4, method: PUT, operation_id: create_origin4
8982026-10-15T04:22:16.341ZOct 15 04:22:16.306 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/origin4", status: 204, headers: {"x-request-id": "8a3ecc5f-85fc-4f4f-9a7d-2bc5cb2540ba", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_origin4
8992026-10-15T04:22:16.341ZOct 15 04:22:16.306 DEBG client request, body: Some(Body), uri: http://10.151.7.169:4676/bgp/config/origin6, method: PUT, operation_id: create_origin6
9002026-10-15T04:22:16.341ZOct 15 04:22:16.308 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/config/origin6", status: 204, headers: {"x-request-id": "751a6d5d-2e99-4586-865b-4d7c0e17e3ea", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_origin6
9012026-10-15T04:22:16.341ZOct 15 04:22:16.308 DEBG client request, body: Some(Body), uri: http://10.151.7.170:4676/bgp/config/origin4, method: PUT, operation_id: create_origin4
9022026-10-15T04:22:16.341ZOct 15 04:22:16.309 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/bgp/config/origin4", status: 204, headers: {"x-request-id": "43e8cc64-f1ab-436c-b60b-16220df0baa0", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_origin4
9032026-10-15T04:22:16.341ZOct 15 04:22:16.309 DEBG client request, body: Some(Body), uri: http://10.151.7.170:4676/bgp/config/origin6, method: PUT, operation_id: create_origin6
9042026-10-15T04:22:16.341ZOct 15 04:22:16.310 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/bgp/config/origin6", status: 204, headers: {"x-request-id": "c7c47aec-c8e1-4a03-880d-12ae7d695062", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: create_origin6
9052026-10-15T04:22:16.341ZOct 15 04:22:16.310 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9062026-10-15T04:22:16.341ZOct 15 04:22:16.311 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "44464ad7-46ef-4079-a2a3-489101fbc264", "content-length": "8926", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: get_neighbors
9072026-10-15T04:22:16.341ZOct 15 04:22:16.311 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9082026-10-15T04:22:16.341ZOct 15 04:22:16.312 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "f6a12c1c-b463-4ce0-9de5-8a91588a54a2", "content-length": "8926", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: get_neighbors
9092026-10-15T04:22:16.341ZOct 15 04:22:16.312 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9102026-10-15T04:22:16.341ZOct 15 04:22:16.312 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "ddb8cc52-09da-4c32-98de-e92f94d7d4f2", "content-length": "8926", "date": "Thu, 15 Oct 2026 04:22:15 GMT"} }), operation_id: get_neighbors
9112026-10-15T04:22:16.341Zmgd bgp cr2 -> Established: iteration 1/20: expected Some(Established), got Some(Connect)
9122026-10-15T04:22:17.327ZOct 15 04:22:17.314 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9132026-10-15T04:22:17.350ZOct 15 04:22:17.315 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "5702fec5-86ec-448b-bdf7-f1cb386ce0b0", "content-length": "8927", "date": "Thu, 15 Oct 2026 04:22:16 GMT"} }), operation_id: get_neighbors
9142026-10-15T04:22:17.350Zmgd bgp cr2 -> Established: iteration 2/20: expected Some(Established), got Some(Connect)
9152026-10-15T04:22:18.330ZOct 15 04:22:18.316 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9162026-10-15T04:22:18.354ZOct 15 04:22:18.317 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "060ae551-05f3-41de-8c2c-017ed8f7eaae", "content-length": "9470", "date": "Thu, 15 Oct 2026 04:22:17 GMT"} }), operation_id: get_neighbors
9172026-10-15T04:22:18.354ZOct 15 04:22:18.318 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9182026-10-15T04:22:18.354ZOct 15 04:22:18.318 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "f55d3ee8-721d-4614-a527-9e6c06e5746d", "content-length": "9470", "date": "Thu, 15 Oct 2026 04:22:17 GMT"} }), operation_id: get_neighbors
9192026-10-15T04:22:18.354Zmgd bgp cr3 -> Established: iteration 1/20: expected Some(Established), got Some(OpenConfirm)
9202026-10-15T04:22:19.333ZOct 15 04:22:19.320 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9212026-10-15T04:22:19.357ZOct 15 04:22:19.320 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "00eeb712-2c6b-418e-80ad-6bb4662014ff", "content-length": "9488", "date": "Thu, 15 Oct 2026 04:22:18 GMT"} }), operation_id: get_neighbors
9222026-10-15T04:22:19.357ZOct 15 04:22:19.320 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9232026-10-15T04:22:19.357ZOct 15 04:22:19.321 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "46511d16-e0ea-4ca2-9cbf-1106efd4d904", "content-length": "9488", "date": "Thu, 15 Oct 2026 04:22:18 GMT"} }), operation_id: get_neighbors
9242026-10-15T04:22:19.357Zmgd bgp peer -> Established: iteration 1/20: expected Some(Established), got Some(Connect)
9252026-10-15T04:22:20.335ZOct 15 04:22:20.322 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9262026-10-15T04:22:20.359ZOct 15 04:22:20.323 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "cead9a70-4b1e-455e-bb2b-364adee63d6d", "content-length": "9488", "date": "Thu, 15 Oct 2026 04:22:19 GMT"} }), operation_id: get_neighbors
9272026-10-15T04:22:20.359Zmgd bgp peer -> Established: iteration 2/20: expected Some(Established), got Some(Connect)
9282026-10-15T04:22:21.338ZOct 15 04:22:21.325 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9292026-10-15T04:22:21.362ZOct 15 04:22:21.325 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "7e5c2fcb-25ce-4a61-a19e-f1f618fcdc52", "content-length": "9492", "date": "Thu, 15 Oct 2026 04:22:20 GMT"} }), operation_id: get_neighbors
9302026-10-15T04:22:21.362Zmgd bgp peer -> Established: iteration 3/20: expected Some(Established), got Some(OpenConfirm)
9312026-10-15T04:22:22.339ZOct 15 04:22:22.327 DEBG client request, body: None, uri: http://10.151.7.169:4676/bgp/status/neighbors?asn=33, method: GET, operation_id: get_neighbors
9322026-10-15T04:22:22.363ZOct 15 04:22:22.328 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/bgp/status/neighbors?asn=33", status: 200, headers: {"content-type": "application/json", "x-request-id": "035614e0-d4c5-4bfd-b368-f2bbd09de959", "content-length": "9740", "date": "Thu, 15 Oct 2026 04:22:21 GMT"} }), operation_id: get_neighbors
9332026-10-15T04:22:22.363ZOct 15 04:22:22.328 DEBG client request, body: None, uri: http://10.151.7.170:4676/bgp/status/neighbors?asn=47, method: GET, operation_id: get_neighbors
9342026-10-15T04:22:22.363ZOct 15 04:22:22.329 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/bgp/status/neighbors?asn=47", status: 200, headers: {"content-type": "application/json", "x-request-id": "a7d5ce0a-d5ff-4d9b-9eff-3cb0e443754d", "content-length": "2389", "date": "Thu, 15 Oct 2026 04:22:21 GMT"} }), operation_id: get_neighbors
9352026-10-15T04:22:22.363ZOct 15 04:22:22.329 DEBG client request, body: None, uri: http://10.151.7.169:4676/rib/status/imported?address_family=Ipv4, method: GET, operation_id: get_rib_imported
9362026-10-15T04:22:22.363ZOct 15 04:22:22.329 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/rib/status/imported?address_family=Ipv4", status: 200, headers: {"content-type": "application/json", "x-request-id": "7082014a-097c-4c19-afae-a505d34c95d9", "content-length": "1020", "date": "Thu, 15 Oct 2026 04:22:21 GMT"} }), operation_id: get_rib_imported
9372026-10-15T04:22:22.364ZOct 15 04:22:22.329 DEBG client request, body: None, uri: http://10.151.7.169:4676/rib/status/imported?address_family=Ipv6, method: GET, operation_id: get_rib_imported
9382026-10-15T04:22:22.364ZOct 15 04:22:22.330 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/rib/status/imported?address_family=Ipv6", status: 200, headers: {"content-type": "application/json", "x-request-id": "c6b5debf-6f8e-40dc-8fac-8ba255755c01", "content-length": "1019", "date": "Thu, 15 Oct 2026 04:22:21 GMT"} }), operation_id: get_rib_imported
9392026-10-15T04:22:22.364ZOct 15 04:22:22.330 DEBG client request, body: None, uri: http://10.151.7.169:4676/rib/status/selected?address_family=Ipv4, method: GET, operation_id: get_rib_selected
9402026-10-15T04:22:22.364ZOct 15 04:22:22.330 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/rib/status/selected?address_family=Ipv4", status: 200, headers: {"content-type": "application/json", "x-request-id": "fa56ce3d-c259-444d-8e8e-2d1d3a0328a2", "content-length": "1020", "date": "Thu, 15 Oct 2026 04:22:21 GMT"} }), operation_id: get_rib_selected
9412026-10-15T04:22:22.364ZOct 15 04:22:22.330 DEBG client request, body: None, uri: http://10.151.7.169:4676/rib/status/selected?address_family=Ipv6, method: GET, operation_id: get_rib_selected
9422026-10-15T04:22:22.364ZOct 15 04:22:22.331 DEBG client response, result: Ok(Response { url: "http://10.151.7.169:4676/rib/status/selected?address_family=Ipv6", status: 200, headers: {"content-type": "application/json", "x-request-id": "0b0e18ca-e97e-4507-9238-c07b1926ca5d", "content-length": "1019", "date": "Thu, 15 Oct 2026 04:22:21 GMT"} }), operation_id: get_rib_selected
9432026-10-15T04:22:22.450Zdpd ipv6 targets for fd99::/64: iteration 1/20: expected 4, got 3
9442026-10-15T04:22:23.452ZOct 15 04:22:23.440 INFO cr1: executing frr script show ip bgp json
9452026-10-15T04:22:23.476ZOct 15 04:22:23.440 DEBG [sc] cr1: starting
9462026-10-15T04:22:23.476ZOct 15 04:22:23.440 DEBG [sc] cr1: connecting to [::1]:56556
9472026-10-15T04:22:23.476ZOct 15 04:22:23.440 DEBG [sc] cr1 waiting for prompt
9482026-10-15T04:22:23.476ZOct 15 04:22:23.452 DEBG [sc] cr1: logging in
9492026-10-15T04:22:23.810ZOct 15 04:22:23.798 DEBG [sc] cr1: executing command `/usr/bin/vtysh -c 'show ip bgp json'`
9502026-10-15T04:22:25.938ZOct 15 04:22:25.926 INFO cr1: executing frr script show bgp json
9512026-10-15T04:22:25.938ZOct 15 04:22:25.926 DEBG [sc] cr1: starting
9522026-10-15T04:22:25.938ZOct 15 04:22:25.926 DEBG [sc] cr1: connecting to [::1]:56556
9532026-10-15T04:22:25.962ZOct 15 04:22:25.926 DEBG [sc] cr1 waiting for prompt
9542026-10-15T04:22:25.962ZOct 15 04:22:25.937 DEBG [sc] cr1: logging in
9552026-10-15T04:22:26.104ZOct 15 04:22:26.091 DEBG [sc] cr1: executing command `/usr/bin/vtysh -c 'show bgp json'`
9562026-10-15T04:22:28.242ZOct 15 04:22:28.229 INFO cr2: executing eos script show ip bgp | json
9572026-10-15T04:22:28.242ZOct 15 04:22:28.229 DEBG [sc] cr2: starting
9582026-10-15T04:22:28.242ZOct 15 04:22:28.229 DEBG [sc] cr2: connecting to [::1]:65340
9592026-10-15T04:22:28.266ZOct 15 04:22:28.230 DEBG [sc] cr2 waiting for prompt
9602026-10-15T04:22:28.266ZOct 15 04:22:28.241 DEBG [sc] cr2: logging in
9612026-10-15T04:22:28.592ZOct 15 04:22:28.579 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'`
9622026-10-15T04:22:31.022ZOct 15 04:22:31.009 INFO cr2: executing eos script show ipv6 bgp | json
9632026-10-15T04:22:31.022ZOct 15 04:22:31.009 DEBG [sc] cr2: starting
9642026-10-15T04:22:31.022ZOct 15 04:22:31.009 DEBG [sc] cr2: connecting to [::1]:65340
9652026-10-15T04:22:31.046ZOct 15 04:22:31.010 DEBG [sc] cr2 waiting for prompt
9662026-10-15T04:22:31.046ZOct 15 04:22:31.020 DEBG [sc] cr2: logging in
9672026-10-15T04:22:31.177ZOct 15 04:22:31.164 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'`
9682026-10-15T04:22:33.586ZOct 15 04:22:33.573 INFO cr3: executing juniper script show route 4.5.6.0/24 protocol bgp
9692026-10-15T04:22:33.586ZOct 15 04:22:33.573 DEBG [sc] cr3: starting
9702026-10-15T04:22:33.586ZOct 15 04:22:33.573 DEBG [sc] cr3: connecting to [::1]:36580
9712026-10-15T04:22:33.610ZOct 15 04:22:33.574 DEBG [sc] cr3 waiting for prompt
9722026-10-15T04:22:33.610ZOct 15 04:22:33.585 DEBG [sc] cr3: logging in
9732026-10-15T04:22:33.943ZOct 15 04:22:33.931 DEBG [sc] cr3: executing command `docker exec crpd1 cli -c 'show route 4.5.6.0/24 protocol bgp'`
9742026-10-15T04:22:36.126ZOct 15 04:22:36.113 INFO cr3: executing juniper script show route fdee::/64 protocol bgp
9752026-10-15T04:22:36.126ZOct 15 04:22:36.113 DEBG [sc] cr3: starting
9762026-10-15T04:22:36.126ZOct 15 04:22:36.113 DEBG [sc] cr3: connecting to [::1]:36580
9772026-10-15T04:22:36.150ZOct 15 04:22:36.114 DEBG [sc] cr3 waiting for prompt
9782026-10-15T04:22:36.150ZOct 15 04:22:36.124 DEBG [sc] cr3: logging in
9792026-10-15T04:22:36.259ZOct 15 04:22:36.246 DEBG [sc] cr3: executing command `docker exec crpd1 cli -c 'show route fdee::/64 protocol bgp'`
9802026-10-15T04:22:38.460ZOct 15 04:22:38.447 DEBG client request, body: None, uri: http://10.151.7.170:4676/rib/status/imported?address_family=Ipv4, method: GET, operation_id: get_rib_imported
9812026-10-15T04:22:38.485ZOct 15 04:22:38.447 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/rib/status/imported?address_family=Ipv4", status: 200, headers: {"content-type": "application/json", "x-request-id": "fa5b25aa-6110-498d-8e58-894f573c4085", "content-length": "253", "date": "Thu, 15 Oct 2026 04:22:37 GMT"} }), operation_id: get_rib_imported
9822026-10-15T04:22:38.485ZOct 15 04:22:38.447 DEBG client request, body: None, uri: http://10.151.7.170:4676/rib/status/imported?address_family=Ipv6, method: GET, operation_id: get_rib_imported
9832026-10-15T04:22:38.485ZOct 15 04:22:38.448 DEBG client response, result: Ok(Response { url: "http://10.151.7.170:4676/rib/status/imported?address_family=Ipv6", status: 200, headers: {"content-type": "application/json", "x-request-id": "e7a640b0-8ae3-4267-bde6-136be2a93ebe", "content-length": "252", "date": "Thu, 15 Oct 2026 04:22:37 GMT"} }), operation_id: get_rib_imported
9842026-10-15T04:22:38.485ZOct 15 04:22:38.448 INFO interop bgp unnumbered test passed 🎉
9852026-10-15T04:22:38.485Z+ (( status != 0 ))
9862026-10-15T04:22:38.485Z+ pfexec ./falcon-lab cleanup interop bgp-unnumbered
9872026-10-15T04:22:38.509ZOct 15 04:22:38.489 INFO destroying runner for deployment interop_bgpu
9882026-10-15T04:22:38.509ZOct 15 04:22:38.489 INFO destroying deployment interop_bgpu
9892026-10-15T04:22:38.509ZOct 15 04:22:38.489 INFO destroying nodes
9902026-10-15T04:22:38.629ZOct 15 04:22:38.616 INFO destroying links
9912026-10-15T04:22:38.629ZOct 15 04:22:38.616 INFO destroying link interop_bgpu_ox_sn_vnic0
9922026-10-15T04:22:38.653ZOct 15 04:22:38.621 INFO destroying link interop_bgpu_ox_sn_sim0
9932026-10-15T04:22:38.653ZOct 15 04:22:38.622 INFO destroying link interop_bgpu_cr1_vn_vnic0
9942026-10-15T04:22:39.638ZOct 15 04:22:39.625 INFO destroying link interop_bgpu_cr1_vn_sim0
9952026-10-15T04:22:39.661ZOct 15 04:22:39.626 INFO destroying link interop_bgpu_ox_sn_vnic1
9962026-10-15T04:22:39.661ZOct 15 04:22:39.627 INFO destroying link interop_bgpu_ox_sn_sim1
9972026-10-15T04:22:39.661ZOct 15 04:22:39.628 INFO destroying link interop_bgpu_cr2_vn_vnic0
9982026-10-15T04:22:39.661ZOct 15 04:22:39.629 INFO destroying link interop_bgpu_cr2_vn_sim0
9992026-10-15T04:22:39.661ZOct 15 04:22:39.630 INFO destroying link interop_bgpu_ox_sn_vnic2
10002026-10-15T04:22:39.661ZOct 15 04:22:39.632 INFO destroying link interop_bgpu_ox_sn_sim2
10012026-10-15T04:22:39.661ZOct 15 04:22:39.633 INFO destroying link interop_bgpu_cr3_vn_vnic0
10022026-10-15T04:22:39.661ZOct 15 04:22:39.634 INFO destroying link interop_bgpu_cr3_vn_sim0
10032026-10-15T04:22:39.661ZOct 15 04:22:39.635 INFO destroying link interop_bgpu_ox_sn_vnic3
10042026-10-15T04:22:39.662ZOct 15 04:22:39.636 INFO destroying link interop_bgpu_ox_sn_sim3
10052026-10-15T04:22:39.662ZOct 15 04:22:39.637 INFO destroying link interop_bgpu_peer_vn_vnic0
10062026-10-15T04:22:39.662ZOct 15 04:22:39.638 INFO destroying link interop_bgpu_peer_vn_sim0
10072026-10-15T04:22:39.662ZOct 15 04:22:39.639 INFO destroying external links
10082026-10-15T04:22:39.662ZOct 15 04:22:39.639 INFO destroying external link interop_bgpu_ox_vn_vnic4
10092026-10-15T04:22:39.662ZOct 15 04:22:39.640 INFO destroying external link interop_bgpu_cr1_vn_vnic1
10102026-10-15T04:22:39.662ZOct 15 04:22:39.641 INFO destroying external link interop_bgpu_cr2_vn_vnic1
10112026-10-15T04:22:39.662ZOct 15 04:22:39.642 INFO destroying external link interop_bgpu_cr3_vn_vnic1
10122026-10-15T04:22:39.662ZOct 15 04:22:39.643 INFO destroying external link interop_bgpu_peer_vn_vnic1
10132026-10-15T04:22:39.662ZOct 15 04:22:39.645 INFO destroying images
10142026-10-15T04:22:40.212ZOct 15 04:22:40.200 INFO destroying workspace at .falcon
10152026-10-15T04:22:40.236Z+ return 0
10162026-10-15T04:22:40.236Z+ run_test interop bfd-static-routing
10172026-10-15T04:22:40.236Z+ local topology=interop
10182026-10-15T04:22:40.236Z+ local scenario=bfd-static-routing
10192026-10-15T04:22:40.236Z+ local test_name=interop-bfd-static-routing
10202026-10-15T04:22:40.237Z+ local status=0
10212026-10-15T04:22:40.237Z+ clear_falcon_workspace_files
10222026-10-15T04:22:40.237Z+ [[ -d .falcon ]]
10232026-10-15T04:22:40.237Z+ find .falcon -maxdepth 1 -type f -exec rm -f '{}' +
10242026-10-15T04:22:40.237Z+ RUST_LOG=debug
10252026-10-15T04:22:40.237Z+ pfexec ./falcon-lab run interop bfd-static-routing --no-cleanup
10262026-10-15T04:22:40.261ZOct 15 04:22:40.236 DEBG using default route interface igb0
10272026-10-15T04:22:40.261ZOct 15 04:22:40.236 DEBG using default route interface igb0
10282026-10-15T04:22:40.261ZOct 15 04:22:40.236 DEBG using default route interface igb0
10292026-10-15T04:22:40.261ZOct 15 04:22:40.236 DEBG using default route interface igb0
10302026-10-15T04:22:40.261ZOct 15 04:22:40.236 DEBG using default route interface igb0
10312026-10-15T04:22:40.261ZOct 15 04:22:40.236 INFO interop_bfd: launching interop topology
10322026-10-15T04:22:40.261ZOct 15 04:22:40.236 INFO launching runner: deployment(interop_bfd) persistent(true) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
10332026-10-15T04:22:40.261ZOct 15 04:22:40.236 INFO starting preflight for deployment interop_bfd
10342026-10-15T04:22:41.045ZOct 15 04:22:41.032 INFO creating links
10352026-10-15T04:22:41.045ZOct 15 04:22:41.032 DEBG destroying link interop_bfd_ox_sn_vnic0
10362026-10-15T04:22:41.045ZOct 15 04:22:41.032 DEBG destroying link interop_bfd_ox_sn_sim0
10372026-10-15T04:22:41.045ZOct 15 04:22:41.032 INFO creating simnet link 'interop_bfd_ox_sn_sim0'
10382026-10-15T04:22:41.068ZOct 15 04:22:41.035 INFO creating vnic link 'interop_bfd_ox_sn_vnic0'
10392026-10-15T04:22:41.069ZOct 15 04:22:41.042 DEBG link pair created
10402026-10-15T04:22:41.069ZOct 15 04:22:41.042 DEBG destroying link interop_bfd_cr1_vn_vnic0
10412026-10-15T04:22:41.069ZOct 15 04:22:41.042 DEBG destroying link interop_bfd_cr1_vn_sim0
10422026-10-15T04:22:41.069ZOct 15 04:22:41.042 INFO creating simnet link 'interop_bfd_cr1_vn_sim0'
10432026-10-15T04:22:41.069ZOct 15 04:22:41.043 INFO creating vnic link 'interop_bfd_cr1_vn_vnic0'
10442026-10-15T04:22:41.069ZOct 15 04:22:41.049 DEBG link pair created
10452026-10-15T04:22:41.069ZOct 15 04:22:41.049 DEBG destroying link interop_bfd_ox_sn_vnic1
10462026-10-15T04:22:41.070ZOct 15 04:22:41.049 DEBG destroying link interop_bfd_ox_sn_sim1
10472026-10-15T04:22:41.070ZOct 15 04:22:41.049 INFO creating simnet link 'interop_bfd_ox_sn_sim1'
10482026-10-15T04:22:41.070ZOct 15 04:22:41.050 INFO creating vnic link 'interop_bfd_ox_sn_vnic1'
10492026-10-15T04:22:41.093ZOct 15 04:22:41.056 DEBG link pair created
10502026-10-15T04:22:41.093ZOct 15 04:22:41.056 DEBG destroying link interop_bfd_cr2_vn_vnic0
10512026-10-15T04:22:41.093ZOct 15 04:22:41.056 DEBG destroying link interop_bfd_cr2_vn_sim0
10522026-10-15T04:22:41.093ZOct 15 04:22:41.056 INFO creating simnet link 'interop_bfd_cr2_vn_sim0'
10532026-10-15T04:22:41.094ZOct 15 04:22:41.058 INFO creating vnic link 'interop_bfd_cr2_vn_vnic0'
10542026-10-15T04:22:41.094ZOct 15 04:22:41.064 DEBG link pair created
10552026-10-15T04:22:41.094ZOct 15 04:22:41.064 DEBG destroying link interop_bfd_ox_sn_vnic2
10562026-10-15T04:22:41.094ZOct 15 04:22:41.064 DEBG destroying link interop_bfd_ox_sn_sim2
10572026-10-15T04:22:41.094ZOct 15 04:22:41.064 INFO creating simnet link 'interop_bfd_ox_sn_sim2'
10582026-10-15T04:22:41.094ZOct 15 04:22:41.065 INFO creating vnic link 'interop_bfd_ox_sn_vnic2'
10592026-10-15T04:22:41.094ZOct 15 04:22:41.072 DEBG link pair created
10602026-10-15T04:22:41.094ZOct 15 04:22:41.072 DEBG destroying link interop_bfd_cr3_vn_vnic0
10612026-10-15T04:22:41.094ZOct 15 04:22:41.072 DEBG destroying link interop_bfd_cr3_vn_sim0
10622026-10-15T04:22:41.094ZOct 15 04:22:41.072 INFO creating simnet link 'interop_bfd_cr3_vn_sim0'
10632026-10-15T04:22:41.094ZOct 15 04:22:41.073 INFO creating vnic link 'interop_bfd_cr3_vn_vnic0'
10642026-10-15T04:22:41.094ZOct 15 04:22:41.079 DEBG link pair created
10652026-10-15T04:22:41.094ZOct 15 04:22:41.079 DEBG destroying link interop_bfd_ox_sn_vnic3
10662026-10-15T04:22:41.094ZOct 15 04:22:41.079 DEBG destroying link interop_bfd_ox_sn_sim3
10672026-10-15T04:22:41.095ZOct 15 04:22:41.079 INFO creating simnet link 'interop_bfd_ox_sn_sim3'
10682026-10-15T04:22:41.095ZOct 15 04:22:41.080 INFO creating vnic link 'interop_bfd_ox_sn_vnic3'
10692026-10-15T04:22:41.119ZOct 15 04:22:41.087 DEBG link pair created
10702026-10-15T04:22:41.119ZOct 15 04:22:41.087 DEBG destroying link interop_bfd_peer_vn_vnic0
10712026-10-15T04:22:41.119ZOct 15 04:22:41.087 DEBG destroying link interop_bfd_peer_vn_sim0
10722026-10-15T04:22:41.119ZOct 15 04:22:41.087 INFO creating simnet link 'interop_bfd_peer_vn_sim0'
10732026-10-15T04:22:41.119ZOct 15 04:22:41.088 INFO creating vnic link 'interop_bfd_peer_vn_vnic0'
10742026-10-15T04:22:41.119ZOct 15 04:22:41.094 DEBG link pair created
10752026-10-15T04:22:41.119ZOct 15 04:22:41.094 INFO creating external links
10762026-10-15T04:22:41.119ZOct 15 04:22:41.094 DEBG destroying external link interop_bfd_ox_vn_vnic4
10772026-10-15T04:22:41.119ZOct 15 04:22:41.094 INFO creating external link interop_bfd_ox_vn_vnic4
10782026-10-15T04:22:41.119ZOct 15 04:22:41.095 DEBG external link interop_bfd_ox_vn_vnic4@igb0 created
10792026-10-15T04:22:41.119ZOct 15 04:22:41.095 DEBG destroying external link interop_bfd_cr1_vn_vnic1
10802026-10-15T04:22:41.119ZOct 15 04:22:41.095 INFO creating external link interop_bfd_cr1_vn_vnic1
10812026-10-15T04:22:41.119ZOct 15 04:22:41.096 DEBG external link interop_bfd_cr1_vn_vnic1@igb0 created
10822026-10-15T04:22:41.119ZOct 15 04:22:41.096 DEBG destroying external link interop_bfd_cr2_vn_vnic1
10832026-10-15T04:22:41.119ZOct 15 04:22:41.096 INFO creating external link interop_bfd_cr2_vn_vnic1
10842026-10-15T04:22:41.119ZOct 15 04:22:41.097 DEBG external link interop_bfd_cr2_vn_vnic1@igb0 created
10852026-10-15T04:22:41.119ZOct 15 04:22:41.097 DEBG destroying external link interop_bfd_cr3_vn_vnic1
10862026-10-15T04:22:41.119ZOct 15 04:22:41.097 INFO creating external link interop_bfd_cr3_vn_vnic1
10872026-10-15T04:22:41.119ZOct 15 04:22:41.098 DEBG external link interop_bfd_cr3_vn_vnic1@igb0 created
10882026-10-15T04:22:41.119ZOct 15 04:22:41.098 DEBG destroying external link interop_bfd_peer_vn_vnic1
10892026-10-15T04:22:41.120ZOct 15 04:22:41.098 INFO creating external link interop_bfd_peer_vn_vnic1
10902026-10-15T04:22:41.120ZOct 15 04:22:41.099 DEBG external link interop_bfd_peer_vn_vnic1@igb0 created
10912026-10-15T04:22:41.120ZOct 15 04:22:41.099 INFO creating nodes
10922026-10-15T04:22:41.120ZOct 15 04:22:41.099 INFO ox: launching node
10932026-10-15T04:22:41.120ZOct 15 04:22:41.101 INFO cr1: launching node
10942026-10-15T04:22:41.120ZOct 15 04:22:41.104 INFO cr2: launching node
10952026-10-15T04:22:41.120ZOct 15 04:22:41.106 INFO cr3: launching node
10962026-10-15T04:22:41.143ZOct 15 04:22:41.108 INFO peer: launching node
10972026-10-15T04:22:41.144ZOct 15 04:22:41.122 INFO launched instance ox with pid 987 on port 53515
10982026-10-15T04:22:41.144ZOct 15 04:22:41.122 INFO ox: instance ensure
10992026-10-15T04:22:41.144ZOct 15 04:22:41.122 INFO launched instance cr1 with pid 988 on port 32930
11002026-10-15T04:22:41.144ZOct 15 04:22:41.122 INFO cr1: instance ensure
11012026-10-15T04:22:41.144ZOct 15 04:22:41.122 INFO launched instance cr2 with pid 989 on port 45456
11022026-10-15T04:22:41.144ZOct 15 04:22:41.123 INFO cr2: instance ensure
11032026-10-15T04:22:41.144ZOct 15 04:22:41.123 INFO launched instance cr3 with pid 990 on port 49808
11042026-10-15T04:22:41.144ZOct 15 04:22:41.123 INFO cr3: instance ensure
11052026-10-15T04:22:41.144ZOct 15 04:22:41.123 INFO launched instance peer with pid 991 on port 40373
11062026-10-15T04:22:41.144ZOct 15 04:22:41.123 INFO peer: instance ensure
11072026-10-15T04:22:44.885ZOct 15 04:22:44.873 INFO peer: instance ensure completed after 0 retries
11082026-10-15T04:22:44.885ZOct 15 04:22:44.873 INFO peer: instance run
11092026-10-15T04:22:44.909ZOct 15 04:22:44.873 DEBG [sc] peer: starting
11102026-10-15T04:22:44.910ZOct 15 04:22:44.873 DEBG [sc] peer: connecting to [::1]:40373
11112026-10-15T04:22:44.910ZOct 15 04:22:44.874 DEBG [sc] peer waiting for prompt
11122026-10-15T04:22:44.935ZOct 15 04:22:44.913 INFO cr2: instance ensure completed after 0 retries
11132026-10-15T04:22:44.935ZOct 15 04:22:44.913 INFO cr2: instance run
11142026-10-15T04:22:44.935ZOct 15 04:22:44.914 DEBG [sc] cr2: starting
11152026-10-15T04:22:44.935ZOct 15 04:22:44.914 DEBG [sc] cr2: connecting to [::1]:45456
11162026-10-15T04:22:44.935ZOct 15 04:22:44.915 DEBG [sc] cr2 waiting for prompt
11172026-10-15T04:22:44.961ZOct 15 04:22:44.924 INFO cr3: instance ensure completed after 0 retries
11182026-10-15T04:22:44.961ZOct 15 04:22:44.924 INFO cr3: instance run
11192026-10-15T04:22:45.078ZOct 15 04:22:45.065 INFO cr1: instance ensure completed after 0 retries
11202026-10-15T04:22:45.078ZOct 15 04:22:45.065 INFO cr1: instance run
11212026-10-15T04:22:45.102ZOct 15 04:22:45.065 DEBG [sc] cr1: starting
11222026-10-15T04:22:45.102ZOct 15 04:22:45.065 DEBG [sc] cr1: connecting to [::1]:32930
11232026-10-15T04:22:45.102ZOct 15 04:22:45.066 DEBG [sc] cr1 waiting for prompt
11242026-10-15T04:22:45.102ZOct 15 04:22:45.079 INFO ox: instance ensure completed after 0 retries
11252026-10-15T04:22:45.102ZOct 15 04:22:45.079 INFO ox: instance run
11262026-10-15T04:22:45.102ZOct 15 04:22:45.079 DEBG [sc] ox: starting
11272026-10-15T04:22:45.102ZOct 15 04:22:45.079 DEBG [sc] ox: connecting to [::1]:53515
11282026-10-15T04:22:45.102ZOct 15 04:22:45.080 DEBG [sc] ox waiting for prompt
11292026-10-15T04:23:02.371ZOct 15 04:23:02.358 DEBG [sc] cr1: logging in
11302026-10-15T04:23:02.745ZOct 15 04:23:02.732 INFO cr1: mounting /opt/cargo-bay
11312026-10-15T04:23:02.745ZOct 15 04:23:02.732 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; mount -t 9p -o ro,msize=65536 /opt/cargo-bay /opt/cargo-bay`
11322026-10-15T04:23:02.778ZOct 15 04:23:02.765 DEBG [sc] cr1: executing command `cd`
11332026-10-15T04:23:02.801ZOct 15 04:23:02.776 INFO cr1: finished mounting /opt/cargo-bay
11342026-10-15T04:23:02.801ZOct 15 04:23:02.776 DEBG [sc] cr1: executing command `hostname cr1`
11352026-10-15T04:23:02.801ZOct 15 04:23:02.788 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
11362026-10-15T04:23:02.825ZOct 15 04:23:02.799 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
11372026-10-15T04:23:04.267ZOct 15 04:23:04.254 DEBG [sc] ox: logging in
11382026-10-15T04:23:04.345ZOct 15 04:23:04.333 DEBG [sc] peer: logging in
11392026-10-15T04:23:04.519ZOct 15 04:23:04.506 INFO ox: mounting /opt/cargo-bay
11402026-10-15T04:23:04.519ZOct 15 04:23:04.506 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
11412026-10-15T04:23:04.591ZOct 15 04:23:04.578 INFO peer: mounting /opt/cargo-bay
11422026-10-15T04:23:04.591ZOct 15 04:23:04.578 DEBG [sc] peer: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
11432026-10-15T04:23:05.799ZOct 15 04:23:05.786 DEBG [sc] ox: executing command `cd`
11442026-10-15T04:23:05.822ZOct 15 04:23:05.797 INFO ox: finished mounting /opt/cargo-bay
11452026-10-15T04:23:05.822ZOct 15 04:23:05.797 DEBG [sc] ox: executing command `hostname ox`
11462026-10-15T04:23:05.822ZOct 15 04:23:05.808 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
11472026-10-15T04:23:05.846ZOct 15 04:23:05.820 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
11482026-10-15T04:23:05.846ZOct 15 04:23:05.831 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
11492026-10-15T04:23:05.869ZOct 15 04:23:05.842 INFO ox: logging out
11502026-10-15T04:23:05.943ZOct 15 04:23:05.930 INFO ox: logged out
11512026-10-15T04:23:05.969ZOct 15 04:23:05.936 DEBG [sc] peer: executing command `cd`
11522026-10-15T04:23:05.969ZOct 15 04:23:05.947 INFO peer: finished mounting /opt/cargo-bay
11532026-10-15T04:23:05.969ZOct 15 04:23:05.947 DEBG [sc] peer: executing command `hostname peer`
11542026-10-15T04:23:05.992ZOct 15 04:23:05.958 DEBG [sc] peer: executing command `echo 'peer' > /etc/nodename`
11552026-10-15T04:23:05.992ZOct 15 04:23:05.969 DEBG [sc] peer: executing command `echo '::1 peer.local peer' >> /etc/hosts`
11562026-10-15T04:23:06.016ZOct 15 04:23:05.980 DEBG [sc] peer: executing command `echo '127.0.0.1 peer.local peer' >> /etc/hosts`
11572026-10-15T04:23:06.017ZOct 15 04:23:05.991 INFO peer: logging out
11582026-10-15T04:23:06.104ZOct 15 04:23:06.091 INFO peer: logged out
11592026-10-15T04:23:06.621ZOct 15 04:23:06.608 DEBG [sc] cr2: logging in
11602026-10-15T04:23:07.066ZOct 15 04:23:07.053 INFO cr2: mounting /opt/cargo-bay
11612026-10-15T04:23:07.066ZOct 15 04:23:07.053 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
11622026-10-15T04:23:07.090ZOct 15 04:23:07.075 DEBG [sc] cr2: executing command `cd`
11632026-10-15T04:23:07.113ZOct 15 04:23:07.085 INFO cr2: finished mounting /opt/cargo-bay
11642026-10-15T04:23:07.113ZOct 15 04:23:07.085 DEBG [sc] cr2: executing command `hostname cr2`
11652026-10-15T04:23:07.137ZOct 15 04:23:07.096 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
11662026-10-15T04:23:07.137ZOct 15 04:23:07.107 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
11672026-10-15T04:23:07.137ZOct 15 04:23:07.118 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
11682026-10-15T04:23:07.161ZOct 15 04:23:07.130 INFO cr2: logging out
11692026-10-15T04:23:10.154ZOct 15 04:23:10.141 INFO cr2: logged out
11702026-10-15T04:32:40.292ZError: launch timed out
11712026-10-15T04:32:40.292Z
11722026-10-15T04:32:40.292ZCaused by:
11732026-10-15T04:32:40.292Z deadline has elapsed
11742026-10-15T04:32:40.317Z+ status=1
11752026-10-15T04:32:40.317Z+ (( status != 0 ))
11762026-10-15T04:32:40.317Z+ collect_falcon_artifacts interop-bfd-static-routing
11772026-10-15T04:32:40.317Z+ local test_name=interop-bfd-static-routing
11782026-10-15T04:32:40.317Z+ local artifact_dir=/work/falcon-interop-bfd-static-routing
11792026-10-15T04:32:40.317Z+ local path
11802026-10-15T04:32:40.317Z+ [[ ! -d .falcon ]]
11812026-10-15T04:32:40.317Z+ rm -rf /work/falcon-interop-bfd-static-routing
11822026-10-15T04:32:40.317Z+ mkdir -p /work/falcon-interop-bfd-static-routing
11832026-10-15T04:32:40.317Z+ for path in .falcon/*.{out,err}
11842026-10-15T04:32:40.317Z+ [[ -e .falcon/cr1.out ]]
11852026-10-15T04:32:40.317Z+ cp -p .falcon/cr1.out /work/falcon-interop-bfd-static-routing/
11862026-10-15T04:32:40.317Z+ for path in .falcon/*.{out,err}
11872026-10-15T04:32:40.317Z+ [[ -e .falcon/cr2.out ]]
11882026-10-15T04:32:40.317Z+ cp -p .falcon/cr2.out /work/falcon-interop-bfd-static-routing/
11892026-10-15T04:32:40.317Z+ for path in .falcon/*.{out,err}
11902026-10-15T04:32:40.317Z+ [[ -e .falcon/cr3.out ]]
11912026-10-15T04:32:40.317Z+ cp -p .falcon/cr3.out /work/falcon-interop-bfd-static-routing/
11922026-10-15T04:32:40.343Z+ for path in .falcon/*.{out,err}
11932026-10-15T04:32:40.344Z+ [[ -e .falcon/ox.out ]]
11942026-10-15T04:32:40.344Z+ cp -p .falcon/ox.out /work/falcon-interop-bfd-static-routing/
11952026-10-15T04:32:40.344Z+ for path in .falcon/*.{out,err}
11962026-10-15T04:32:40.344Z+ [[ -e .falcon/peer.out ]]
11972026-10-15T04:32:40.344Z+ cp -p .falcon/peer.out /work/falcon-interop-bfd-static-routing/
11982026-10-15T04:32:40.344Z+ for path in .falcon/*.{out,err}
11992026-10-15T04:32:40.344Z+ [[ -e .falcon/cr1.err ]]
12002026-10-15T04:32:40.344Z+ cp -p .falcon/cr1.err /work/falcon-interop-bfd-static-routing/
12012026-10-15T04:32:40.344Z+ for path in .falcon/*.{out,err}
12022026-10-15T04:32:40.344Z+ [[ -e .falcon/cr2.err ]]
12032026-10-15T04:32:40.344Z+ cp -p .falcon/cr2.err /work/falcon-interop-bfd-static-routing/
12042026-10-15T04:32:40.344Z+ for path in .falcon/*.{out,err}
12052026-10-15T04:32:40.344Z+ [[ -e .falcon/cr3.err ]]
12062026-10-15T04:32:40.344Z+ cp -p .falcon/cr3.err /work/falcon-interop-bfd-static-routing/
12072026-10-15T04:32:40.344Z+ for path in .falcon/*.{out,err}
12082026-10-15T04:32:40.344Z+ [[ -e .falcon/ox.err ]]
12092026-10-15T04:32:40.344Z+ cp -p .falcon/ox.err /work/falcon-interop-bfd-static-routing/
12102026-10-15T04:32:40.368Z+ for path in .falcon/*.{out,err}
12112026-10-15T04:32:40.368Z+ [[ -e .falcon/peer.err ]]
12122026-10-15T04:32:40.368Z+ cp -p .falcon/peer.err /work/falcon-interop-bfd-static-routing/
12132026-10-15T04:32:40.368Z+ tar cvfz /work/falcon-interop-bfd-static-routing.tar.gz -C /work falcon-interop-bfd-static-routing
12142026-10-15T04:32:40.368ZCompressing '/work/falcon-interop-bfd-static-routing.tar.gz' with '/usr/bin/gzip'...
12152026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/ 0K
12162026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/ox.out 54K
12172026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/cr2.err 1K
12182026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/peer.out 51K
12192026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/cr3.err 1K
12202026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/cr1.out 51K
12212026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/cr2.out 51K
12222026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/ox.err 1K
12232026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/cr3.out 127K
12242026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/cr1.err 1K
12252026-10-15T04:32:40.368Za falcon-interop-bfd-static-routing/peer.err 1K
12262026-10-15T04:32:40.368Z+ rm -rf /work/falcon-interop-bfd-static-routing
12272026-10-15T04:32:40.368Z+ pfexec ./falcon-lab cleanup interop bfd-static-routing
12282026-10-15T04:32:40.392ZOct 15 04:32:40.366 INFO destroying runner for deployment interop_bfd
12292026-10-15T04:32:40.392ZOct 15 04:32:40.366 INFO destroying deployment interop_bfd
12302026-10-15T04:32:40.392ZOct 15 04:32:40.366 INFO destroying nodes
12312026-10-15T04:32:40.463ZOct 15 04:32:40.451 INFO destroying links
12322026-10-15T04:32:40.463ZOct 15 04:32:40.451 INFO destroying link interop_bfd_ox_sn_vnic0
12332026-10-15T04:32:40.487ZOct 15 04:32:40.456 INFO destroying link interop_bfd_ox_sn_sim0
12342026-10-15T04:32:40.487ZOct 15 04:32:40.457 INFO destroying link interop_bfd_cr1_vn_vnic0
12352026-10-15T04:32:41.472ZOct 15 04:32:41.461 INFO destroying link interop_bfd_cr1_vn_sim0
12362026-10-15T04:32:41.496ZOct 15 04:32:41.462 INFO destroying link interop_bfd_ox_sn_vnic1
12372026-10-15T04:32:41.496ZOct 15 04:32:41.463 INFO destroying link interop_bfd_ox_sn_sim1
12382026-10-15T04:32:41.496ZOct 15 04:32:41.464 INFO destroying link interop_bfd_cr2_vn_vnic0
12392026-10-15T04:32:41.496ZOct 15 04:32:41.465 INFO destroying link interop_bfd_cr2_vn_sim0
12402026-10-15T04:32:41.496ZOct 15 04:32:41.466 INFO destroying link interop_bfd_ox_sn_vnic2
12412026-10-15T04:32:41.496ZOct 15 04:32:41.467 INFO destroying link interop_bfd_ox_sn_sim2
12422026-10-15T04:32:41.496ZOct 15 04:32:41.468 INFO destroying link interop_bfd_cr3_vn_vnic0
12432026-10-15T04:32:41.496ZOct 15 04:32:41.469 INFO destroying link interop_bfd_cr3_vn_sim0
12442026-10-15T04:32:41.496ZOct 15 04:32:41.470 INFO destroying link interop_bfd_ox_sn_vnic3
12452026-10-15T04:32:41.496ZOct 15 04:32:41.471 INFO destroying link interop_bfd_ox_sn_sim3
12462026-10-15T04:32:41.496ZOct 15 04:32:41.472 INFO destroying link interop_bfd_peer_vn_vnic0
12472026-10-15T04:32:41.496ZOct 15 04:32:41.473 INFO destroying link interop_bfd_peer_vn_sim0
12482026-10-15T04:32:41.496ZOct 15 04:32:41.474 INFO destroying external links
12492026-10-15T04:32:41.496ZOct 15 04:32:41.474 INFO destroying external link interop_bfd_ox_vn_vnic4
12502026-10-15T04:32:41.496ZOct 15 04:32:41.475 INFO destroying external link interop_bfd_cr1_vn_vnic1
12512026-10-15T04:32:41.496ZOct 15 04:32:41.476 INFO destroying external link interop_bfd_cr2_vn_vnic1
12522026-10-15T04:32:41.497ZOct 15 04:32:41.478 INFO destroying external link interop_bfd_cr3_vn_vnic1
12532026-10-15T04:32:41.497ZOct 15 04:32:41.479 INFO destroying external link interop_bfd_peer_vn_vnic1
12542026-10-15T04:32:41.497ZOct 15 04:32:41.480 INFO destroying images
12552026-10-15T04:32:41.879ZOct 15 04:32:41.867 INFO destroying workspace at .falcon
12562026-10-15T04:32:41.903Z+ return 1
12572026-10-15T04:32:41.903Zprocess exited: duration 1218912 ms, exit code 1
 
12582026-10-15T04:32:41.981Zfound 2 output files
12592026-10-15T04:32:41.981Zuploading: /work/dhcp-server.log (38263 bytes)
12602026-10-15T04:32:43.123Zuploaded: /work/dhcp-server.log
12612026-10-15T04:32:43.123Zuploading: /work/falcon-interop-bfd-static-routing.tar.gz (26786 bytes)
12622026-10-15T04:32:44.255Zuploaded: /work/falcon-interop-bfd-static-routing.tar.gz