|
|
|
| 1 | 2026-03-02T08:58:52.185Z | job dependencies complete; ready to run (waiting for 28 m 51 s) |
| 2 | 2026-03-02T09:02:07.628Z | job assigned to worker 01KJPWB4YEWN0MC2BF7A21XC68 [factory edgar, gimlet/BRM42220010/508] (queued for 3 m 15 s) |
| 3 | 2026-03-02T09:02:08.280Z | downloading input: /input/build/work/debug/ddmadm |
| 4 | 2026-03-02T09:02:30.046Z | downloaded input: /input/build/work/debug/ddmadm |
| 5 | 2026-03-02T09:02:30.070Z | downloading input: /input/build-interop/work/testbed.tar.gz |
| 6 | 2026-03-02T09:02:38.259Z | downloaded input: /input/build-interop/work/testbed.tar.gz |
| 7 | 2026-03-02T09:02:38.259Z | downloading input: /input/build-interop/work/dhcp-server |
| 8 | 2026-03-02T09:02:40.712Z | downloaded input: /input/build-interop/work/dhcp-server |
| 9 | 2026-03-02T09:02:40.712Z | downloading input: /input/build/work/debug/ddmd |
| 10 | 2026-03-02T09:03:05.652Z | downloaded input: /input/build/work/debug/ddmd |
| 11 | 2026-03-02T09:03:05.652Z | downloading input: /input/build/work/debug/mgadm |
| 12 | 2026-03-02T09:03:31.371Z | downloaded input: /input/build/work/debug/mgadm |
| 13 | 2026-03-02T09:03:31.371Z | downloading input: /input/build/work/debug/mgd |
| 14 | 2026-03-02T09:04:00.160Z | downloaded input: /input/build/work/debug/mgd |
| 15 | 2026-03-02T09:04:00.191Z | downloading input: /input/build/work/release/ddmadm |
| 16 | 2026-03-02T09:04:01.656Z | downloaded input: /input/build/work/release/ddmadm |
| 17 | 2026-03-02T09:04:01.656Z | downloading input: /input/build/work/release/ddmd |
| 18 | 2026-03-02T09:04:03.878Z | downloaded input: /input/build/work/release/ddmd |
| 19 | 2026-03-02T09:04:03.878Z | downloading input: /input/build/work/release/falcon-lab |
| 20 | 2026-03-02T09:04:05.516Z | downloaded input: /input/build/work/release/falcon-lab |
| 21 | 2026-03-02T09:04:05.516Z | downloading input: /input/build/work/release/mgadm |
| 22 | 2026-03-02T09:04:08.468Z | downloaded input: /input/build/work/release/mgadm |
| 23 | 2026-03-02T09:04:08.468Z | downloading input: /input/build/work/release/mgd |
| 24 | 2026-03-02T09:04:11.922Z | downloaded input: /input/build/work/release/mgd |
| |
| 25 | 2026-03-02T09:04:11.946Z | starting task 0: "setup" |
| 26 | 2026-03-02T09:04:11.946Z | ++ uname -s |
| 27 | 2026-03-02T09:04:11.946Z | + kern=SunOS |
| 28 | 2026-03-02T09:04:11.946Z | + build_user=build |
| 29 | 2026-03-02T09:04:11.947Z | + build_uid=12345 |
| 30 | 2026-03-02T09:04:11.947Z | + work_dir=/work |
| 31 | 2026-03-02T09:04:11.947Z | + input_dir=/input |
| 32 | 2026-03-02T09:04:11.947Z | + [[ 0 == 12345 ]] |
| 33 | 2026-03-02T09:04:11.947Z | + case "$kern" in |
| 34 | 2026-03-02T09:04:11.947Z | + groupadd -g 12345 build |
| 35 | 2026-03-02T09:04:11.971Z | + useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build |
| 36 | 2026-03-02T09:04:13.958Z | + zfs create -o mountpoint=/work rpool/work |
| 37 | 2026-03-02T09:04:14.046Z | ++ awk '$2 == "/home" { print $3 }' /etc/mnttab |
| 38 | 2026-03-02T09:04:14.071Z | + home_fs= |
| 39 | 2026-03-02T09:04:14.071Z | + [[ '' == autofs ]] |
| 40 | 2026-03-02T09:04:14.071Z | + mkdir -p /home/build |
| 41 | 2026-03-02T09:04:14.071Z | + chown build:build /home/build /work |
| 42 | 2026-03-02T09:04:15.060Z | + chmod 0700 /home/build /work |
| 43 | 2026-03-02T09:04:15.084Z | process exited: duration 3141 ms, exit code 0 |
| |
| 44 | 2026-03-02T09:04:15.132Z | starting task 1: "authentication" |
| 45 | 2026-03-02T09:04:15.229Z | process exited: duration 96 ms, exit code 0 |
| |
| 46 | 2026-03-02T09:04:15.278Z | starting task 2: "build" |
| 47 | 2026-03-02T09:04:15.302Z | + set -e |
| 48 | 2026-03-02T09:04:15.302Z | + banner zpool |
| 49 | 2026-03-02T09:04:15.302Z | |
| 50 | 2026-03-02T09:04:15.302Z | ###### ##### #### #### # |
| 51 | 2026-03-02T09:04:15.302Z | # # # # # # # # |
| 52 | 2026-03-02T09:04:15.302Z | # # # # # # # # |
| 53 | 2026-03-02T09:04:15.302Z | # ##### # # # # # |
| 54 | 2026-03-02T09:04:15.302Z | # # # # # # # |
| 55 | 2026-03-02T09:04:15.302Z | ###### # #### #### ###### |
| 56 | 2026-03-02T09:04:15.302Z | |
| 57 | 2026-03-02T09:04:15.302Z | ++ pfexec diskinfo -pH |
| 58 | 2026-03-02T09:04:15.302Z | ++ sort -k8 -n -r |
| 59 | 2026-03-02T09:04:15.326Z | ++ head -1 |
| 60 | 2026-03-02T09:04:15.326Z | ++ awk '{print $2}' |
| 61 | 2026-03-02T09:04:15.351Z | + DISK=c9t0014EE81000BC3B1d0 |
| 62 | 2026-03-02T09:04:15.351Z | + export DISK |
| 63 | 2026-03-02T09:04:15.351Z | + pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0 |
| 64 | 2026-03-02T09:04:15.416Z | + pfexec zfs create -o mountpoint=/ci cpool/ci |
| 65 | 2026-03-02T09:04:15.444Z | ++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me |
| 66 | 2026-03-02T09:07:30.244Z | + [[ '' =~ true ]] |
| 67 | 2026-03-02T09:07:30.268Z | + pfexec chown 12345 /ci |
| 68 | 2026-03-02T09:07:30.268Z | + cd /ci |
| 69 | 2026-03-02T09:07:30.268Z | + export FALCON_DATASET=cpool/falcon |
| 70 | 2026-03-02T09:07:30.268Z | + FALCON_DATASET=cpool/falcon |
| 71 | 2026-03-02T09:07:30.268Z | + banner setup |
| 72 | 2026-03-02T09:07:30.268Z | |
| 73 | 2026-03-02T09:07:30.268Z | #### ###### ##### # # ##### |
| 74 | 2026-03-02T09:07:30.268Z | # # # # # # # |
| 75 | 2026-03-02T09:07:30.268Z | #### ##### # # # # # |
| 76 | 2026-03-02T09:07:30.269Z | # # # # # ##### |
| 77 | 2026-03-02T09:07:30.269Z | # # # # # # # |
| 78 | 2026-03-02T09:07:30.269Z | #### ###### # #### # |
| 79 | 2026-03-02T09:07:30.269Z | |
| 80 | 2026-03-02T09:07:30.269Z | + cp /input/build-interop/work/dhcp-server . |
| 81 | 2026-03-02T09:07:30.610Z | + cp /input/build/work/release/falcon-lab . |
| 82 | 2026-03-02T09:07:30.639Z | + cp /input/build/work/release/mgd . |
| 83 | 2026-03-02T09:07:30.704Z | + cp /input/build/work/release/ddmd . |
| 84 | 2026-03-02T09:07:30.747Z | + chmod +x dhcp-server falcon-lab mgd ddmd |
| 85 | 2026-03-02T09:07:30.772Z | + mkdir -p cargo-bay |
| 86 | 2026-03-02T09:07:30.772Z | + mv mgd cargo-bay/ |
| 87 | 2026-03-02T09:07:30.772Z | + mv ddmd cargo-bay/ |
| 88 | 2026-03-02T09:07:30.772Z | + export EXT_INTERFACE=igb0 |
| 89 | 2026-03-02T09:07:30.772Z | + EXT_INTERFACE=igb0 |
| 90 | 2026-03-02T09:07:30.772Z | ++ bmat address ls -f extra -Ho first |
| 91 | 2026-03-02T09:07:30.796Z | + first=10.151.6.164 |
| 92 | 2026-03-02T09:07:30.796Z | ++ bmat address ls -f extra -Ho last |
| 93 | 2026-03-02T09:07:30.825Z | + last=10.151.6.227 |
| 94 | 2026-03-02T09:07:30.825Z | ++ bmat address ls -f extra -Ho gateway |
| 95 | 2026-03-02T09:07:30.849Z | + gw=10.151.6.1 |
| 96 | 2026-03-02T09:07:30.849Z | ++ ipadm show-addr igb0/dhcp -po ADDR |
| 97 | 2026-03-02T09:07:30.849Z | ++ sed 's#/.*##g' |
| 98 | 2026-03-02T09:07:30.849Z | + server=10.151.6.100 |
| 99 | 2026-03-02T09:07:30.849Z | + pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100 |
| 100 | 2026-03-02T09:07:30.874Z | ++ pfexec dladm create-vnic -l igb0 dummy0 |
| 101 | 2026-03-02T09:07:30.874Z | + error= |
| 102 | 2026-03-02T09:07:30.874Z | + RUST_LOG=debug |
| 103 | 2026-03-02T09:07:30.874Z | + pfexec ./falcon-lab run trio-unnumbered |
| 104 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 DEBG using default route interface igb0 |
| 105 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 DEBG using default route interface igb0 |
| 106 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 DEBG using default route interface igb0 |
| 107 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon) |
| 108 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 INFO starting preflight for deployment mgtriou |
| 109 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 INFO propolis-server binary not found |
| 110 | 2026-03-02T09:07:30.898Z | Mar 02 09:07:30.870 INFO downloading propolis server rev ff31c527515d65886e599fc07eb41240aeb767c6, writing to .falcon/bin/propolis-server |
| 111 | 2026-03-02T09:07:34.691Z | Mar 02 09:07:34.679 INFO ovmf fd not found |
| 112 | 2026-03-02T09:07:34.691Z | Mar 02 09:07:34.679 INFO downloading ovmf |
| 113 | 2026-03-02T09:07:35.054Z | Mar 02 09:07:35.042 INFO base image for helios-2.9 does not exist, attempting to install |
| 114 | 2026-03-02T09:07:35.054Z | Mar 02 09:07:35.042 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz |
| 115 | 2026-03-02T09:08:03.200Z | Mar 02 09:08:03.188 INFO extracting image to /tmp/helios-2.9_0.raw |
| 116 | 2026-03-02T09:08:36.570Z | Mar 02 09:08:36.557 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896 |
| 117 | 2026-03-02T09:08:36.615Z | Mar 02 09:08:36.603 INFO copying image data to zvol |
| 118 | 2026-03-02T09:08:46.853Z | Mar 02 09:08:46.840 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base |
| 119 | 2026-03-02T09:08:50.305Z | Mar 02 09:08:50.293 INFO base image for debian-13.2 does not exist, attempting to install |
| 120 | 2026-03-02T09:08:50.305Z | Mar 02 09:08:50.293 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz |
| 121 | 2026-03-02T09:09:10.152Z | Mar 02 09:09:10.139 INFO extracting image to /tmp/debian-13.2_0.raw |
| 122 | 2026-03-02T09:09:30.941Z | Mar 02 09:09:30.929 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568 |
| 123 | 2026-03-02T09:09:30.965Z | Mar 02 09:09:30.952 INFO copying image data to zvol |
| 124 | 2026-03-02T09:09:37.225Z | Mar 02 09:09:37.213 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base |
| 125 | 2026-03-02T09:09:40.656Z | Mar 02 09:09:40.644 INFO base image for eos-4.35 does not exist, attempting to install |
| 126 | 2026-03-02T09:09:40.656Z | Mar 02 09:09:40.644 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz |
| 127 | 2026-03-02T09:10:23.284Z | Mar 02 09:10:23.271 INFO extracting image to /tmp/eos-4.35_0.raw |
| 128 | 2026-03-02T09:11:46.073Z | Mar 02 09:11:46.060 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576 |
| 129 | 2026-03-02T09:11:46.097Z | Mar 02 09:11:46.083 INFO copying image data to zvol |
| 130 | 2026-03-02T09:12:33.829Z | Mar 02 09:12:33.817 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base |
| 131 | 2026-03-02T09:12:37.244Z | Mar 02 09:12:37.232 INFO creating links |
| 132 | 2026-03-02T09:12:37.245Z | Mar 02 09:12:37.233 DEBG destroying link mgtriou_ox_sn_vnic0 |
| 133 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.233 DEBG destroying link mgtriou_ox_sn_sim0 |
| 134 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.233 INFO creating simnet link 'mgtriou_ox_sn_sim0' |
| 135 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.235 INFO creating vnic link 'mgtriou_ox_sn_vnic0' |
| 136 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.243 DEBG link pair created |
| 137 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.243 DEBG destroying link mgtriou_cr1_vn_vnic0 |
| 138 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.243 DEBG destroying link mgtriou_cr1_vn_sim0 |
| 139 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.243 INFO creating simnet link 'mgtriou_cr1_vn_sim0' |
| 140 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.244 INFO creating vnic link 'mgtriou_cr1_vn_vnic0' |
| 141 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.251 DEBG link pair created |
| 142 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.251 DEBG destroying link mgtriou_ox_sn_vnic1 |
| 143 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.251 DEBG destroying link mgtriou_ox_sn_sim1 |
| 144 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.251 INFO creating simnet link 'mgtriou_ox_sn_sim1' |
| 145 | 2026-03-02T09:12:37.269Z | Mar 02 09:12:37.252 INFO creating vnic link 'mgtriou_ox_sn_vnic1' |
| 146 | 2026-03-02T09:12:37.293Z | Mar 02 09:12:37.259 DEBG link pair created |
| 147 | 2026-03-02T09:12:37.293Z | Mar 02 09:12:37.259 DEBG destroying link mgtriou_cr2_vn_vnic0 |
| 148 | 2026-03-02T09:12:37.293Z | Mar 02 09:12:37.259 DEBG destroying link mgtriou_cr2_vn_sim0 |
| 149 | 2026-03-02T09:12:37.293Z | Mar 02 09:12:37.259 INFO creating simnet link 'mgtriou_cr2_vn_sim0' |
| 150 | 2026-03-02T09:12:37.293Z | Mar 02 09:12:37.260 INFO creating vnic link 'mgtriou_cr2_vn_vnic0' |
| 151 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.267 DEBG link pair created |
| 152 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.267 INFO creating external links |
| 153 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.267 DEBG destroying external link mgtriou_ox_vn_vnic2 |
| 154 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.267 INFO creating external link mgtriou_ox_vn_vnic2 |
| 155 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.269 DEBG external link mgtriou_ox_vn_vnic2@igb0 created |
| 156 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.269 DEBG destroying external link mgtriou_cr1_vn_vnic1 |
| 157 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.269 INFO creating external link mgtriou_cr1_vn_vnic1 |
| 158 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.270 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created |
| 159 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.270 DEBG destroying external link mgtriou_cr2_vn_vnic1 |
| 160 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.270 INFO creating external link mgtriou_cr2_vn_vnic1 |
| 161 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.271 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created |
| 162 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.272 INFO creating nodes |
| 163 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.272 INFO ox: launching node |
| 164 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.274 INFO cr1: launching node |
| 165 | 2026-03-02T09:12:37.294Z | Mar 02 09:12:37.276 INFO cr2: launching node |
| 166 | 2026-03-02T09:12:37.318Z | Mar 02 09:12:37.290 INFO launched instance ox with pid 851 on port 56958 |
| 167 | 2026-03-02T09:12:37.318Z | Mar 02 09:12:37.290 INFO ox: instance ensure |
| 168 | 2026-03-02T09:12:37.318Z | Mar 02 09:12:37.290 INFO launched instance cr1 with pid 852 on port 33777 |
| 169 | 2026-03-02T09:12:37.318Z | Mar 02 09:12:37.290 INFO cr1: instance ensure |
| 170 | 2026-03-02T09:12:37.318Z | Mar 02 09:12:37.291 INFO launched instance cr2 with pid 853 on port 59028 |
| 171 | 2026-03-02T09:12:37.318Z | Mar 02 09:12:37.291 INFO cr2: instance ensure |
| 172 | 2026-03-02T09:12:39.615Z | Mar 02 09:12:39.603 INFO cr2: instance run |
| 173 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.603 DEBG [sc] cr2: starting |
| 174 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.603 DEBG [sc] cr2: connecting to [::1]:59028 |
| 175 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.604 DEBG [sc] cr2 waiting for prompt |
| 176 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.615 INFO cr1: instance run |
| 177 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.616 DEBG [sc] cr1: starting |
| 178 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.616 DEBG [sc] cr1: connecting to [::1]:33777 |
| 179 | 2026-03-02T09:12:39.639Z | Mar 02 09:12:39.617 DEBG [sc] cr1 waiting for prompt |
| 180 | 2026-03-02T09:12:39.664Z | Mar 02 09:12:39.647 INFO ox: instance run |
| 181 | 2026-03-02T09:12:39.664Z | Mar 02 09:12:39.648 DEBG [sc] ox: starting |
| 182 | 2026-03-02T09:12:39.664Z | Mar 02 09:12:39.648 DEBG [sc] ox: connecting to [::1]:56958 |
| 183 | 2026-03-02T09:12:39.664Z | Mar 02 09:12:39.648 DEBG [sc] ox waiting for prompt |
| 184 | 2026-03-02T09:12:56.813Z | Mar 02 09:12:56.801 DEBG [sc] cr1: logging in |
| 185 | 2026-03-02T09:12:57.169Z | Mar 02 09:12:57.157 INFO cr1: mounting /opt/cargo-bay |
| 186 | 2026-03-02T09:12:57.169Z | Mar 02 09:12:57.157 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull` |
| 187 | 2026-03-02T09:12:57.194Z | Mar 02 09:12:57.180 DEBG [sc] cr1: executing command `cd` |
| 188 | 2026-03-02T09:12:57.218Z | Mar 02 09:12:57.191 INFO cr1: finished mounting /opt/cargo-bay |
| 189 | 2026-03-02T09:12:57.218Z | Mar 02 09:12:57.191 DEBG [sc] cr1: executing command `hostname cr1` |
| 190 | 2026-03-02T09:12:57.218Z | Mar 02 09:12:57.201 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename` |
| 191 | 2026-03-02T09:12:57.243Z | Mar 02 09:12:57.212 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts` |
| 192 | 2026-03-02T09:12:57.243Z | Mar 02 09:12:57.224 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts` |
| 193 | 2026-03-02T09:12:57.267Z | Mar 02 09:12:57.235 INFO cr1: logging out |
| 194 | 2026-03-02T09:12:59.313Z | Mar 02 09:12:59.301 INFO cr1: logged out |
| 195 | 2026-03-02T09:13:00.299Z | Mar 02 09:13:00.287 DEBG [sc] ox: logging in |
| 196 | 2026-03-02T09:13:00.490Z | Mar 02 09:13:00.478 INFO ox: mounting /opt/cargo-bay |
| 197 | 2026-03-02T09:13:00.490Z | Mar 02 09:13:00.478 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull` |
| 198 | 2026-03-02T09:13:01.216Z | Mar 02 09:13:01.204 DEBG [sc] cr2: logging in |
| 199 | 2026-03-02T09:13:01.640Z | Mar 02 09:13:01.628 INFO cr2: mounting /opt/cargo-bay |
| 200 | 2026-03-02T09:13:01.640Z | Mar 02 09:13:01.628 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull` |
| 201 | 2026-03-02T09:13:01.665Z | Mar 02 09:13:01.650 DEBG [sc] cr2: executing command `cd` |
| 202 | 2026-03-02T09:13:01.689Z | Mar 02 09:13:01.661 INFO cr2: finished mounting /opt/cargo-bay |
| 203 | 2026-03-02T09:13:01.689Z | Mar 02 09:13:01.661 DEBG [sc] cr2: executing command `hostname cr2` |
| 204 | 2026-03-02T09:13:01.689Z | Mar 02 09:13:01.665 DEBG [sc] ox: executing command `cd` |
| 205 | 2026-03-02T09:13:01.689Z | Mar 02 09:13:01.672 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename` |
| 206 | 2026-03-02T09:13:01.689Z | Mar 02 09:13:01.676 INFO ox: finished mounting /opt/cargo-bay |
| 207 | 2026-03-02T09:13:01.689Z | Mar 02 09:13:01.676 DEBG [sc] ox: executing command `hostname ox` |
| 208 | 2026-03-02T09:13:01.713Z | Mar 02 09:13:01.683 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts` |
| 209 | 2026-03-02T09:13:01.713Z | Mar 02 09:13:01.687 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename` |
| 210 | 2026-03-02T09:13:01.713Z | Mar 02 09:13:01.694 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts` |
| 211 | 2026-03-02T09:13:01.713Z | Mar 02 09:13:01.699 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts` |
| 212 | 2026-03-02T09:13:01.738Z | Mar 02 09:13:01.705 INFO cr2: logging out |
| 213 | 2026-03-02T09:13:01.738Z | Mar 02 09:13:01.710 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts` |
| 214 | 2026-03-02T09:13:01.738Z | Mar 02 09:13:01.721 INFO ox: logging out |
| 215 | 2026-03-02T09:13:01.850Z | Mar 02 09:13:01.838 INFO ox: logged out |
| 216 | 2026-03-02T09:13:04.671Z | Mar 02 09:13:04.659 INFO cr2: logged out |
| 217 | 2026-03-02T09:13:04.696Z | Mar 02 09:13:04.660 DEBG [sc] ox: starting |
| 218 | 2026-03-02T09:13:04.696Z | Mar 02 09:13:04.660 DEBG [sc] ox: connecting to [::1]:56958 |
| 219 | 2026-03-02T09:13:04.696Z | Mar 02 09:13:04.660 DEBG [sc] ox waiting for prompt |
| 220 | 2026-03-02T09:13:04.696Z | Mar 02 09:13:04.672 DEBG [sc] ox: logging in |
| 221 | 2026-03-02T09:13:04.771Z | Mar 02 09:13:04.759 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp` |
| 222 | 2026-03-02T09:13:11.324Z | Mar 02 09:13:11.312 DEBG [sc] ox: starting |
| 223 | 2026-03-02T09:13:11.324Z | Mar 02 09:13:11.312 DEBG [sc] ox: connecting to [::1]:56958 |
| 224 | 2026-03-02T09:13:11.348Z | Mar 02 09:13:11.313 DEBG [sc] ox waiting for prompt |
| 225 | 2026-03-02T09:13:11.349Z | Mar 02 09:13:11.323 DEBG [sc] ox: logging in |
| 226 | 2026-03-02T09:13:11.423Z | Mar 02 09:13:11.411 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf` |
| 227 | 2026-03-02T09:13:11.499Z | Mar 02 09:13:11.488 DEBG [sc] ox: starting |
| 228 | 2026-03-02T09:13:11.499Z | Mar 02 09:13:11.488 DEBG [sc] ox: connecting to [::1]:56958 |
| 229 | 2026-03-02T09:13:11.523Z | Mar 02 09:13:11.488 DEBG [sc] ox waiting for prompt |
| 230 | 2026-03-02T09:13:11.523Z | Mar 02 09:13:11.498 DEBG [sc] ox: logging in |
| 231 | 2026-03-02T09:13:11.587Z | Mar 02 09:13:11.575 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr` |
| 232 | 2026-03-02T09:13:11.654Z | Mar 02 09:13:11.642 INFO cr1: installing frr |
| 233 | 2026-03-02T09:13:11.654Z | Mar 02 09:13:11.642 INFO ox: setting up npuvm |
| 234 | 2026-03-02T09:13:11.654Z | Mar 02 09:13:11.642 INFO waiting for ceos to initialize |
| 235 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.642 DEBG [sc] ox: starting |
| 236 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.642 DEBG [sc] ox: connecting to [::1]:56958 |
| 237 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.642 DEBG [sc] cr1: starting |
| 238 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.642 DEBG [sc] cr2: starting |
| 239 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.642 DEBG [sc] cr1: connecting to [::1]:33777 |
| 240 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.642 DEBG [sc] cr2: connecting to [::1]:59028 |
| 241 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.643 DEBG [sc] ox waiting for prompt |
| 242 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.643 DEBG [sc] cr2 waiting for prompt |
| 243 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.643 DEBG [sc] cr1 waiting for prompt |
| 244 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.653 DEBG [sc] ox: logging in |
| 245 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.654 DEBG [sc] cr2: logging in |
| 246 | 2026-03-02T09:13:11.679Z | Mar 02 09:13:11.654 DEBG [sc] cr1: logging in |
| 247 | 2026-03-02T09:13:11.753Z | Mar 02 09:13:11.741 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm` |
| 248 | 2026-03-02T09:13:11.821Z | Mar 02 09:13:11.809 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'` |
| 249 | 2026-03-02T09:13:12.038Z | Mar 02 09:13:12.026 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr` |
| 250 | 2026-03-02T09:13:14.076Z | Mar 02 09:13:14.064 INFO cr2: executing eos script show version |
| 251 | 2026-03-02T09:13:14.076Z | Mar 02 09:13:14.064 DEBG [sc] cr2: starting |
| 252 | 2026-03-02T09:13:14.076Z | Mar 02 09:13:14.064 DEBG [sc] cr2: connecting to [::1]:59028 |
| 253 | 2026-03-02T09:13:14.100Z | Mar 02 09:13:14.065 DEBG [sc] cr2 waiting for prompt |
| 254 | 2026-03-02T09:13:14.100Z | Mar 02 09:13:14.075 DEBG [sc] cr2: logging in |
| 255 | 2026-03-02T09:13:14.230Z | Mar 02 09:13:14.218 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'` |
| 256 | 2026-03-02T09:13:17.192Z | Mar 02 09:13:17.180 DEBG [sc] ox: starting |
| 257 | 2026-03-02T09:13:17.192Z | Mar 02 09:13:17.180 DEBG [sc] ox: connecting to [::1]:56958 |
| 258 | 2026-03-02T09:13:17.216Z | Mar 02 09:13:17.180 DEBG [sc] ox waiting for prompt |
| 259 | 2026-03-02T09:13:17.216Z | Mar 02 09:13:17.190 DEBG [sc] ox: logging in |
| 260 | 2026-03-02T09:13:17.291Z | Mar 02 09:13:17.279 DEBG [sc] ox: executing command `chmod +x npuvm` |
| 261 | 2026-03-02T09:13:17.357Z | Mar 02 09:13:17.346 DEBG [sc] ox: starting |
| 262 | 2026-03-02T09:13:17.357Z | Mar 02 09:13:17.346 DEBG [sc] ox: connecting to [::1]:56958 |
| 263 | 2026-03-02T09:13:17.381Z | Mar 02 09:13:17.346 DEBG [sc] ox waiting for prompt |
| 264 | 2026-03-02T09:13:17.381Z | Mar 02 09:13:17.356 DEBG [sc] ox: logging in |
| 265 | 2026-03-02T09:13:17.446Z | Mar 02 09:13:17.434 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 ` |
| 266 | 2026-03-02T09:13:22.263Z | Mar 02 09:13:22.251 DEBG [sc] cr2: starting |
| 267 | 2026-03-02T09:13:22.263Z | Mar 02 09:13:22.251 DEBG [sc] cr2: connecting to [::1]:59028 |
| 268 | 2026-03-02T09:13:22.287Z | Mar 02 09:13:22.252 DEBG [sc] cr2 waiting for prompt |
| 269 | 2026-03-02T09:13:22.287Z | Mar 02 09:13:22.262 DEBG [sc] cr2: logging in |
| 270 | 2026-03-02T09:13:22.416Z | Mar 02 09:13:22.404 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'` |
| 271 | 2026-03-02T09:13:24.523Z | Mar 02 09:13:24.512 INFO cr2: executing eos script show version |
| 272 | 2026-03-02T09:13:24.523Z | Mar 02 09:13:24.512 DEBG [sc] cr2: starting |
| 273 | 2026-03-02T09:13:24.523Z | Mar 02 09:13:24.512 DEBG [sc] cr2: connecting to [::1]:59028 |
| 274 | 2026-03-02T09:13:24.548Z | Mar 02 09:13:24.512 DEBG [sc] cr2 waiting for prompt |
| 275 | 2026-03-02T09:13:24.548Z | Mar 02 09:13:24.522 DEBG [sc] cr2: logging in |
| 276 | 2026-03-02T09:13:24.679Z | Mar 02 09:13:24.667 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'` |
| 277 | 2026-03-02T09:13:27.059Z | Mar 02 09:13:27.047 INFO cr2: executing eos script |
| 278 | 2026-03-02T09:13:27.059Z | enable |
| 279 | 2026-03-02T09:13:27.059Z | configure |
| 280 | 2026-03-02T09:13:27.059Z | ipv6 unicast-routing |
| 281 | 2026-03-02T09:13:27.060Z | ip routing ipv6 interfaces |
| 282 | 2026-03-02T09:13:27.060Z | ip routing |
| 283 | 2026-03-02T09:13:27.060Z | ip route 1.2.3.0/24 null0 |
| 284 | 2026-03-02T09:13:27.060Z | ipv6 route fd99::/64 null0 |
| 285 | 2026-03-02T09:13:27.060Z | interface et1 |
| 286 | 2026-03-02T09:13:27.060Z | no switchport |
| 287 | 2026-03-02T09:13:27.060Z | ipv6 enable |
| 288 | 2026-03-02T09:13:27.060Z | |
| 289 | 2026-03-02T09:13:27.060Z | router bgp 45 |
| 290 | 2026-03-02T09:13:27.060Z | router-id 1.2.3.1 |
| 291 | 2026-03-02T09:13:27.060Z | no bgp default ipv4-unicast |
| 292 | 2026-03-02T09:13:27.060Z | timers bgp 2 6 |
| 293 | 2026-03-02T09:13:27.060Z | neighbor ebgp peer group |
| 294 | 2026-03-02T09:13:27.060Z | neighbor ebgp remote-as 33 |
| 295 | 2026-03-02T09:13:27.060Z | neighbor interface Et1 peer-group ebgp |
| 296 | 2026-03-02T09:13:27.060Z | address-family ipv4 |
| 297 | 2026-03-02T09:13:27.060Z | neighbor ebgp activate |
| 298 | 2026-03-02T09:13:27.060Z | neighbor ebgp next-hop address-family ipv6 originate |
| 299 | 2026-03-02T09:13:27.060Z | network 1.2.3.0/24 |
| 300 | 2026-03-02T09:13:27.060Z | exit |
| 301 | 2026-03-02T09:13:27.060Z | address-family ipv6 |
| 302 | 2026-03-02T09:13:27.060Z | neighbor ebgp activate |
| 303 | 2026-03-02T09:13:27.060Z | neighbor ebgp next-hop address-family ipv6 originate |
| 304 | 2026-03-02T09:13:27.060Z | network fd99::/64 |
| 305 | 2026-03-02T09:13:27.060Z | exit |
| 306 | 2026-03-02T09:13:27.060Z | exit |
| 307 | 2026-03-02T09:13:27.060Z | |
| 308 | 2026-03-02T09:13:27.060Z | Mar 02 09:13:27.047 DEBG [sc] cr2: starting |
| 309 | 2026-03-02T09:13:27.060Z | Mar 02 09:13:27.047 DEBG [sc] cr2: connecting to [::1]:59028 |
| 310 | 2026-03-02T09:13:27.085Z | Mar 02 09:13:27.048 DEBG [sc] cr2 waiting for prompt |
| 311 | 2026-03-02T09:13:27.085Z | Mar 02 09:13:27.058 DEBG [sc] cr2: logging in |
| 312 | 2026-03-02T09:13:27.224Z | Mar 02 09:13:27.212 DEBG [sc] cr2: executing command `docker exec ceos Cli -c ' |
| 313 | 2026-03-02T09:13:27.224Z | enable |
| 314 | 2026-03-02T09:13:27.224Z | configure |
| 315 | 2026-03-02T09:13:27.224Z | ipv6 unicast-routing |
| 316 | 2026-03-02T09:13:27.224Z | ip routing ipv6 interfaces |
| 317 | 2026-03-02T09:13:27.224Z | ip routing |
| 318 | 2026-03-02T09:13:27.224Z | ip route 1.2.3.0/24 null0 |
| 319 | 2026-03-02T09:13:27.224Z | ipv6 route fd99::/64 null0 |
| 320 | 2026-03-02T09:13:27.224Z | interface et1 |
| 321 | 2026-03-02T09:13:27.224Z | no switchport |
| 322 | 2026-03-02T09:13:27.224Z | ipv6 enable |
| 323 | 2026-03-02T09:13:27.224Z | |
| 324 | 2026-03-02T09:13:27.224Z | router bgp 45 |
| 325 | 2026-03-02T09:13:27.224Z | router-id 1.2.3.1 |
| 326 | 2026-03-02T09:13:27.224Z | no bgp default ipv4-unicast |
| 327 | 2026-03-02T09:13:27.224Z | timers bgp 2 6 |
| 328 | 2026-03-02T09:13:27.225Z | neighbor ebgp peer group |
| 329 | 2026-03-02T09:13:27.225Z | neighbor ebgp remote-as 33 |
| 330 | 2026-03-02T09:13:27.225Z | neighbor interface Et1 peer-group ebgp |
| 331 | 2026-03-02T09:13:27.225Z | address-family ipv4 |
| 332 | 2026-03-02T09:13:27.225Z | neighbor ebgp activate |
| 333 | 2026-03-02T09:13:27.225Z | neighbor ebgp next-hop address-family ipv6 originate |
| 334 | 2026-03-02T09:13:27.225Z | network 1.2.3.0/24 |
| 335 | 2026-03-02T09:13:27.225Z | exit |
| 336 | 2026-03-02T09:13:27.225Z | address-family ipv6 |
| 337 | 2026-03-02T09:13:27.225Z | neighbor ebgp activate |
| 338 | 2026-03-02T09:13:27.225Z | neighbor ebgp next-hop address-family ipv6 originate |
| 339 | 2026-03-02T09:13:27.225Z | network fd99::/64 |
| 340 | 2026-03-02T09:13:27.225Z | exit |
| 341 | 2026-03-02T09:13:27.225Z | exit |
| 342 | 2026-03-02T09:13:27.225Z | '` |
| 343 | 2026-03-02T09:13:49.463Z | Mar 02 09:13:49.451 INFO cr1: enabling frr daemon bgpd |
| 344 | 2026-03-02T09:13:49.463Z | Mar 02 09:13:49.451 DEBG [sc] cr1: starting |
| 345 | 2026-03-02T09:13:49.463Z | Mar 02 09:13:49.451 DEBG [sc] cr1: connecting to [::1]:33777 |
| 346 | 2026-03-02T09:13:49.488Z | Mar 02 09:13:49.452 DEBG [sc] cr1 waiting for prompt |
| 347 | 2026-03-02T09:13:49.488Z | Mar 02 09:13:49.462 DEBG [sc] cr1: logging in |
| 348 | 2026-03-02T09:13:49.608Z | Mar 02 09:13:49.596 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons` |
| 349 | 2026-03-02T09:13:51.677Z | Mar 02 09:13:51.664 DEBG [sc] cr1: starting |
| 350 | 2026-03-02T09:13:51.677Z | Mar 02 09:13:51.664 DEBG [sc] cr1: connecting to [::1]:33777 |
| 351 | 2026-03-02T09:13:51.701Z | Mar 02 09:13:51.664 DEBG [sc] cr1 waiting for prompt |
| 352 | 2026-03-02T09:13:51.702Z | Mar 02 09:13:51.674 DEBG [sc] cr1: logging in |
| 353 | 2026-03-02T09:13:51.854Z | Mar 02 09:13:51.842 DEBG [sc] cr1: executing command `systemctl restart frr` |
| 354 | 2026-03-02T09:13:59.485Z | Mar 02 09:13:59.473 INFO cr1: executing frr script |
| 355 | 2026-03-02T09:13:59.485Z | configure |
| 356 | 2026-03-02T09:13:59.485Z | ip forwarding |
| 357 | 2026-03-02T09:13:59.485Z | ipv6 forwarding |
| 358 | 2026-03-02T09:13:59.485Z | ip route 1.2.3.0/24 null0 |
| 359 | 2026-03-02T09:13:59.485Z | ipv6 route fd99::/64 null0 |
| 360 | 2026-03-02T09:13:59.485Z | route-map PERMIT-ALL permit 10 |
| 361 | 2026-03-02T09:13:59.485Z | router bgp 44 |
| 362 | 2026-03-02T09:13:59.485Z | timers bgp 2 6 |
| 363 | 2026-03-02T09:13:59.485Z | neighbor enp0s8 interface remote-as external |
| 364 | 2026-03-02T09:13:59.485Z | neighbor enp0s8 timers connect 1 |
| 365 | 2026-03-02T09:13:59.485Z | address-family ipv4 unicast |
| 366 | 2026-03-02T09:13:59.485Z | network 1.2.3.0/24 |
| 367 | 2026-03-02T09:13:59.486Z | neighbor enp0s8 activate |
| 368 | 2026-03-02T09:13:59.487Z | neighbor enp0s8 route-map PERMIT-ALL out |
| 369 | 2026-03-02T09:13:59.487Z | neighbor enp0s8 route-map PERMIT-ALL in |
| 370 | 2026-03-02T09:13:59.487Z | exit-address-family |
| 371 | 2026-03-02T09:13:59.487Z | address-family ipv6 unicast |
| 372 | 2026-03-02T09:13:59.487Z | network fd99::/64 |
| 373 | 2026-03-02T09:13:59.487Z | neighbor enp0s8 activate |
| 374 | 2026-03-02T09:13:59.487Z | neighbor enp0s8 route-map PERMIT-ALL out |
| 375 | 2026-03-02T09:13:59.487Z | neighbor enp0s8 route-map PERMIT-ALL in |
| 376 | 2026-03-02T09:13:59.487Z | exit-address-family |
| 377 | 2026-03-02T09:13:59.487Z | exit |
| 378 | 2026-03-02T09:13:59.487Z | |
| 379 | 2026-03-02T09:13:59.487Z | Mar 02 09:13:59.473 DEBG [sc] cr1: starting |
| 380 | 2026-03-02T09:13:59.487Z | Mar 02 09:13:59.473 DEBG [sc] cr1: connecting to [::1]:33777 |
| 381 | 2026-03-02T09:13:59.511Z | Mar 02 09:13:59.474 DEBG [sc] cr1 waiting for prompt |
| 382 | 2026-03-02T09:13:59.511Z | Mar 02 09:13:59.485 DEBG [sc] cr1: logging in |
| 383 | 2026-03-02T09:13:59.641Z | Mar 02 09:13:59.629 DEBG [sc] cr1: executing command `vtysh -c '' -c ' configure' -c ' ip forwarding' -c ' ipv6 forwarding' -c ' ip route 1.2.3.0/24 null0' -c ' ipv6 route fd99::/64 null0' -c ' route-map PERMIT-ALL permit 10' -c ' router bgp 44' -c ' timers bgp 2 6' -c ' neighbor enp0s8 interface remote-as external' -c ' neighbor enp0s8 timers connect 1 ' -c ' address-family ipv4 unicast' -c ' network 1.2.3.0/24' -c ' neighbor enp0s8 activate' -c ' neighbor enp0s8 route-map PERMIT-ALL out' -c ' neighbor enp0s8 route-map PERMIT-ALL in' -c ' exit-address-family' -c ' address-family ipv6 unicast' -c ' network fd99::/64' -c ' neighbor enp0s8 activate' -c ' neighbor enp0s8 route-map PERMIT-ALL out' -c ' neighbor enp0s8 route-map PERMIT-ALL in' -c ' exit-address-family' -c ' exit' -c ' '` |
| 384 | 2026-03-02T09:14:50.230Z | Mar 02 09:14:50.218 DEBG [sc] ox: starting |
| 385 | 2026-03-02T09:14:50.230Z | Mar 02 09:14:50.218 DEBG [sc] ox: connecting to [::1]:56958 |
| 386 | 2026-03-02T09:14:50.254Z | Mar 02 09:14:50.219 DEBG [sc] ox waiting for prompt |
| 387 | 2026-03-02T09:14:50.254Z | Mar 02 09:14:50.229 DEBG [sc] ox: logging in |
| 388 | 2026-03-02T09:14:50.318Z | Mar 02 09:14:50.306 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so` |
| 389 | 2026-03-02T09:14:50.533Z | Mar 02 09:14:50.521 DEBG [sc] ox: starting |
| 390 | 2026-03-02T09:14:50.533Z | Mar 02 09:14:50.521 DEBG [sc] ox: connecting to [::1]:56958 |
| 391 | 2026-03-02T09:14:50.557Z | Mar 02 09:14:50.521 DEBG [sc] ox waiting for prompt |
| 392 | 2026-03-02T09:14:50.557Z | Mar 02 09:14:50.533 DEBG [sc] ox: logging in |
| 393 | 2026-03-02T09:14:50.622Z | Mar 02 09:14:50.610 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link` |
| 394 | 2026-03-02T09:14:51.647Z | Mar 02 09:14:51.635 DEBG [sc] ox: starting |
| 395 | 2026-03-02T09:14:51.647Z | Mar 02 09:14:51.635 DEBG [sc] ox: connecting to [::1]:56958 |
| 396 | 2026-03-02T09:14:51.671Z | Mar 02 09:14:51.635 DEBG [sc] ox waiting for prompt |
| 397 | 2026-03-02T09:14:51.671Z | Mar 02 09:14:51.635 DEBG [sc] ox: logging in |
| 398 | 2026-03-02T09:14:51.725Z | Mar 02 09:14:51.713 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link` |
| 399 | 2026-03-02T09:14:51.779Z | Mar 02 09:14:51.767 DEBG [sc] ox: starting |
| 400 | 2026-03-02T09:14:51.779Z | Mar 02 09:14:51.767 DEBG [sc] ox: connecting to [::1]:56958 |
| 401 | 2026-03-02T09:14:51.803Z | Mar 02 09:14:51.768 DEBG [sc] ox waiting for prompt |
| 402 | 2026-03-02T09:14:51.804Z | Mar 02 09:14:51.779 DEBG [sc] ox: logging in |
| 403 | 2026-03-02T09:14:51.869Z | Mar 02 09:14:51.857 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll` |
| 404 | 2026-03-02T09:14:51.925Z | Mar 02 09:14:51.913 DEBG [sc] ox: starting |
| 405 | 2026-03-02T09:14:51.925Z | Mar 02 09:14:51.913 DEBG [sc] ox: connecting to [::1]:56958 |
| 406 | 2026-03-02T09:14:51.949Z | Mar 02 09:14:51.914 DEBG [sc] ox waiting for prompt |
| 407 | 2026-03-02T09:14:51.949Z | Mar 02 09:14:51.923 DEBG [sc] ox: logging in |
| 408 | 2026-03-02T09:14:52.013Z | Mar 02 09:14:52.001 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr` |
| 409 | 2026-03-02T09:14:52.067Z | Mar 02 09:14:52.056 DEBG [sc] ox: starting |
| 410 | 2026-03-02T09:14:52.068Z | Mar 02 09:14:52.056 DEBG [sc] ox: connecting to [::1]:56958 |
| 411 | 2026-03-02T09:14:52.092Z | Mar 02 09:14:52.056 DEBG [sc] ox waiting for prompt |
| 412 | 2026-03-02T09:14:52.092Z | Mar 02 09:14:52.067 DEBG [sc] ox: logging in |
| 413 | 2026-03-02T09:14:52.155Z | Mar 02 09:14:52.144 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link` |
| 414 | 2026-03-02T09:14:52.211Z | Mar 02 09:14:52.200 DEBG [sc] ox: starting |
| 415 | 2026-03-02T09:14:52.211Z | Mar 02 09:14:52.200 DEBG [sc] ox: connecting to [::1]:56958 |
| 416 | 2026-03-02T09:14:52.236Z | Mar 02 09:14:52.200 DEBG [sc] ox waiting for prompt |
| 417 | 2026-03-02T09:14:52.236Z | Mar 02 09:14:52.211 DEBG [sc] ox: logging in |
| 418 | 2026-03-02T09:14:52.299Z | Mar 02 09:14:52.287 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll` |
| 419 | 2026-03-02T09:14:52.366Z | Mar 02 09:14:52.354 DEBG [sc] ox: starting |
| 420 | 2026-03-02T09:14:52.366Z | Mar 02 09:14:52.354 DEBG [sc] ox: connecting to [::1]:56958 |
| 421 | 2026-03-02T09:14:52.390Z | Mar 02 09:14:52.355 DEBG [sc] ox waiting for prompt |
| 422 | 2026-03-02T09:14:52.390Z | Mar 02 09:14:52.365 DEBG [sc] ox: logging in |
| 423 | 2026-03-02T09:14:52.453Z | Mar 02 09:14:52.441 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr` |
| 424 | 2026-03-02T09:14:52.520Z | Mar 02 09:14:52.508 DEBG [sc] ox: starting |
| 425 | 2026-03-02T09:14:52.520Z | Mar 02 09:14:52.508 DEBG [sc] ox: connecting to [::1]:56958 |
| 426 | 2026-03-02T09:14:52.544Z | Mar 02 09:14:52.509 DEBG [sc] ox waiting for prompt |
| 427 | 2026-03-02T09:14:52.544Z | Mar 02 09:14:52.519 DEBG [sc] ox: logging in |
| 428 | 2026-03-02T09:14:52.618Z | Mar 02 09:14:52.606 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &` |
| 429 | 2026-03-02T09:14:52.685Z | Mar 02 09:14:52.673 DEBG [sc] ox: starting |
| 430 | 2026-03-02T09:14:52.685Z | Mar 02 09:14:52.673 DEBG [sc] ox: connecting to [::1]:56958 |
| 431 | 2026-03-02T09:14:52.709Z | Mar 02 09:14:52.673 DEBG [sc] ox waiting for prompt |
| 432 | 2026-03-02T09:14:52.709Z | Mar 02 09:14:52.684 DEBG [sc] ox: logging in |
| 433 | 2026-03-02T09:14:52.785Z | Mar 02 09:14:52.773 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &` |
| 434 | 2026-03-02T09:14:52.854Z | Mar 02 09:14:52.842 INFO adding BGP router to mgd |
| 435 | 2026-03-02T09:14:54.873Z | Mar 02 09:14:54.861 INFO cr1: executing frr script show ip bgp json |
| 436 | 2026-03-02T09:14:54.873Z | Mar 02 09:14:54.861 DEBG [sc] cr1: starting |
| 437 | 2026-03-02T09:14:54.873Z | Mar 02 09:14:54.861 DEBG [sc] cr1: connecting to [::1]:33777 |
| 438 | 2026-03-02T09:14:54.898Z | Mar 02 09:14:54.862 DEBG [sc] cr1 waiting for prompt |
| 439 | 2026-03-02T09:14:54.898Z | Mar 02 09:14:54.873 DEBG [sc] cr1: logging in |
| 440 | 2026-03-02T09:14:55.215Z | Mar 02 09:14:55.204 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'` |
| 441 | 2026-03-02T09:14:57.345Z | Mar 02 09:14:57.333 INFO cr1: executing frr script show bgp json |
| 442 | 2026-03-02T09:14:57.370Z | Mar 02 09:14:57.333 DEBG [sc] cr1: starting |
| 443 | 2026-03-02T09:14:57.370Z | Mar 02 09:14:57.333 DEBG [sc] cr1: connecting to [::1]:33777 |
| 444 | 2026-03-02T09:14:57.370Z | Mar 02 09:14:57.334 DEBG [sc] cr1 waiting for prompt |
| 445 | 2026-03-02T09:14:57.370Z | Mar 02 09:14:57.345 DEBG [sc] cr1: logging in |
| 446 | 2026-03-02T09:14:57.490Z | Mar 02 09:14:57.478 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'` |
| 447 | 2026-03-02T09:14:59.622Z | Mar 02 09:14:59.610 INFO cr2: executing eos script show ip bgp | json |
| 448 | 2026-03-02T09:14:59.622Z | Mar 02 09:14:59.610 DEBG [sc] cr2: starting |
| 449 | 2026-03-02T09:14:59.622Z | Mar 02 09:14:59.610 DEBG [sc] cr2: connecting to [::1]:59028 |
| 450 | 2026-03-02T09:14:59.646Z | Mar 02 09:14:59.610 DEBG [sc] cr2 waiting for prompt |
| 451 | 2026-03-02T09:14:59.646Z | Mar 02 09:14:59.622 DEBG [sc] cr2: logging in |
| 452 | 2026-03-02T09:14:59.962Z | Mar 02 09:14:59.950 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'` |
| 453 | 2026-03-02T09:15:06.278Z | Mar 02 09:15:06.266 INFO cr2: executing eos script show ip bgp | json |
| 454 | 2026-03-02T09:15:06.278Z | Mar 02 09:15:06.266 DEBG [sc] cr2: starting |
| 455 | 2026-03-02T09:15:06.278Z | Mar 02 09:15:06.266 DEBG [sc] cr2: connecting to [::1]:59028 |
| 456 | 2026-03-02T09:15:06.303Z | Mar 02 09:15:06.267 DEBG [sc] cr2 waiting for prompt |
| 457 | 2026-03-02T09:15:07.336Z | Mar 02 09:15:07.323 DEBG [sc] cr2: logging in |
| 458 | 2026-03-02T09:15:07.479Z | Mar 02 09:15:07.467 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'` |
| 459 | 2026-03-02T09:15:13.147Z | Mar 02 09:15:13.135 INFO cr2: executing eos script show ipv6 bgp | json |
| 460 | 2026-03-02T09:15:13.147Z | Mar 02 09:15:13.135 DEBG [sc] cr2: starting |
| 461 | 2026-03-02T09:15:13.147Z | Mar 02 09:15:13.135 DEBG [sc] cr2: connecting to [::1]:59028 |
| 462 | 2026-03-02T09:15:13.172Z | Mar 02 09:15:13.136 DEBG [sc] cr2 waiting for prompt |
| 463 | 2026-03-02T09:15:13.172Z | Mar 02 09:15:13.145 DEBG [sc] cr2: logging in |
| 464 | 2026-03-02T09:15:13.295Z | Mar 02 09:15:13.277 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'` |
| 465 | 2026-03-02T09:15:15.704Z | Mar 02 09:15:15.691 INFO trio bgp unnumbered test passed 🎉 |
| 466 | 2026-03-02T09:15:15.704Z | Mar 02 09:15:15.691 INFO destroying runner for deployment mgtriou |
| 467 | 2026-03-02T09:15:15.704Z | Mar 02 09:15:15.691 INFO destroying deployment mgtriou |
| 468 | 2026-03-02T09:15:15.704Z | Mar 02 09:15:15.691 INFO destroying nodes |
| 469 | 2026-03-02T09:15:15.824Z | Mar 02 09:15:15.812 INFO destroying links |
| 470 | 2026-03-02T09:15:15.824Z | Mar 02 09:15:15.812 INFO destroying link mgtriou_ox_sn_vnic0 |
| 471 | 2026-03-02T09:15:15.848Z | Mar 02 09:15:15.815 INFO destroying link mgtriou_ox_sn_sim0 |
| 472 | 2026-03-02T09:15:15.848Z | Mar 02 09:15:15.817 INFO destroying link mgtriou_cr1_vn_vnic0 |
| 473 | 2026-03-02T09:15:16.832Z | Mar 02 09:15:16.820 INFO destroying link mgtriou_cr1_vn_sim0 |
| 474 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.821 INFO destroying link mgtriou_ox_sn_vnic1 |
| 475 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.822 INFO destroying link mgtriou_ox_sn_sim1 |
| 476 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.823 INFO destroying link mgtriou_cr2_vn_vnic0 |
| 477 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.825 INFO destroying link mgtriou_cr2_vn_sim0 |
| 478 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.826 INFO destroying external links |
| 479 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.826 INFO destroying external link mgtriou_ox_vn_vnic2 |
| 480 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.827 INFO destroying external link mgtriou_cr1_vn_vnic1 |
| 481 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.828 INFO destroying external link mgtriou_cr2_vn_vnic1 |
| 482 | 2026-03-02T09:15:16.856Z | Mar 02 09:15:16.829 INFO destroying images |
| 483 | 2026-03-02T09:15:17.317Z | Mar 02 09:15:17.305 INFO destroying workspace at .falcon |
| 484 | 2026-03-02T09:15:17.341Z | process exited: duration 662030 ms, exit code 0 |
| |
| 485 | 2026-03-02T09:15:17.392Z | found 0 output files |