01KEKBTT8ETT1V2R53JR4E0YGD: falcon

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

Buildomat Job: 01KEKBVJ8J2JZ8PX7S40ZHYRZ1

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-01-10T07:35:37.649Zjob dependencies complete; ready to run (waiting for 25 m 23 s)
22026-01-10T07:38:28.599Zjob assigned to worker 01KEKD9K0C12AWSE0R4HZFDMEZ [factory edgar, gimlet/BRM42220060/35] (queued for 2 m 50 s)
32026-01-10T07:38:29.315Zdownloading input: /input/build/work/debug/ddmadm
42026-01-10T07:38:50.257Zdownloaded input: /input/build/work/debug/ddmadm
52026-01-10T07:38:50.257Zdownloading input: /input/build/work/debug/ddmd
62026-01-10T07:39:14.279Zdownloaded input: /input/build/work/debug/ddmd
72026-01-10T07:39:14.279Zdownloading input: /input/build/work/debug/mgadm
82026-01-10T07:39:38.622Zdownloaded input: /input/build/work/debug/mgadm
92026-01-10T07:39:38.646Zdownloading input: /input/build-interop/work/testbed.tar.gz
102026-01-10T07:39:46.809Zdownloaded input: /input/build-interop/work/testbed.tar.gz
112026-01-10T07:39:46.809Zdownloading input: /input/build-interop/work/dhcp-server
122026-01-10T07:39:49.192Zdownloaded input: /input/build-interop/work/dhcp-server
132026-01-10T07:39:49.192Zdownloading input: /input/build/work/debug/mgd
142026-01-10T07:40:19.995Zdownloaded input: /input/build/work/debug/mgd
152026-01-10T07:40:19.995Zdownloading input: /input/build/work/release/ddmadm
162026-01-10T07:40:21.528Zdownloaded input: /input/build/work/release/ddmadm
172026-01-10T07:40:21.528Zdownloading input: /input/build/work/release/ddmd
182026-01-10T07:40:24.044Zdownloaded input: /input/build/work/release/ddmd
192026-01-10T07:40:24.045Zdownloading input: /input/build/work/release/falcon-lab
202026-01-10T07:40:26.531Zdownloaded input: /input/build/work/release/falcon-lab
212026-01-10T07:40:26.532Zdownloading input: /input/build/work/release/mgadm
222026-01-10T07:40:28.864Zdownloaded input: /input/build/work/release/mgadm
232026-01-10T07:40:28.864Zdownloading input: /input/build/work/release/mgd
242026-01-10T07:40:31.936Zdownloaded input: /input/build/work/release/mgd
 
252026-01-10T07:40:31.936Zstarting task 0: "setup"
262026-01-10T07:40:31.960Z++ uname -s
272026-01-10T07:40:31.960Z+ kern=SunOS
282026-01-10T07:40:31.960Z+ build_user=build
292026-01-10T07:40:31.960Z+ build_uid=12345
302026-01-10T07:40:31.960Z+ work_dir=/work
312026-01-10T07:40:31.960Z+ input_dir=/input
322026-01-10T07:40:31.961Z+ [[ 0 == 12345 ]]
332026-01-10T07:40:31.961Z+ case "$kern" in
342026-01-10T07:40:31.961Z+ groupadd -g 12345 build
352026-01-10T07:40:31.985Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
362026-01-10T07:40:33.974Z+ zfs create -o mountpoint=/work rpool/work
372026-01-10T07:40:34.731Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
382026-01-10T07:40:34.755Z+ home_fs=
392026-01-10T07:40:34.755Z+ [[ '' == autofs ]]
402026-01-10T07:40:34.755Z+ mkdir -p /home/build
412026-01-10T07:40:34.755Z+ chown build:build /home/build /work
422026-01-10T07:40:35.744Z+ chmod 0700 /home/build /work
432026-01-10T07:40:35.768Zprocess exited: duration 3811 ms, exit code 0
 
442026-01-10T07:40:35.817Zstarting task 1: "authentication"
452026-01-10T07:40:35.912Zprocess exited: duration 94 ms, exit code 0
 
462026-01-10T07:40:35.959Zstarting task 2: "build"
472026-01-10T07:40:35.983Z+ set -e
482026-01-10T07:40:35.983Z+ banner zpool
492026-01-10T07:40:35.983Z
502026-01-10T07:40:35.983Z ###### ##### #### #### #
512026-01-10T07:40:35.983Z # # # # # # # #
522026-01-10T07:40:35.983Z # # # # # # # #
532026-01-10T07:40:35.983Z # ##### # # # # #
542026-01-10T07:40:35.983Z # # # # # # #
552026-01-10T07:40:35.983Z ###### # #### #### ######
562026-01-10T07:40:35.983Z
572026-01-10T07:40:35.983Z++ pfexec diskinfo -pH
582026-01-10T07:40:36.007Z++ sort -k8 -n -r
592026-01-10T07:40:36.007Z++ head -1
602026-01-10T07:40:36.007Z++ awk '{print $2}'
612026-01-10T07:40:36.031Z+ DISK=c9t0014EE81000BC57Cd0
622026-01-10T07:40:36.031Z+ export DISK
632026-01-10T07:40:36.031Z+ pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC57Cd0
642026-01-10T07:40:36.102Z+ pfexec zfs create -o mountpoint=/ci cpool/ci
652026-01-10T07:40:36.129Z++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me
662026-01-10T07:43:50.929Z+ [[ '' =~ true ]]
672026-01-10T07:43:50.929Z+ pfexec chown 12345 /ci
682026-01-10T07:43:50.965Z+ cd /ci
692026-01-10T07:43:50.965Z+ export FALCON_DATASET=cpool/falcon
702026-01-10T07:43:50.965Z+ FALCON_DATASET=cpool/falcon
712026-01-10T07:43:50.965Z+ banner setup
722026-01-10T07:43:50.965Z
732026-01-10T07:43:50.965Z #### ###### ##### # # #####
742026-01-10T07:43:50.965Z # # # # # # #
752026-01-10T07:43:50.965Z #### ##### # # # # #
762026-01-10T07:43:50.965Z # # # # # #####
772026-01-10T07:43:50.965Z # # # # # # #
782026-01-10T07:43:50.965Z #### ###### # #### #
792026-01-10T07:43:50.965Z
802026-01-10T07:43:50.965Z+ cp /input/build-interop/work/dhcp-server .
812026-01-10T07:43:51.117Z+ cp /input/build/work/release/falcon-lab .
822026-01-10T07:43:51.162Z+ cp /input/build/work/release/mgd .
832026-01-10T07:43:51.224Z+ cp /input/build/work/release/ddmd .
842026-01-10T07:43:51.285Z+ chmod +x dhcp-server falcon-lab mgd ddmd
852026-01-10T07:43:51.318Z+ mkdir -p cargo-bay
862026-01-10T07:43:51.318Z+ mv mgd cargo-bay/
872026-01-10T07:43:51.318Z+ mv ddmd cargo-bay/
882026-01-10T07:43:51.318Z+ export EXT_INTERFACE=igb0
892026-01-10T07:43:51.319Z+ EXT_INTERFACE=igb0
902026-01-10T07:43:51.319Z++ bmat address ls -f extra -Ho first
912026-01-10T07:43:51.344Z+ first=10.151.7.164
922026-01-10T07:43:51.344Z++ bmat address ls -f extra -Ho last
932026-01-10T07:43:51.377Z+ last=10.151.7.227
942026-01-10T07:43:51.377Z++ bmat address ls -f extra -Ho gateway
952026-01-10T07:43:51.401Z+ gw=10.151.7.1
962026-01-10T07:43:51.401Z++ ipadm show-addr igb0/dhcp -po ADDR
972026-01-10T07:43:51.401Z++ sed 's#/.*##g'
982026-01-10T07:43:51.401Z+ server=10.151.7.100
992026-01-10T07:43:51.401Z+ RUST_LOG=debug
1002026-01-10T07:43:51.401Z+ pfexec ./falcon-lab run --dendrite-commit 0c2ab6c341bf9e3802c688961b3bc687b941a144 trio-unnumbered
1012026-01-10T07:43:51.401Z+ pfexec ./dhcp-server 10.151.7.164 10.151.7.227 10.151.7.1 10.151.7.100
1022026-01-10T07:43:51.429ZJan 10 07:43:51.394 DEBG using default route interface igb0
1032026-01-10T07:43:51.429ZJan 10 07:43:51.394 DEBG using default route interface igb0
1042026-01-10T07:43:51.429ZJan 10 07:43:51.394 DEBG using default route interface igb0
1052026-01-10T07:43:51.429ZJan 10 07:43:51.394 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon)
1062026-01-10T07:43:51.429ZJan 10 07:43:51.394 INFO starting preflight for deployment mgtriou
1072026-01-10T07:43:51.429ZJan 10 07:43:51.394 INFO propolis-server binary not found
1082026-01-10T07:43:51.429ZJan 10 07:43:51.394 INFO downloading propolis server rev 2dc643742f82d2e072a1281dab23ba2bfdcee440, writing to .falcon/bin/propolis-server
1092026-01-10T07:43:55.004ZJan 10 07:43:54.993 INFO ovmf fd not found
1102026-01-10T07:43:55.004ZJan 10 07:43:54.993 INFO downloading ovmf
1112026-01-10T07:43:55.329ZJan 10 07:43:55.317 INFO base image for helios-2.9 does not exist, attempting to install
1122026-01-10T07:43:55.329ZJan 10 07:43:55.317 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz
1132026-01-10T07:44:23.522ZJan 10 07:44:23.510 INFO extracting image to /tmp/helios-2.9_0.raw
1142026-01-10T07:44:56.918ZJan 10 07:44:56.906 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896
1152026-01-10T07:44:56.961ZJan 10 07:44:56.949 INFO copying image data to zvol
1162026-01-10T07:45:07.344ZJan 10 07:45:07.333 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base
1172026-01-10T07:45:10.795ZJan 10 07:45:10.783 INFO base image for debian-13.2 does not exist, attempting to install
1182026-01-10T07:45:10.795ZJan 10 07:45:10.783 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz
1192026-01-10T07:45:43.919ZJan 10 07:45:43.907 INFO extracting image to /tmp/debian-13.2_0.raw
1202026-01-10T07:46:04.696ZJan 10 07:46:04.684 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568
1212026-01-10T07:46:04.720ZJan 10 07:46:04.705 INFO copying image data to zvol
1222026-01-10T07:46:10.797ZJan 10 07:46:10.785 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base
1232026-01-10T07:46:14.312ZJan 10 07:46:14.287 INFO creating links
1242026-01-10T07:46:14.312ZJan 10 07:46:14.287 DEBG destroying link mgtriou_ox_sn_vnic0
1252026-01-10T07:46:14.312ZJan 10 07:46:14.287 DEBG destroying link mgtriou_ox_sn_sim0
1262026-01-10T07:46:14.312ZJan 10 07:46:14.287 INFO creating simnet link 'mgtriou_ox_sn_sim0'
1272026-01-10T07:46:14.312ZJan 10 07:46:14.290 INFO creating vnic link 'mgtriou_ox_sn_vnic0'
1282026-01-10T07:46:14.312ZJan 10 07:46:14.297 DEBG link pair created
1292026-01-10T07:46:14.312ZJan 10 07:46:14.297 DEBG destroying link mgtriou_cr1_vn_vnic0
1302026-01-10T07:46:14.312ZJan 10 07:46:14.297 DEBG destroying link mgtriou_cr1_vn_sim0
1312026-01-10T07:46:14.312ZJan 10 07:46:14.297 INFO creating simnet link 'mgtriou_cr1_vn_sim0'
1322026-01-10T07:46:14.313ZJan 10 07:46:14.298 INFO creating vnic link 'mgtriou_cr1_vn_vnic0'
1332026-01-10T07:46:14.336ZJan 10 07:46:14.305 DEBG link pair created
1342026-01-10T07:46:14.336ZJan 10 07:46:14.305 DEBG destroying link mgtriou_ox_sn_vnic1
1352026-01-10T07:46:14.336ZJan 10 07:46:14.305 DEBG destroying link mgtriou_ox_sn_sim1
1362026-01-10T07:46:14.336ZJan 10 07:46:14.305 INFO creating simnet link 'mgtriou_ox_sn_sim1'
1372026-01-10T07:46:14.336ZJan 10 07:46:14.307 INFO creating vnic link 'mgtriou_ox_sn_vnic1'
1382026-01-10T07:46:14.336ZJan 10 07:46:14.314 DEBG link pair created
1392026-01-10T07:46:14.336ZJan 10 07:46:14.314 DEBG destroying link mgtriou_cr2_vn_vnic0
1402026-01-10T07:46:14.336ZJan 10 07:46:14.314 DEBG destroying link mgtriou_cr2_vn_sim0
1412026-01-10T07:46:14.336ZJan 10 07:46:14.314 INFO creating simnet link 'mgtriou_cr2_vn_sim0'
1422026-01-10T07:46:14.336ZJan 10 07:46:14.315 INFO creating vnic link 'mgtriou_cr2_vn_vnic0'
1432026-01-10T07:46:14.336ZJan 10 07:46:14.321 DEBG link pair created
1442026-01-10T07:46:14.336ZJan 10 07:46:14.321 INFO creating external links
1452026-01-10T07:46:14.336ZJan 10 07:46:14.321 DEBG destroying external link mgtriou_ox_vn_vnic2
1462026-01-10T07:46:14.336ZJan 10 07:46:14.321 INFO creating external link mgtriou_ox_vn_vnic2
1472026-01-10T07:46:14.336ZJan 10 07:46:14.323 DEBG external link mgtriou_ox_vn_vnic2@igb0 created
1482026-01-10T07:46:14.336ZJan 10 07:46:14.323 DEBG destroying external link mgtriou_cr1_vn_vnic1
1492026-01-10T07:46:14.336ZJan 10 07:46:14.323 INFO creating external link mgtriou_cr1_vn_vnic1
1502026-01-10T07:46:14.336ZJan 10 07:46:14.324 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created
1512026-01-10T07:46:14.336ZJan 10 07:46:14.324 DEBG destroying external link mgtriou_cr2_vn_vnic1
1522026-01-10T07:46:14.337ZJan 10 07:46:14.324 INFO creating external link mgtriou_cr2_vn_vnic1
1532026-01-10T07:46:14.361ZJan 10 07:46:14.325 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created
1542026-01-10T07:46:14.361ZJan 10 07:46:14.325 INFO creating nodes
1552026-01-10T07:46:14.361ZJan 10 07:46:14.326 INFO ox: launching node
1562026-01-10T07:46:14.361ZJan 10 07:46:14.328 INFO cr1: launching node
1572026-01-10T07:46:14.361ZJan 10 07:46:14.330 INFO cr2: launching node
1582026-01-10T07:46:14.361ZJan 10 07:46:14.344 INFO launched instance ox with pid 833 on port 37867
1592026-01-10T07:46:14.361ZJan 10 07:46:14.344 INFO ox: instance ensure
1602026-01-10T07:46:14.361ZJan 10 07:46:14.344 INFO launched instance cr1 with pid 834 on port 59899
1612026-01-10T07:46:14.361ZJan 10 07:46:14.344 INFO cr1: instance ensure
1622026-01-10T07:46:14.361ZJan 10 07:46:14.345 INFO launched instance cr2 with pid 835 on port 56740
1632026-01-10T07:46:14.361ZJan 10 07:46:14.345 INFO cr2: instance ensure
1642026-01-10T07:46:16.630ZJan 10 07:46:16.619 INFO cr1: instance run
1652026-01-10T07:46:16.654ZJan 10 07:46:16.619 DEBG [sc] cr1: starting
1662026-01-10T07:46:16.654ZJan 10 07:46:16.619 DEBG [sc] cr1: connecting to [::1]:59899
1672026-01-10T07:46:16.654ZJan 10 07:46:16.620 DEBG [sc] cr1 waiting for prompt
1682026-01-10T07:46:16.678ZJan 10 07:46:16.647 INFO ox: instance run
1692026-01-10T07:46:16.678ZJan 10 07:46:16.647 DEBG [sc] ox: starting
1702026-01-10T07:46:16.678ZJan 10 07:46:16.647 DEBG [sc] ox: connecting to [::1]:37867
1712026-01-10T07:46:16.678ZJan 10 07:46:16.648 DEBG [sc] ox waiting for prompt
1722026-01-10T07:46:16.703ZJan 10 07:46:16.692 INFO cr2: instance run
1732026-01-10T07:46:16.727ZJan 10 07:46:16.692 DEBG [sc] cr2: starting
1742026-01-10T07:46:16.727ZJan 10 07:46:16.692 DEBG [sc] cr2: connecting to [::1]:56740
1752026-01-10T07:46:16.727ZJan 10 07:46:16.693 DEBG [sc] cr2 waiting for prompt
1762026-01-10T07:46:34.147ZJan 10 07:46:34.135 DEBG [sc] cr1: logging in
1772026-01-10T07:46:34.432ZJan 10 07:46:34.420 DEBG [sc] cr2: logging in
1782026-01-10T07:46:34.974ZJan 10 07:46:34.962 INFO cr1: mounting /opt/cargo-bay
1792026-01-10T07:46:34.974ZJan 10 07:46:34.962 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
1802026-01-10T07:46:35.007ZJan 10 07:46:34.996 DEBG [sc] cr1: executing command `cd`
1812026-01-10T07:46:35.031ZJan 10 07:46:35.007 INFO cr1: finished mounting /opt/cargo-bay
1822026-01-10T07:46:35.031ZJan 10 07:46:35.007 DEBG [sc] cr1: executing command `hostname cr1`
1832026-01-10T07:46:35.055ZJan 10 07:46:35.029 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename`
1842026-01-10T07:46:35.055ZJan 10 07:46:35.040 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts`
1852026-01-10T07:46:35.079ZJan 10 07:46:35.050 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts`
1862026-01-10T07:46:35.079ZJan 10 07:46:35.061 INFO cr1: logging out
1872026-01-10T07:46:35.424ZJan 10 07:46:35.412 INFO cr2: mounting /opt/cargo-bay
1882026-01-10T07:46:35.424ZJan 10 07:46:35.412 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
1892026-01-10T07:46:35.456ZJan 10 07:46:35.444 DEBG [sc] cr2: executing command `cd`
1902026-01-10T07:46:35.480ZJan 10 07:46:35.456 INFO cr2: finished mounting /opt/cargo-bay
1912026-01-10T07:46:35.480ZJan 10 07:46:35.456 DEBG [sc] cr2: executing command `hostname cr2`
1922026-01-10T07:46:35.480ZJan 10 07:46:35.467 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename`
1932026-01-10T07:46:35.504ZJan 10 07:46:35.478 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts`
1942026-01-10T07:46:35.504ZJan 10 07:46:35.489 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts`
1952026-01-10T07:46:35.528ZJan 10 07:46:35.499 INFO cr2: logging out
1962026-01-10T07:46:37.192ZJan 10 07:46:37.180 INFO cr1: logged out
1972026-01-10T07:46:37.636ZJan 10 07:46:37.625 INFO cr2: logged out
1982026-01-10T07:46:39.264ZJan 10 07:46:39.253 DEBG [sc] ox: logging in
1992026-01-10T07:46:39.456ZJan 10 07:46:39.444 INFO ox: mounting /opt/cargo-bay
2002026-01-10T07:46:39.456ZJan 10 07:46:39.444 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull`
2012026-01-10T07:46:40.390ZJan 10 07:46:40.378 DEBG [sc] ox: executing command `cd`
2022026-01-10T07:46:40.414ZJan 10 07:46:40.389 INFO ox: finished mounting /opt/cargo-bay
2032026-01-10T07:46:40.414ZJan 10 07:46:40.389 DEBG [sc] ox: executing command `hostname ox`
2042026-01-10T07:46:40.414ZJan 10 07:46:40.400 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename`
2052026-01-10T07:46:40.437ZJan 10 07:46:40.411 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts`
2062026-01-10T07:46:40.437ZJan 10 07:46:40.423 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts`
2072026-01-10T07:46:40.461ZJan 10 07:46:40.433 INFO ox: logging out
2082026-01-10T07:46:40.522ZJan 10 07:46:40.510 INFO ox: logged out
2092026-01-10T07:46:40.546ZJan 10 07:46:40.510 DEBG [sc] ox: starting
2102026-01-10T07:46:40.546ZJan 10 07:46:40.510 DEBG [sc] ox: connecting to [::1]:37867
2112026-01-10T07:46:40.546ZJan 10 07:46:40.511 DEBG [sc] ox waiting for prompt
2122026-01-10T07:46:40.546ZJan 10 07:46:40.521 DEBG [sc] ox: logging in
2132026-01-10T07:46:40.621ZJan 10 07:46:40.609 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp`
2142026-01-10T07:46:45.897ZJan 10 07:46:45.885 DEBG [sc] ox: starting
2152026-01-10T07:46:45.897ZJan 10 07:46:45.885 DEBG [sc] ox: connecting to [::1]:37867
2162026-01-10T07:46:45.921ZJan 10 07:46:45.886 DEBG [sc] ox waiting for prompt
2172026-01-10T07:46:45.921ZJan 10 07:46:45.896 DEBG [sc] ox: logging in
2182026-01-10T07:46:45.984ZJan 10 07:46:45.972 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf`
2192026-01-10T07:46:46.061ZJan 10 07:46:46.050 DEBG [sc] ox: starting
2202026-01-10T07:46:46.061ZJan 10 07:46:46.050 DEBG [sc] ox: connecting to [::1]:37867
2212026-01-10T07:46:46.086ZJan 10 07:46:46.050 DEBG [sc] ox waiting for prompt
2222026-01-10T07:46:46.086ZJan 10 07:46:46.061 DEBG [sc] ox: logging in
2232026-01-10T07:46:46.151ZJan 10 07:46:46.139 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr`
2242026-01-10T07:46:46.217ZJan 10 07:46:46.205 INFO cr1: installing frr
2252026-01-10T07:46:46.217ZJan 10 07:46:46.205 INFO cr2: installing frr
2262026-01-10T07:46:46.217ZJan 10 07:46:46.206 INFO ox: setting up npuvm
2272026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] cr2: starting
2282026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] cr2: connecting to [::1]:56740
2292026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] ox: starting
2302026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] ox: connecting to [::1]:37867
2312026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] cr1: starting
2322026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] cr1: connecting to [::1]:59899
2332026-01-10T07:46:46.241ZJan 10 07:46:46.206 DEBG [sc] ox waiting for prompt
2342026-01-10T07:46:46.242ZJan 10 07:46:46.206 DEBG [sc] cr2 waiting for prompt
2352026-01-10T07:46:46.242ZJan 10 07:46:46.206 DEBG [sc] cr1 waiting for prompt
2362026-01-10T07:46:46.242ZJan 10 07:46:46.216 DEBG [sc] ox: logging in
2372026-01-10T07:46:46.242ZJan 10 07:46:46.218 DEBG [sc] cr2: logging in
2382026-01-10T07:46:46.242ZJan 10 07:46:46.218 DEBG [sc] cr1: logging in
2392026-01-10T07:46:46.316ZJan 10 07:46:46.304 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm`
2402026-01-10T07:46:46.560ZJan 10 07:46:46.544 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr`
2412026-01-10T07:46:46.585ZJan 10 07:46:46.556 DEBG [sc] cr2: executing command `apt-get -y update && apt-get -y install frr`
2422026-01-10T07:46:51.543ZJan 10 07:46:51.531 DEBG [sc] ox: starting
2432026-01-10T07:46:51.543ZJan 10 07:46:51.531 DEBG [sc] ox: connecting to [::1]:37867
2442026-01-10T07:46:51.567ZJan 10 07:46:51.531 DEBG [sc] ox waiting for prompt
2452026-01-10T07:46:51.567ZJan 10 07:46:51.542 DEBG [sc] ox: logging in
2462026-01-10T07:46:51.631ZJan 10 07:46:51.619 DEBG [sc] ox: executing command `chmod +x npuvm`
2472026-01-10T07:46:51.686ZJan 10 07:46:51.674 DEBG [sc] ox: starting
2482026-01-10T07:46:51.686ZJan 10 07:46:51.674 DEBG [sc] ox: connecting to [::1]:37867
2492026-01-10T07:46:51.710ZJan 10 07:46:51.675 DEBG [sc] ox waiting for prompt
2502026-01-10T07:46:51.710ZJan 10 07:46:51.685 DEBG [sc] ox: logging in
2512026-01-10T07:46:51.775ZJan 10 07:46:51.763 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 --dendrite-commit 0c2ab6c341bf9e3802c688961b3bc687b941a144`
2522026-01-10T07:47:23.886ZJan 10 07:47:23.874 INFO cr1: enabling frr daemon bgpd
2532026-01-10T07:47:23.886ZJan 10 07:47:23.874 DEBG [sc] cr1: starting
2542026-01-10T07:47:23.886ZJan 10 07:47:23.874 DEBG [sc] cr1: connecting to [::1]:59899
2552026-01-10T07:47:23.910ZJan 10 07:47:23.875 DEBG [sc] cr1 waiting for prompt
2562026-01-10T07:47:23.910ZJan 10 07:47:23.885 DEBG [sc] cr1: logging in
2572026-01-10T07:47:24.029ZJan 10 07:47:24.017 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
2582026-01-10T07:47:24.314ZJan 10 07:47:24.295 INFO cr2: enabling frr daemon bgpd
2592026-01-10T07:47:24.314ZJan 10 07:47:24.295 DEBG [sc] cr2: starting
2602026-01-10T07:47:24.314ZJan 10 07:47:24.295 DEBG [sc] cr2: connecting to [::1]:56740
2612026-01-10T07:47:24.314ZJan 10 07:47:24.296 DEBG [sc] cr2 waiting for prompt
2622026-01-10T07:47:24.338ZJan 10 07:47:24.306 DEBG [sc] cr2: logging in
2632026-01-10T07:47:24.439ZJan 10 07:47:24.427 DEBG [sc] cr2: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons`
2642026-01-10T07:47:26.105ZJan 10 07:47:26.093 DEBG [sc] cr1: starting
2652026-01-10T07:47:26.105ZJan 10 07:47:26.093 DEBG [sc] cr1: connecting to [::1]:59899
2662026-01-10T07:47:26.129ZJan 10 07:47:26.093 DEBG [sc] cr1 waiting for prompt
2672026-01-10T07:47:26.129ZJan 10 07:47:26.105 DEBG [sc] cr1: logging in
2682026-01-10T07:47:26.270ZJan 10 07:47:26.258 DEBG [sc] cr1: executing command `systemctl restart frr`
2692026-01-10T07:47:26.515ZJan 10 07:47:26.503 DEBG [sc] cr2: starting
2702026-01-10T07:47:26.515ZJan 10 07:47:26.503 DEBG [sc] cr2: connecting to [::1]:56740
2712026-01-10T07:47:26.540ZJan 10 07:47:26.504 DEBG [sc] cr2 waiting for prompt
2722026-01-10T07:47:26.541ZJan 10 07:47:26.514 DEBG [sc] cr2: logging in
2732026-01-10T07:47:26.670ZJan 10 07:47:26.659 DEBG [sc] cr2: executing command `systemctl restart frr`
2742026-01-10T07:47:33.872ZJan 10 07:47:33.860 INFO cr1: executing frr script
2752026-01-10T07:47:33.872Z configure
2762026-01-10T07:47:33.872Z ip forwarding
2772026-01-10T07:47:33.872Z ip route 1.2.3.0/24 null0
2782026-01-10T07:47:33.872Z route-map PERMIT-ALL permit 10
2792026-01-10T07:47:33.872Z router bgp 44
2802026-01-10T07:47:33.872Z timers bgp 2 6
2812026-01-10T07:47:33.872Z neighbor enp0s8 interface remote-as external
2822026-01-10T07:47:33.872Z neighbor enp0s8 capability extended-nexthop
2832026-01-10T07:47:33.872Z neighbor enp0s8 timers connect 1
2842026-01-10T07:47:33.872Z neighbor enp0s8 route-map PERMIT-ALL out
2852026-01-10T07:47:33.872Z network 1.2.3.0/24
2862026-01-10T07:47:33.873Z exit
2872026-01-10T07:47:33.873Z
2882026-01-10T07:47:33.873ZJan 10 07:47:33.860 DEBG [sc] cr1: starting
2892026-01-10T07:47:33.873ZJan 10 07:47:33.860 DEBG [sc] cr1: connecting to [::1]:59899
2902026-01-10T07:47:33.897ZJan 10 07:47:33.861 DEBG [sc] cr1 waiting for prompt
2912026-01-10T07:47:33.897ZJan 10 07:47:33.872 DEBG [sc] cr1: logging in
2922026-01-10T07:47:34.015ZJan 10 07:47:34.004 DEBG [sc] cr1: executing command `vtysh '' -c ' configure' -c ' ip forwarding' -c ' ip route 1.2.3.0/24 null0' -c ' route-map PERMIT-ALL permit 10' -c ' router bgp 44' -c ' timers bgp 2 6' -c ' neighbor enp0s8 interface remote-as external' -c ' neighbor enp0s8 capability extended-nexthop' -c ' neighbor enp0s8 timers connect 1 ' -c ' neighbor enp0s8 route-map PERMIT-ALL out' -c ' network 1.2.3.0/24' -c ' exit' -c ' '`
2932026-01-10T07:47:34.279ZJan 10 07:47:34.267 INFO cr2: executing frr script
2942026-01-10T07:47:34.279Z configure
2952026-01-10T07:47:34.279Z ip forwarding
2962026-01-10T07:47:34.279Z ip route 1.2.3.0/24 null0
2972026-01-10T07:47:34.279Z route-map PERMIT-ALL permit 10
2982026-01-10T07:47:34.279Z router bgp 44
2992026-01-10T07:47:34.279Z timers bgp 2 6
3002026-01-10T07:47:34.279Z neighbor enp0s8 interface remote-as external
3012026-01-10T07:47:34.279Z neighbor enp0s8 capability extended-nexthop
3022026-01-10T07:47:34.279Z neighbor enp0s8 timers connect 1
3032026-01-10T07:47:34.279Z neighbor enp0s8 route-map PERMIT-ALL out
3042026-01-10T07:47:34.280Z network 1.2.3.0/24
3052026-01-10T07:47:34.280Z exit
3062026-01-10T07:47:34.280Z
3072026-01-10T07:47:34.280ZJan 10 07:47:34.267 DEBG [sc] cr2: starting
3082026-01-10T07:47:34.280ZJan 10 07:47:34.267 DEBG [sc] cr2: connecting to [::1]:56740
3092026-01-10T07:47:34.304ZJan 10 07:47:34.268 DEBG [sc] cr2 waiting for prompt
3102026-01-10T07:47:34.337ZJan 10 07:47:34.279 DEBG [sc] cr2: logging in
3112026-01-10T07:47:34.433ZJan 10 07:47:34.421 DEBG [sc] cr2: executing command `vtysh '' -c ' configure' -c ' ip forwarding' -c ' ip route 1.2.3.0/24 null0' -c ' route-map PERMIT-ALL permit 10' -c ' router bgp 44' -c ' timers bgp 2 6' -c ' neighbor enp0s8 interface remote-as external' -c ' neighbor enp0s8 capability extended-nexthop' -c ' neighbor enp0s8 timers connect 1 ' -c ' neighbor enp0s8 route-map PERMIT-ALL out' -c ' network 1.2.3.0/24' -c ' exit' -c ' '`
3122026-01-10T07:48:13.495ZJan 10 07:48:13.483 DEBG [sc] ox: starting
3132026-01-10T07:48:13.495ZJan 10 07:48:13.483 DEBG [sc] ox: connecting to [::1]:37867
3142026-01-10T07:48:13.519ZJan 10 07:48:13.484 DEBG [sc] ox waiting for prompt
3152026-01-10T07:48:13.519ZJan 10 07:48:13.494 DEBG [sc] ox: logging in
3162026-01-10T07:48:13.583ZJan 10 07:48:13.572 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so`
3172026-01-10T07:48:14.232ZJan 10 07:48:14.221 DEBG [sc] ox: starting
3182026-01-10T07:48:14.233ZJan 10 07:48:14.221 DEBG [sc] ox: connecting to [::1]:37867
3192026-01-10T07:48:14.257ZJan 10 07:48:14.221 DEBG [sc] ox waiting for prompt
3202026-01-10T07:48:14.257ZJan 10 07:48:14.233 DEBG [sc] ox: logging in
3212026-01-10T07:48:14.322ZJan 10 07:48:14.310 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
3222026-01-10T07:48:15.346ZJan 10 07:48:15.334 DEBG [sc] ox: starting
3232026-01-10T07:48:15.346ZJan 10 07:48:15.334 DEBG [sc] ox: connecting to [::1]:37867
3242026-01-10T07:48:15.370ZJan 10 07:48:15.335 DEBG [sc] ox waiting for prompt
3252026-01-10T07:48:15.370ZJan 10 07:48:15.335 DEBG [sc] ox: logging in
3262026-01-10T07:48:15.423ZJan 10 07:48:15.412 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link`
3272026-01-10T07:48:15.480ZJan 10 07:48:15.468 DEBG [sc] ox: starting
3282026-01-10T07:48:15.480ZJan 10 07:48:15.468 DEBG [sc] ox: connecting to [::1]:37867
3292026-01-10T07:48:15.504ZJan 10 07:48:15.469 DEBG [sc] ox waiting for prompt
3302026-01-10T07:48:15.504ZJan 10 07:48:15.478 DEBG [sc] ox: logging in
3312026-01-10T07:48:15.567ZJan 10 07:48:15.555 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll`
3322026-01-10T07:48:15.623ZJan 10 07:48:15.610 DEBG [sc] ox: starting
3332026-01-10T07:48:15.623ZJan 10 07:48:15.610 DEBG [sc] ox: connecting to [::1]:37867
3342026-01-10T07:48:15.647ZJan 10 07:48:15.611 DEBG [sc] ox waiting for prompt
3352026-01-10T07:48:15.647ZJan 10 07:48:15.622 DEBG [sc] ox: logging in
3362026-01-10T07:48:15.710ZJan 10 07:48:15.699 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr`
3372026-01-10T07:48:15.766ZJan 10 07:48:15.755 DEBG [sc] ox: starting
3382026-01-10T07:48:15.766ZJan 10 07:48:15.755 DEBG [sc] ox: connecting to [::1]:37867
3392026-01-10T07:48:15.791ZJan 10 07:48:15.756 DEBG [sc] ox waiting for prompt
3402026-01-10T07:48:15.791ZJan 10 07:48:15.765 DEBG [sc] ox: logging in
3412026-01-10T07:48:15.854ZJan 10 07:48:15.842 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link`
3422026-01-10T07:48:15.909ZJan 10 07:48:15.897 DEBG [sc] ox: starting
3432026-01-10T07:48:15.909ZJan 10 07:48:15.897 DEBG [sc] ox: connecting to [::1]:37867
3442026-01-10T07:48:15.935ZJan 10 07:48:15.898 DEBG [sc] ox waiting for prompt
3452026-01-10T07:48:15.935ZJan 10 07:48:15.908 DEBG [sc] ox: logging in
3462026-01-10T07:48:15.996ZJan 10 07:48:15.985 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll`
3472026-01-10T07:48:16.053ZJan 10 07:48:16.041 DEBG [sc] ox: starting
3482026-01-10T07:48:16.053ZJan 10 07:48:16.041 DEBG [sc] ox: connecting to [::1]:37867
3492026-01-10T07:48:16.077ZJan 10 07:48:16.042 DEBG [sc] ox waiting for prompt
3502026-01-10T07:48:16.077ZJan 10 07:48:16.052 DEBG [sc] ox: logging in
3512026-01-10T07:48:16.141ZJan 10 07:48:16.129 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr`
3522026-01-10T07:48:16.196ZJan 10 07:48:16.184 DEBG [sc] ox: starting
3532026-01-10T07:48:16.196ZJan 10 07:48:16.184 DEBG [sc] ox: connecting to [::1]:37867
3542026-01-10T07:48:16.220ZJan 10 07:48:16.185 DEBG [sc] ox waiting for prompt
3552026-01-10T07:48:16.220ZJan 10 07:48:16.195 DEBG [sc] ox: logging in
3562026-01-10T07:48:16.284ZJan 10 07:48:16.272 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &`
3572026-01-10T07:48:16.339ZJan 10 07:48:16.327 DEBG [sc] ox: starting
3582026-01-10T07:48:16.339ZJan 10 07:48:16.327 DEBG [sc] ox: connecting to [::1]:37867
3592026-01-10T07:48:16.363ZJan 10 07:48:16.328 DEBG [sc] ox waiting for prompt
3602026-01-10T07:48:16.364ZJan 10 07:48:16.338 DEBG [sc] ox: logging in
3612026-01-10T07:48:16.439ZJan 10 07:48:16.427 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &`
3622026-01-10T07:48:16.507ZJan 10 07:48:16.496 INFO adding BGP router to mgd
3632026-01-10T07:48:19.523ZJan 10 07:48:19.511 INFO trio bgp unnumbered test passed 🎉
3642026-01-10T07:48:19.523ZJan 10 07:48:19.511 INFO destroying runner for deployment mgtriou
3652026-01-10T07:48:19.523ZJan 10 07:48:19.511 INFO destroying deployment mgtriou
3662026-01-10T07:48:19.523ZJan 10 07:48:19.511 INFO destroying nodes
3672026-01-10T07:48:19.648ZJan 10 07:48:19.636 INFO destroying links
3682026-01-10T07:48:19.648ZJan 10 07:48:19.636 INFO destroying link mgtriou_ox_sn_vnic0
3692026-01-10T07:48:19.672ZJan 10 07:48:19.639 INFO destroying link mgtriou_ox_sn_sim0
3702026-01-10T07:48:19.672ZJan 10 07:48:19.640 INFO destroying link mgtriou_cr1_vn_vnic0
3712026-01-10T07:48:20.654ZJan 10 07:48:20.642 INFO destroying link mgtriou_cr1_vn_sim0
3722026-01-10T07:48:20.678ZJan 10 07:48:20.644 INFO destroying link mgtriou_ox_sn_vnic1
3732026-01-10T07:48:20.678ZJan 10 07:48:20.645 INFO destroying link mgtriou_ox_sn_sim1
3742026-01-10T07:48:20.678ZJan 10 07:48:20.646 INFO destroying link mgtriou_cr2_vn_vnic0
3752026-01-10T07:48:20.678ZJan 10 07:48:20.647 INFO destroying link mgtriou_cr2_vn_sim0
3762026-01-10T07:48:20.678ZJan 10 07:48:20.648 INFO destroying external links
3772026-01-10T07:48:20.678ZJan 10 07:48:20.648 INFO destroying external link mgtriou_ox_vn_vnic2
3782026-01-10T07:48:20.679ZJan 10 07:48:20.649 INFO destroying external link mgtriou_cr1_vn_vnic1
3792026-01-10T07:48:20.679ZJan 10 07:48:20.650 INFO destroying external link mgtriou_cr2_vn_vnic1
3802026-01-10T07:48:20.679ZJan 10 07:48:20.652 INFO destroying images
3812026-01-10T07:48:21.291ZJan 10 07:48:21.279 INFO destroying workspace at .falcon
3822026-01-10T07:48:21.315Zprocess exited: duration 465322 ms, exit code 0
 
3832026-01-10T07:48:21.366Zfound 0 output files