|
|
|
| 1 | 2026-01-17T20:34:39.998Z | job dependencies complete; ready to run (waiting for 28 m 27 s) |
| 2 | 2026-01-17T20:37:45.343Z | job assigned to worker 01KF6TNJ0CGJDKYA0VFZ71XCCE [factory edgar, gimlet/BRM42220010/384] (queued for 3 m 5 s) |
| 3 | 2026-01-17T20:37:46.386Z | downloading input: /input/build/work/debug/ddmadm |
| 4 | 2026-01-17T20:38:07.593Z | downloaded input: /input/build/work/debug/ddmadm |
| 5 | 2026-01-17T20:38:07.618Z | downloading input: /input/build-interop/work/testbed.tar.gz |
| 6 | 2026-01-17T20:38:15.754Z | downloaded input: /input/build-interop/work/testbed.tar.gz |
| 7 | 2026-01-17T20:38:15.754Z | downloading input: /input/build-interop/work/dhcp-server |
| 8 | 2026-01-17T20:38:18.081Z | downloaded input: /input/build-interop/work/dhcp-server |
| 9 | 2026-01-17T20:38:18.081Z | downloading input: /input/build/work/debug/ddmd |
| 10 | 2026-01-17T20:38:41.881Z | downloaded input: /input/build/work/debug/ddmd |
| 11 | 2026-01-17T20:38:41.881Z | downloading input: /input/build/work/debug/mgadm |
| 12 | 2026-01-17T20:39:06.226Z | downloaded input: /input/build/work/debug/mgadm |
| 13 | 2026-01-17T20:39:06.226Z | downloading input: /input/build/work/debug/mgd |
| 14 | 2026-01-17T20:39:33.251Z | downloaded input: /input/build/work/debug/mgd |
| 15 | 2026-01-17T20:39:33.275Z | downloading input: /input/build/work/release/ddmadm |
| 16 | 2026-01-17T20:39:34.694Z | downloaded input: /input/build/work/release/ddmadm |
| 17 | 2026-01-17T20:39:34.694Z | downloading input: /input/build/work/release/ddmd |
| 18 | 2026-01-17T20:39:37.201Z | downloaded input: /input/build/work/release/ddmd |
| 19 | 2026-01-17T20:39:37.201Z | downloading input: /input/build/work/release/falcon-lab |
| 20 | 2026-01-17T20:39:38.784Z | downloaded input: /input/build/work/release/falcon-lab |
| 21 | 2026-01-17T20:39:38.786Z | downloading input: /input/build/work/release/mgadm |
| 22 | 2026-01-17T20:39:41.137Z | downloaded input: /input/build/work/release/mgadm |
| 23 | 2026-01-17T20:39:41.137Z | downloading input: /input/build/work/release/mgd |
| 24 | 2026-01-17T20:39:44.105Z | downloaded input: /input/build/work/release/mgd |
| |
| 25 | 2026-01-17T20:39:44.105Z | starting task 0: "setup" |
| 26 | 2026-01-17T20:39:44.129Z | ++ uname -s |
| 27 | 2026-01-17T20:39:44.129Z | + kern=SunOS |
| 28 | 2026-01-17T20:39:44.129Z | + build_user=build |
| 29 | 2026-01-17T20:39:44.129Z | + build_uid=12345 |
| 30 | 2026-01-17T20:39:44.129Z | + work_dir=/work |
| 31 | 2026-01-17T20:39:44.129Z | + input_dir=/input |
| 32 | 2026-01-17T20:39:44.129Z | + [[ 0 == 12345 ]] |
| 33 | 2026-01-17T20:39:44.129Z | + case "$kern" in |
| 34 | 2026-01-17T20:39:44.129Z | + groupadd -g 12345 build |
| 35 | 2026-01-17T20:39:44.154Z | + useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build |
| 36 | 2026-01-17T20:39:46.144Z | + zfs create -o mountpoint=/work rpool/work |
| 37 | 2026-01-17T20:39:46.908Z | ++ awk '$2 == "/home" { print $3 }' /etc/mnttab |
| 38 | 2026-01-17T20:39:46.931Z | + home_fs= |
| 39 | 2026-01-17T20:39:46.931Z | + [[ '' == autofs ]] |
| 40 | 2026-01-17T20:39:46.931Z | + mkdir -p /home/build |
| 41 | 2026-01-17T20:39:46.931Z | + chown build:build /home/build /work |
| 42 | 2026-01-17T20:39:48.921Z | + chmod 0700 /home/build /work |
| 43 | 2026-01-17T20:39:48.945Z | process exited: duration 4820 ms, exit code 0 |
| |
| 44 | 2026-01-17T20:39:48.992Z | starting task 1: "authentication" |
| 45 | 2026-01-17T20:39:49.085Z | process exited: duration 92 ms, exit code 0 |
| |
| 46 | 2026-01-17T20:39:49.133Z | starting task 2: "build" |
| 47 | 2026-01-17T20:39:49.158Z | + set -e |
| 48 | 2026-01-17T20:39:49.158Z | + banner zpool |
| 49 | 2026-01-17T20:39:49.159Z | |
| 50 | 2026-01-17T20:39:49.159Z | ###### ##### #### #### # |
| 51 | 2026-01-17T20:39:49.159Z | # # # # # # # # |
| 52 | 2026-01-17T20:39:49.159Z | # # # # # # # # |
| 53 | 2026-01-17T20:39:49.159Z | # ##### # # # # # |
| 54 | 2026-01-17T20:39:49.159Z | # # # # # # # |
| 55 | 2026-01-17T20:39:49.159Z | ###### # #### #### ###### |
| 56 | 2026-01-17T20:39:49.159Z | |
| 57 | 2026-01-17T20:39:49.159Z | ++ pfexec diskinfo -pH |
| 58 | 2026-01-17T20:39:49.159Z | ++ sort -k8 -n -r |
| 59 | 2026-01-17T20:39:49.183Z | ++ head -1 |
| 60 | 2026-01-17T20:39:49.183Z | ++ awk '{print $2}' |
| 61 | 2026-01-17T20:39:49.207Z | + DISK=c9t0014EE81000BC3B1d0 |
| 62 | 2026-01-17T20:39:49.207Z | + export DISK |
| 63 | 2026-01-17T20:39:49.207Z | + pfexec zpool create -o ashift=12 -f cpool c9t0014EE81000BC3B1d0 |
| 64 | 2026-01-17T20:39:49.273Z | + pfexec zfs create -o mountpoint=/ci cpool/ci |
| 65 | 2026-01-17T20:39:49.300Z | ++ curl -s http://catacomb.eng.oxide.computer:12346/trim-me |
| 66 | 2026-01-17T20:43:04.087Z | + [[ '' =~ true ]] |
| 67 | 2026-01-17T20:43:04.111Z | + pfexec chown 12345 /ci |
| 68 | 2026-01-17T20:43:04.111Z | + cd /ci |
| 69 | 2026-01-17T20:43:04.111Z | + export FALCON_DATASET=cpool/falcon |
| 70 | 2026-01-17T20:43:04.111Z | + FALCON_DATASET=cpool/falcon |
| 71 | 2026-01-17T20:43:04.111Z | + banner setup |
| 72 | 2026-01-17T20:43:04.111Z | |
| 73 | 2026-01-17T20:43:04.111Z | #### ###### ##### # # ##### |
| 74 | 2026-01-17T20:43:04.111Z | # # # # # # # |
| 75 | 2026-01-17T20:43:04.111Z | #### ##### # # # # # |
| 76 | 2026-01-17T20:43:04.111Z | # # # # # ##### |
| 77 | 2026-01-17T20:43:04.111Z | # # # # # # # |
| 78 | 2026-01-17T20:43:04.111Z | #### ###### # #### # |
| 79 | 2026-01-17T20:43:04.111Z | |
| 80 | 2026-01-17T20:43:04.111Z | + cp /input/build-interop/work/dhcp-server . |
| 81 | 2026-01-17T20:43:04.440Z | + cp /input/build/work/release/falcon-lab . |
| 82 | 2026-01-17T20:43:04.469Z | + cp /input/build/work/release/mgd . |
| 83 | 2026-01-17T20:43:04.531Z | + cp /input/build/work/release/ddmd . |
| 84 | 2026-01-17T20:43:04.584Z | + chmod +x dhcp-server falcon-lab mgd ddmd |
| 85 | 2026-01-17T20:43:04.607Z | + mkdir -p cargo-bay |
| 86 | 2026-01-17T20:43:04.607Z | + mv mgd cargo-bay/ |
| 87 | 2026-01-17T20:43:04.608Z | + mv ddmd cargo-bay/ |
| 88 | 2026-01-17T20:43:04.608Z | + export EXT_INTERFACE=igb0 |
| 89 | 2026-01-17T20:43:04.608Z | + EXT_INTERFACE=igb0 |
| 90 | 2026-01-17T20:43:04.608Z | ++ bmat address ls -f extra -Ho first |
| 91 | 2026-01-17T20:43:04.631Z | + first=10.151.6.164 |
| 92 | 2026-01-17T20:43:04.631Z | ++ bmat address ls -f extra -Ho last |
| 93 | 2026-01-17T20:43:04.655Z | + last=10.151.6.227 |
| 94 | 2026-01-17T20:43:04.655Z | ++ bmat address ls -f extra -Ho gateway |
| 95 | 2026-01-17T20:43:04.678Z | + gw=10.151.6.1 |
| 96 | 2026-01-17T20:43:04.701Z | ++ ipadm show-addr igb0/dhcp -po ADDR |
| 97 | 2026-01-17T20:43:04.701Z | ++ sed 's#/.*##g' |
| 98 | 2026-01-17T20:43:04.701Z | + server=10.151.6.100 |
| 99 | 2026-01-17T20:43:04.701Z | + RUST_LOG=debug |
| 100 | 2026-01-17T20:43:04.701Z | + pfexec ./falcon-lab run --dendrite-commit 0c2ab6c341bf9e3802c688961b3bc687b941a144 trio-unnumbered |
| 101 | 2026-01-17T20:43:04.701Z | + pfexec ./dhcp-server 10.151.6.164 10.151.6.227 10.151.6.1 10.151.6.100 |
| 102 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 DEBG using default route interface igb0 |
| 103 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 DEBG using default route interface igb0 |
| 104 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 DEBG using default route interface igb0 |
| 105 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 INFO launching runner: deployment(mgtriou) persistent(false) custom_propolis_binary(None) dataset(cpool/falcon) falcon_dir(.falcon) |
| 106 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 INFO starting preflight for deployment mgtriou |
| 107 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 INFO propolis-server binary not found |
| 108 | 2026-01-17T20:43:04.725Z | Jan 17 20:43:04.691 INFO downloading propolis server rev 2dc643742f82d2e072a1281dab23ba2bfdcee440, writing to .falcon/bin/propolis-server |
| 109 | 2026-01-17T20:43:08.360Z | Jan 17 20:43:08.343 INFO ovmf fd not found |
| 110 | 2026-01-17T20:43:08.360Z | Jan 17 20:43:08.343 INFO downloading ovmf |
| 111 | 2026-01-17T20:43:08.740Z | Jan 17 20:43:08.723 INFO base image for helios-2.9 does not exist, attempting to install |
| 112 | 2026-01-17T20:43:08.740Z | Jan 17 20:43:08.723 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/helios-2.9_0.raw.xz |
| 113 | 2026-01-17T20:43:36.877Z | Jan 17 20:43:36.865 INFO extracting image to /tmp/helios-2.9_0.raw |
| 114 | 2026-01-17T20:44:10.274Z | Jan 17 20:44:10.260 INFO creating zvol cpool/falcon/img/helios-2.9 of size 4508880896 |
| 115 | 2026-01-17T20:44:10.318Z | Jan 17 20:44:10.305 INFO copying image data to zvol |
| 116 | 2026-01-17T20:44:20.636Z | Jan 17 20:44:20.623 INFO creating zfs snapshot cpool/falcon/img/helios-2.9@base |
| 117 | 2026-01-17T20:44:23.980Z | Jan 17 20:44:23.967 INFO base image for debian-13.2 does not exist, attempting to install |
| 118 | 2026-01-17T20:44:23.980Z | Jan 17 20:44:23.967 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/debian-13.2_0.raw.xz |
| 119 | 2026-01-17T20:44:44.851Z | Jan 17 20:44:44.838 INFO extracting image to /tmp/debian-13.2_0.raw |
| 120 | 2026-01-17T20:45:05.604Z | Jan 17 20:45:05.590 INFO creating zvol cpool/falcon/img/debian-13.2 of size 3221229568 |
| 121 | 2026-01-17T20:45:05.627Z | Jan 17 20:45:05.615 INFO copying image data to zvol |
| 122 | 2026-01-17T20:45:11.814Z | Jan 17 20:45:11.800 INFO creating zfs snapshot cpool/falcon/img/debian-13.2@base |
| 123 | 2026-01-17T20:45:15.242Z | Jan 17 20:45:15.229 INFO base image for eos-4.35 does not exist, attempting to install |
| 124 | 2026-01-17T20:45:15.242Z | Jan 17 20:45:15.229 INFO trying to download https://oxide-falcon-assets.s3.us-west-2.amazonaws.com/eos-4.35_0.raw.xz |
| 125 | 2026-01-17T20:45:58.300Z | Jan 17 20:45:58.288 INFO extracting image to /tmp/eos-4.35_0.raw |
| 126 | 2026-01-17T20:47:21.387Z | Jan 17 20:47:21.373 INFO creating zvol cpool/falcon/img/eos-4.35 of size 21474840576 |
| 127 | 2026-01-17T20:47:21.410Z | Jan 17 20:47:21.397 INFO copying image data to zvol |
| 128 | 2026-01-17T20:48:10.103Z | Jan 17 20:48:10.092 INFO creating zfs snapshot cpool/falcon/img/eos-4.35@base |
| 129 | 2026-01-17T20:48:13.423Z | Jan 17 20:48:13.412 INFO creating links |
| 130 | 2026-01-17T20:48:13.423Z | Jan 17 20:48:13.412 DEBG destroying link mgtriou_ox_sn_vnic0 |
| 131 | 2026-01-17T20:48:13.446Z | Jan 17 20:48:13.412 DEBG destroying link mgtriou_ox_sn_sim0 |
| 132 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.412 INFO creating simnet link 'mgtriou_ox_sn_sim0' |
| 133 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.418 INFO creating vnic link 'mgtriou_ox_sn_vnic0' |
| 134 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.425 DEBG link pair created |
| 135 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.425 DEBG destroying link mgtriou_cr1_vn_vnic0 |
| 136 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.425 DEBG destroying link mgtriou_cr1_vn_sim0 |
| 137 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.425 INFO creating simnet link 'mgtriou_cr1_vn_sim0' |
| 138 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.426 INFO creating vnic link 'mgtriou_cr1_vn_vnic0' |
| 139 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.432 DEBG link pair created |
| 140 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.432 DEBG destroying link mgtriou_ox_sn_vnic1 |
| 141 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.432 DEBG destroying link mgtriou_ox_sn_sim1 |
| 142 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.432 INFO creating simnet link 'mgtriou_ox_sn_sim1' |
| 143 | 2026-01-17T20:48:13.447Z | Jan 17 20:48:13.434 INFO creating vnic link 'mgtriou_ox_sn_vnic1' |
| 144 | 2026-01-17T20:48:13.470Z | Jan 17 20:48:13.441 DEBG link pair created |
| 145 | 2026-01-17T20:48:13.470Z | Jan 17 20:48:13.441 DEBG destroying link mgtriou_cr2_vn_vnic0 |
| 146 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.441 DEBG destroying link mgtriou_cr2_vn_sim0 |
| 147 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.441 INFO creating simnet link 'mgtriou_cr2_vn_sim0' |
| 148 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.442 INFO creating vnic link 'mgtriou_cr2_vn_vnic0' |
| 149 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.448 DEBG link pair created |
| 150 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.448 INFO creating external links |
| 151 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.448 DEBG destroying external link mgtriou_ox_vn_vnic2 |
| 152 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.448 INFO creating external link mgtriou_ox_vn_vnic2 |
| 153 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.450 DEBG external link mgtriou_ox_vn_vnic2@igb0 created |
| 154 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.450 DEBG destroying external link mgtriou_cr1_vn_vnic1 |
| 155 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.450 INFO creating external link mgtriou_cr1_vn_vnic1 |
| 156 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.451 DEBG external link mgtriou_cr1_vn_vnic1@igb0 created |
| 157 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.451 DEBG destroying external link mgtriou_cr2_vn_vnic1 |
| 158 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.451 INFO creating external link mgtriou_cr2_vn_vnic1 |
| 159 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.453 DEBG external link mgtriou_cr2_vn_vnic1@igb0 created |
| 160 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.453 INFO creating nodes |
| 161 | 2026-01-17T20:48:13.471Z | Jan 17 20:48:13.453 INFO ox: launching node |
| 162 | 2026-01-17T20:48:13.472Z | Jan 17 20:48:13.455 INFO cr1: launching node |
| 163 | 2026-01-17T20:48:13.472Z | Jan 17 20:48:13.457 INFO cr2: launching node |
| 164 | 2026-01-17T20:48:13.495Z | Jan 17 20:48:13.471 INFO launched instance ox with pid 850 on port 59573 |
| 165 | 2026-01-17T20:48:13.495Z | Jan 17 20:48:13.471 INFO ox: instance ensure |
| 166 | 2026-01-17T20:48:13.495Z | Jan 17 20:48:13.472 INFO launched instance cr1 with pid 851 on port 52021 |
| 167 | 2026-01-17T20:48:13.495Z | Jan 17 20:48:13.472 INFO cr1: instance ensure |
| 168 | 2026-01-17T20:48:13.495Z | Jan 17 20:48:13.472 INFO launched instance cr2 with pid 852 on port 55207 |
| 169 | 2026-01-17T20:48:13.495Z | Jan 17 20:48:13.472 INFO cr2: instance ensure |
| 170 | 2026-01-17T20:48:15.674Z | Jan 17 20:48:15.663 INFO cr1: instance run |
| 171 | 2026-01-17T20:48:15.698Z | Jan 17 20:48:15.664 DEBG [sc] cr1: starting |
| 172 | 2026-01-17T20:48:15.698Z | Jan 17 20:48:15.664 DEBG [sc] cr1: connecting to [::1]:52021 |
| 173 | 2026-01-17T20:48:15.698Z | Jan 17 20:48:15.665 DEBG [sc] cr1 waiting for prompt |
| 174 | 2026-01-17T20:48:15.818Z | Jan 17 20:48:15.806 INFO ox: instance run |
| 175 | 2026-01-17T20:48:15.841Z | Jan 17 20:48:15.807 DEBG [sc] ox: starting |
| 176 | 2026-01-17T20:48:15.841Z | Jan 17 20:48:15.807 DEBG [sc] ox: connecting to [::1]:59573 |
| 177 | 2026-01-17T20:48:15.841Z | Jan 17 20:48:15.808 DEBG [sc] ox waiting for prompt |
| 178 | 2026-01-17T20:48:15.914Z | Jan 17 20:48:15.903 INFO cr2: instance run |
| 179 | 2026-01-17T20:48:15.938Z | Jan 17 20:48:15.904 DEBG [sc] cr2: starting |
| 180 | 2026-01-17T20:48:15.938Z | Jan 17 20:48:15.904 DEBG [sc] cr2: connecting to [::1]:55207 |
| 181 | 2026-01-17T20:48:15.938Z | Jan 17 20:48:15.905 DEBG [sc] cr2 waiting for prompt |
| 182 | 2026-01-17T20:48:32.856Z | Jan 17 20:48:32.845 DEBG [sc] cr1: logging in |
| 183 | 2026-01-17T20:48:33.231Z | Jan 17 20:48:33.220 INFO cr1: mounting /opt/cargo-bay |
| 184 | 2026-01-17T20:48:33.231Z | Jan 17 20:48:33.220 DEBG [sc] cr1: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull` |
| 185 | 2026-01-17T20:48:33.255Z | Jan 17 20:48:33.242 DEBG [sc] cr1: executing command `cd` |
| 186 | 2026-01-17T20:48:33.278Z | Jan 17 20:48:33.254 INFO cr1: finished mounting /opt/cargo-bay |
| 187 | 2026-01-17T20:48:33.278Z | Jan 17 20:48:33.254 DEBG [sc] cr1: executing command `hostname cr1` |
| 188 | 2026-01-17T20:48:33.278Z | Jan 17 20:48:33.264 DEBG [sc] cr1: executing command `echo 'cr1' > /etc/nodename` |
| 189 | 2026-01-17T20:48:33.302Z | Jan 17 20:48:33.275 DEBG [sc] cr1: executing command `echo '::1 cr1.local cr1' >> /etc/hosts` |
| 190 | 2026-01-17T20:48:33.302Z | Jan 17 20:48:33.286 DEBG [sc] cr1: executing command `echo '127.0.0.1 cr1.local cr1' >> /etc/hosts` |
| 191 | 2026-01-17T20:48:33.325Z | Jan 17 20:48:33.297 INFO cr1: logging out |
| 192 | 2026-01-17T20:48:35.372Z | Jan 17 20:48:35.361 INFO cr1: logged out |
| 193 | 2026-01-17T20:48:36.186Z | Jan 17 20:48:36.175 DEBG [sc] ox: logging in |
| 194 | 2026-01-17T20:48:36.389Z | Jan 17 20:48:36.362 INFO ox: mounting /opt/cargo-bay |
| 195 | 2026-01-17T20:48:36.389Z | Jan 17 20:48:36.362 DEBG [sc] ox: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull` |
| 196 | 2026-01-17T20:48:37.441Z | Jan 17 20:48:37.430 DEBG [sc] ox: executing command `cd` |
| 197 | 2026-01-17T20:48:37.465Z | Jan 17 20:48:37.440 INFO ox: finished mounting /opt/cargo-bay |
| 198 | 2026-01-17T20:48:37.465Z | Jan 17 20:48:37.440 DEBG [sc] ox: executing command `hostname ox` |
| 199 | 2026-01-17T20:48:37.465Z | Jan 17 20:48:37.451 DEBG [sc] ox: executing command `echo 'ox' > /etc/nodename` |
| 200 | 2026-01-17T20:48:37.488Z | Jan 17 20:48:37.462 DEBG [sc] ox: executing command `echo '::1 ox.local ox' >> /etc/hosts` |
| 201 | 2026-01-17T20:48:37.488Z | Jan 17 20:48:37.473 DEBG [sc] ox: executing command `echo '127.0.0.1 ox.local ox' >> /etc/hosts` |
| 202 | 2026-01-17T20:48:37.512Z | Jan 17 20:48:37.484 INFO ox: logging out |
| 203 | 2026-01-17T20:48:37.568Z | Jan 17 20:48:37.558 DEBG [sc] cr2: logging in |
| 204 | 2026-01-17T20:48:37.592Z | Jan 17 20:48:37.562 INFO ox: logged out |
| 205 | 2026-01-17T20:48:37.969Z | Jan 17 20:48:37.958 INFO cr2: mounting /opt/cargo-bay |
| 206 | 2026-01-17T20:48:37.969Z | Jan 17 20:48:37.958 DEBG [sc] cr2: executing command `mkdir -p /opt/cargo-bay; cd /opt/cargo-bay; p9kp pull` |
| 207 | 2026-01-17T20:48:37.993Z | Jan 17 20:48:37.979 DEBG [sc] cr2: executing command `cd` |
| 208 | 2026-01-17T20:48:38.017Z | Jan 17 20:48:37.991 INFO cr2: finished mounting /opt/cargo-bay |
| 209 | 2026-01-17T20:48:38.017Z | Jan 17 20:48:37.991 DEBG [sc] cr2: executing command `hostname cr2` |
| 210 | 2026-01-17T20:48:38.018Z | Jan 17 20:48:38.002 DEBG [sc] cr2: executing command `echo 'cr2' > /etc/nodename` |
| 211 | 2026-01-17T20:48:38.041Z | Jan 17 20:48:38.013 DEBG [sc] cr2: executing command `echo '::1 cr2.local cr2' >> /etc/hosts` |
| 212 | 2026-01-17T20:48:38.041Z | Jan 17 20:48:38.024 DEBG [sc] cr2: executing command `echo '127.0.0.1 cr2.local cr2' >> /etc/hosts` |
| 213 | 2026-01-17T20:48:38.065Z | Jan 17 20:48:38.034 INFO cr2: logging out |
| 214 | 2026-01-17T20:48:40.986Z | Jan 17 20:48:40.975 INFO cr2: logged out |
| 215 | 2026-01-17T20:48:41.010Z | Jan 17 20:48:40.975 DEBG [sc] ox: starting |
| 216 | 2026-01-17T20:48:41.010Z | Jan 17 20:48:40.975 DEBG [sc] ox: connecting to [::1]:59573 |
| 217 | 2026-01-17T20:48:41.010Z | Jan 17 20:48:40.976 DEBG [sc] ox waiting for prompt |
| 218 | 2026-01-17T20:48:41.010Z | Jan 17 20:48:40.987 DEBG [sc] ox: logging in |
| 219 | 2026-01-17T20:48:41.086Z | Jan 17 20:48:41.076 DEBG [sc] ox: executing command `ipadm create-addr -T dhcp vioif1/dhcp` |
| 220 | 2026-01-17T20:48:46.899Z | Jan 17 20:48:46.888 DEBG [sc] ox: starting |
| 221 | 2026-01-17T20:48:46.899Z | Jan 17 20:48:46.888 DEBG [sc] ox: connecting to [::1]:59573 |
| 222 | 2026-01-17T20:48:46.922Z | Jan 17 20:48:46.888 DEBG [sc] ox waiting for prompt |
| 223 | 2026-01-17T20:48:46.922Z | Jan 17 20:48:46.899 DEBG [sc] ox: logging in |
| 224 | 2026-01-17T20:48:46.999Z | Jan 17 20:48:46.988 DEBG [sc] ox: executing command `echo 'nameserver 1.1.1.1' > /etc/resolv.conf` |
| 225 | 2026-01-17T20:48:47.076Z | Jan 17 20:48:47.065 DEBG [sc] ox: starting |
| 226 | 2026-01-17T20:48:47.076Z | Jan 17 20:48:47.065 DEBG [sc] ox: connecting to [::1]:59573 |
| 227 | 2026-01-17T20:48:47.100Z | Jan 17 20:48:47.066 DEBG [sc] ox waiting for prompt |
| 228 | 2026-01-17T20:48:47.100Z | Jan 17 20:48:47.077 DEBG [sc] ox: logging in |
| 229 | 2026-01-17T20:48:47.176Z | Jan 17 20:48:47.165 DEBG [sc] ox: executing command `ipadm show-addr vioif1/dhcp -p -o addr` |
| 230 | 2026-01-17T20:48:47.241Z | Jan 17 20:48:47.230 INFO cr1: installing frr |
| 231 | 2026-01-17T20:48:47.241Z | Jan 17 20:48:47.230 INFO waiting for ceos to initialize |
| 232 | 2026-01-17T20:48:47.241Z | Jan 17 20:48:47.231 INFO ox: setting up npuvm |
| 233 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] cr2: starting |
| 234 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] cr2: connecting to [::1]:55207 |
| 235 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] cr1: starting |
| 236 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] cr1: connecting to [::1]:52021 |
| 237 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] ox: starting |
| 238 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] ox: connecting to [::1]:59573 |
| 239 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] cr2 waiting for prompt |
| 240 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] ox waiting for prompt |
| 241 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.231 DEBG [sc] cr1 waiting for prompt |
| 242 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.241 DEBG [sc] ox: logging in |
| 243 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.242 DEBG [sc] cr2: logging in |
| 244 | 2026-01-17T20:48:47.265Z | Jan 17 20:48:47.243 DEBG [sc] cr1: logging in |
| 245 | 2026-01-17T20:48:47.329Z | Jan 17 20:48:47.319 DEBG [sc] ox: executing command `curl --retry 5 -OL https://buildomat.eng.oxide.computer/public/file/oxidecomputer//softnpu/image/fd2c726815cdb03c2687e1bf2912a9184905557b/npuvm` |
| 246 | 2026-01-17T20:48:47.408Z | Jan 17 20:48:47.397 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'` |
| 247 | 2026-01-17T20:48:47.618Z | Jan 17 20:48:47.607 DEBG [sc] cr1: executing command `apt-get -y update && apt-get -y install frr` |
| 248 | 2026-01-17T20:48:49.690Z | Jan 17 20:48:49.679 INFO cr2: executing eos script show version |
| 249 | 2026-01-17T20:48:49.690Z | Jan 17 20:48:49.679 DEBG [sc] cr2: starting |
| 250 | 2026-01-17T20:48:49.690Z | Jan 17 20:48:49.679 DEBG [sc] cr2: connecting to [::1]:55207 |
| 251 | 2026-01-17T20:48:49.713Z | Jan 17 20:48:49.679 DEBG [sc] cr2 waiting for prompt |
| 252 | 2026-01-17T20:48:49.713Z | Jan 17 20:48:49.690 DEBG [sc] cr2: logging in |
| 253 | 2026-01-17T20:48:49.855Z | Jan 17 20:48:49.844 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'` |
| 254 | 2026-01-17T20:48:52.554Z | Jan 17 20:48:52.543 DEBG [sc] ox: starting |
| 255 | 2026-01-17T20:48:52.554Z | Jan 17 20:48:52.543 DEBG [sc] ox: connecting to [::1]:59573 |
| 256 | 2026-01-17T20:48:52.578Z | Jan 17 20:48:52.543 DEBG [sc] ox waiting for prompt |
| 257 | 2026-01-17T20:48:52.578Z | Jan 17 20:48:52.554 DEBG [sc] ox: logging in |
| 258 | 2026-01-17T20:48:52.654Z | Jan 17 20:48:52.643 DEBG [sc] ox: executing command `chmod +x npuvm` |
| 259 | 2026-01-17T20:48:52.720Z | Jan 17 20:48:52.709 DEBG [sc] ox: starting |
| 260 | 2026-01-17T20:48:52.720Z | Jan 17 20:48:52.709 DEBG [sc] ox: connecting to [::1]:59573 |
| 261 | 2026-01-17T20:48:52.744Z | Jan 17 20:48:52.710 DEBG [sc] ox waiting for prompt |
| 262 | 2026-01-17T20:48:52.744Z | Jan 17 20:48:52.720 DEBG [sc] ox: logging in |
| 263 | 2026-01-17T20:48:52.807Z | Jan 17 20:48:52.796 DEBG [sc] ox: executing command `./npuvm install --front-ports 2 --rear-ports 0 --pkt-source vioif0 --dendrite-commit 0c2ab6c341bf9e3802c688961b3bc687b941a144 ` |
| 264 | 2026-01-17T20:48:58.270Z | Jan 17 20:48:58.258 DEBG [sc] cr2: starting |
| 265 | 2026-01-17T20:48:58.270Z | Jan 17 20:48:58.258 DEBG [sc] cr2: connecting to [::1]:55207 |
| 266 | 2026-01-17T20:48:58.293Z | Jan 17 20:48:58.259 DEBG [sc] cr2 waiting for prompt |
| 267 | 2026-01-17T20:48:58.293Z | Jan 17 20:48:58.269 DEBG [sc] cr2: logging in |
| 268 | 2026-01-17T20:48:58.434Z | Jan 17 20:48:58.423 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'` |
| 269 | 2026-01-17T20:49:00.549Z | Jan 17 20:49:00.537 INFO cr2: executing eos script show version |
| 270 | 2026-01-17T20:49:00.549Z | Jan 17 20:49:00.537 DEBG [sc] cr2: starting |
| 271 | 2026-01-17T20:49:00.549Z | Jan 17 20:49:00.537 DEBG [sc] cr2: connecting to [::1]:55207 |
| 272 | 2026-01-17T20:49:00.572Z | Jan 17 20:49:00.538 DEBG [sc] cr2 waiting for prompt |
| 273 | 2026-01-17T20:49:00.572Z | Jan 17 20:49:00.548 DEBG [sc] cr2: logging in |
| 274 | 2026-01-17T20:49:00.715Z | Jan 17 20:49:00.704 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'` |
| 275 | 2026-01-17T20:49:03.095Z | Jan 17 20:49:03.083 DEBG [sc] cr2: starting |
| 276 | 2026-01-17T20:49:03.095Z | Jan 17 20:49:03.083 DEBG [sc] cr2: connecting to [::1]:55207 |
| 277 | 2026-01-17T20:49:03.119Z | Jan 17 20:49:03.084 DEBG [sc] cr2 waiting for prompt |
| 278 | 2026-01-17T20:49:03.119Z | Jan 17 20:49:03.094 DEBG [sc] cr2: logging in |
| 279 | 2026-01-17T20:49:03.270Z | Jan 17 20:49:03.259 DEBG [sc] cr2: executing command `docker inspect ceos --format '{{.State.Status}}'` |
| 280 | 2026-01-17T20:49:05.385Z | Jan 17 20:49:05.374 INFO cr2: executing eos script show version |
| 281 | 2026-01-17T20:49:05.385Z | Jan 17 20:49:05.374 DEBG [sc] cr2: starting |
| 282 | 2026-01-17T20:49:05.409Z | Jan 17 20:49:05.374 DEBG [sc] cr2: connecting to [::1]:55207 |
| 283 | 2026-01-17T20:49:05.409Z | Jan 17 20:49:05.375 DEBG [sc] cr2 waiting for prompt |
| 284 | 2026-01-17T20:49:05.409Z | Jan 17 20:49:05.385 DEBG [sc] cr2: logging in |
| 285 | 2026-01-17T20:49:05.551Z | Jan 17 20:49:05.540 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show version'` |
| 286 | 2026-01-17T20:49:07.996Z | Jan 17 20:49:07.985 INFO cr2: executing eos script |
| 287 | 2026-01-17T20:49:07.997Z | enable |
| 288 | 2026-01-17T20:49:07.997Z | configure |
| 289 | 2026-01-17T20:49:07.997Z | ipv6 unicast-routing |
| 290 | 2026-01-17T20:49:07.997Z | ip routing ipv6 interfaces |
| 291 | 2026-01-17T20:49:07.997Z | ip routing |
| 292 | 2026-01-17T20:49:07.997Z | ip route 1.2.3.0/24 null0 |
| 293 | 2026-01-17T20:49:07.997Z | ipv6 route fd99::/64 null0 |
| 294 | 2026-01-17T20:49:07.997Z | interface et1 |
| 295 | 2026-01-17T20:49:07.997Z | no switchport |
| 296 | 2026-01-17T20:49:07.997Z | ipv6 enable |
| 297 | 2026-01-17T20:49:07.997Z | |
| 298 | 2026-01-17T20:49:07.997Z | router bgp 45 |
| 299 | 2026-01-17T20:49:07.997Z | router-id 1.2.3.1 |
| 300 | 2026-01-17T20:49:07.997Z | no bgp default ipv4-unicast |
| 301 | 2026-01-17T20:49:07.997Z | timers bgp 2 6 |
| 302 | 2026-01-17T20:49:07.997Z | neighbor ebgp peer group |
| 303 | 2026-01-17T20:49:07.998Z | neighbor ebgp remote-as 33 |
| 304 | 2026-01-17T20:49:07.998Z | neighbor interface Et1 peer-group ebgp |
| 305 | 2026-01-17T20:49:07.998Z | address-family ipv4 |
| 306 | 2026-01-17T20:49:07.998Z | neighbor ebgp activate |
| 307 | 2026-01-17T20:49:07.998Z | neighbor ebgp next-hop address-family ipv6 originate |
| 308 | 2026-01-17T20:49:07.998Z | network 1.2.3.0/24 |
| 309 | 2026-01-17T20:49:07.998Z | exit |
| 310 | 2026-01-17T20:49:07.998Z | address-family ipv6 |
| 311 | 2026-01-17T20:49:07.998Z | neighbor ebgp activate |
| 312 | 2026-01-17T20:49:07.998Z | neighbor ebgp next-hop address-family ipv6 originate |
| 313 | 2026-01-17T20:49:07.998Z | network fd99::/64 |
| 314 | 2026-01-17T20:49:07.998Z | exit |
| 315 | 2026-01-17T20:49:07.998Z | exit |
| 316 | 2026-01-17T20:49:07.998Z | |
| 317 | 2026-01-17T20:49:07.998Z | Jan 17 20:49:07.985 DEBG [sc] cr2: starting |
| 318 | 2026-01-17T20:49:07.998Z | Jan 17 20:49:07.985 DEBG [sc] cr2: connecting to [::1]:55207 |
| 319 | 2026-01-17T20:49:08.022Z | Jan 17 20:49:07.986 DEBG [sc] cr2 waiting for prompt |
| 320 | 2026-01-17T20:49:08.022Z | Jan 17 20:49:07.996 DEBG [sc] cr2: logging in |
| 321 | 2026-01-17T20:49:08.173Z | Jan 17 20:49:08.162 DEBG [sc] cr2: executing command `docker exec ceos Cli -c ' |
| 322 | 2026-01-17T20:49:08.173Z | enable |
| 323 | 2026-01-17T20:49:08.173Z | configure |
| 324 | 2026-01-17T20:49:08.173Z | ipv6 unicast-routing |
| 325 | 2026-01-17T20:49:08.173Z | ip routing ipv6 interfaces |
| 326 | 2026-01-17T20:49:08.173Z | ip routing |
| 327 | 2026-01-17T20:49:08.173Z | ip route 1.2.3.0/24 null0 |
| 328 | 2026-01-17T20:49:08.173Z | ipv6 route fd99::/64 null0 |
| 329 | 2026-01-17T20:49:08.173Z | interface et1 |
| 330 | 2026-01-17T20:49:08.173Z | no switchport |
| 331 | 2026-01-17T20:49:08.174Z | ipv6 enable |
| 332 | 2026-01-17T20:49:08.174Z | |
| 333 | 2026-01-17T20:49:08.174Z | router bgp 45 |
| 334 | 2026-01-17T20:49:08.174Z | router-id 1.2.3.1 |
| 335 | 2026-01-17T20:49:08.174Z | no bgp default ipv4-unicast |
| 336 | 2026-01-17T20:49:08.174Z | timers bgp 2 6 |
| 337 | 2026-01-17T20:49:08.174Z | neighbor ebgp peer group |
| 338 | 2026-01-17T20:49:08.174Z | neighbor ebgp remote-as 33 |
| 339 | 2026-01-17T20:49:08.174Z | neighbor interface Et1 peer-group ebgp |
| 340 | 2026-01-17T20:49:08.174Z | address-family ipv4 |
| 341 | 2026-01-17T20:49:08.174Z | neighbor ebgp activate |
| 342 | 2026-01-17T20:49:08.174Z | neighbor ebgp next-hop address-family ipv6 originate |
| 343 | 2026-01-17T20:49:08.174Z | network 1.2.3.0/24 |
| 344 | 2026-01-17T20:49:08.175Z | exit |
| 345 | 2026-01-17T20:49:08.175Z | address-family ipv6 |
| 346 | 2026-01-17T20:49:08.175Z | neighbor ebgp activate |
| 347 | 2026-01-17T20:49:08.175Z | neighbor ebgp next-hop address-family ipv6 originate |
| 348 | 2026-01-17T20:49:08.175Z | network fd99::/64 |
| 349 | 2026-01-17T20:49:08.175Z | exit |
| 350 | 2026-01-17T20:49:08.175Z | exit |
| 351 | 2026-01-17T20:49:08.175Z | '` |
| 352 | 2026-01-17T20:49:25.279Z | Jan 17 20:49:25.268 INFO cr1: enabling frr daemon bgpd |
| 353 | 2026-01-17T20:49:25.279Z | Jan 17 20:49:25.268 DEBG [sc] cr1: starting |
| 354 | 2026-01-17T20:49:25.279Z | Jan 17 20:49:25.268 DEBG [sc] cr1: connecting to [::1]:52021 |
| 355 | 2026-01-17T20:49:25.302Z | Jan 17 20:49:25.268 DEBG [sc] cr1 waiting for prompt |
| 356 | 2026-01-17T20:49:25.302Z | Jan 17 20:49:25.279 DEBG [sc] cr1: logging in |
| 357 | 2026-01-17T20:49:25.433Z | Jan 17 20:49:25.422 DEBG [sc] cr1: executing command `sed -i 's/bgpd=no/bgpd=yes/g' /etc/frr/daemons` |
| 358 | 2026-01-17T20:49:27.506Z | Jan 17 20:49:27.495 DEBG [sc] cr1: starting |
| 359 | 2026-01-17T20:49:27.506Z | Jan 17 20:49:27.495 DEBG [sc] cr1: connecting to [::1]:52021 |
| 360 | 2026-01-17T20:49:27.529Z | Jan 17 20:49:27.495 DEBG [sc] cr1 waiting for prompt |
| 361 | 2026-01-17T20:49:27.529Z | Jan 17 20:49:27.505 DEBG [sc] cr1: logging in |
| 362 | 2026-01-17T20:49:27.650Z | Jan 17 20:49:27.639 DEBG [sc] cr1: executing command `systemctl restart frr` |
| 363 | 2026-01-17T20:49:35.220Z | Jan 17 20:49:35.209 INFO cr1: executing frr script |
| 364 | 2026-01-17T20:49:35.220Z | configure |
| 365 | 2026-01-17T20:49:35.220Z | ip forwarding |
| 366 | 2026-01-17T20:49:35.220Z | ipv6 forwarding |
| 367 | 2026-01-17T20:49:35.220Z | ip route 1.2.3.0/24 null0 |
| 368 | 2026-01-17T20:49:35.220Z | ipv6 route fd99::/64 null0 |
| 369 | 2026-01-17T20:49:35.220Z | route-map PERMIT-ALL permit 10 |
| 370 | 2026-01-17T20:49:35.221Z | router bgp 44 |
| 371 | 2026-01-17T20:49:35.221Z | timers bgp 2 6 |
| 372 | 2026-01-17T20:49:35.221Z | neighbor enp0s8 interface remote-as external |
| 373 | 2026-01-17T20:49:35.221Z | neighbor enp0s8 timers connect 1 |
| 374 | 2026-01-17T20:49:35.221Z | address-family ipv4 unicast |
| 375 | 2026-01-17T20:49:35.221Z | network 1.2.3.0/24 |
| 376 | 2026-01-17T20:49:35.221Z | neighbor enp0s8 activate |
| 377 | 2026-01-17T20:49:35.221Z | neighbor enp0s8 route-map PERMIT-ALL out |
| 378 | 2026-01-17T20:49:35.221Z | neighbor enp0s8 route-map PERMIT-ALL in |
| 379 | 2026-01-17T20:49:35.221Z | exit-address-family |
| 380 | 2026-01-17T20:49:35.222Z | address-family ipv6 unicast |
| 381 | 2026-01-17T20:49:35.222Z | network fd99::/64 |
| 382 | 2026-01-17T20:49:35.222Z | neighbor enp0s8 activate |
| 383 | 2026-01-17T20:49:35.222Z | neighbor enp0s8 route-map PERMIT-ALL out |
| 384 | 2026-01-17T20:49:35.222Z | neighbor enp0s8 route-map PERMIT-ALL in |
| 385 | 2026-01-17T20:49:35.222Z | exit-address-family |
| 386 | 2026-01-17T20:49:35.222Z | exit |
| 387 | 2026-01-17T20:49:35.222Z | |
| 388 | 2026-01-17T20:49:35.222Z | Jan 17 20:49:35.209 DEBG [sc] cr1: starting |
| 389 | 2026-01-17T20:49:35.222Z | Jan 17 20:49:35.209 DEBG [sc] cr1: connecting to [::1]:52021 |
| 390 | 2026-01-17T20:49:35.246Z | Jan 17 20:49:35.210 DEBG [sc] cr1 waiting for prompt |
| 391 | 2026-01-17T20:49:35.246Z | Jan 17 20:49:35.221 DEBG [sc] cr1: logging in |
| 392 | 2026-01-17T20:49:35.375Z | Jan 17 20:49:35.365 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 ' '` |
| 393 | 2026-01-17T20:50:15.031Z | Jan 17 20:50:15.020 DEBG [sc] ox: starting |
| 394 | 2026-01-17T20:50:15.031Z | Jan 17 20:50:15.020 DEBG [sc] ox: connecting to [::1]:59573 |
| 395 | 2026-01-17T20:50:15.032Z | Jan 17 20:50:15.020 DEBG [sc] ox waiting for prompt |
| 396 | 2026-01-17T20:50:15.055Z | Jan 17 20:50:15.030 DEBG [sc] ox: logging in |
| 397 | 2026-01-17T20:50:15.119Z | Jan 17 20:50:15.108 DEBG [sc] ox: executing command `/root/scadm propolis load-program /root/libsidecar_lite.so` |
| 398 | 2026-01-17T20:50:15.480Z | Jan 17 20:50:15.469 DEBG [sc] ox: starting |
| 399 | 2026-01-17T20:50:15.480Z | Jan 17 20:50:15.469 DEBG [sc] ox: connecting to [::1]:59573 |
| 400 | 2026-01-17T20:50:15.504Z | Jan 17 20:50:15.470 DEBG [sc] ox waiting for prompt |
| 401 | 2026-01-17T20:50:15.504Z | Jan 17 20:50:15.481 DEBG [sc] ox: logging in |
| 402 | 2026-01-17T20:50:15.570Z | Jan 17 20:50:15.559 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link` |
| 403 | 2026-01-17T20:50:16.593Z | Jan 17 20:50:16.581 DEBG [sc] ox: starting |
| 404 | 2026-01-17T20:50:16.593Z | Jan 17 20:50:16.581 DEBG [sc] ox: connecting to [::1]:59573 |
| 405 | 2026-01-17T20:50:16.617Z | Jan 17 20:50:16.583 DEBG [sc] ox waiting for prompt |
| 406 | 2026-01-17T20:50:16.617Z | Jan 17 20:50:16.583 DEBG [sc] ox: logging in |
| 407 | 2026-01-17T20:50:16.672Z | Jan 17 20:50:16.661 DEBG [sc] ox: executing command `dladm show-link tfportqsfp0_0 -p -o link` |
| 408 | 2026-01-17T20:50:16.728Z | Jan 17 20:50:16.717 DEBG [sc] ox: starting |
| 409 | 2026-01-17T20:50:16.728Z | Jan 17 20:50:16.717 DEBG [sc] ox: connecting to [::1]:59573 |
| 410 | 2026-01-17T20:50:16.751Z | Jan 17 20:50:16.718 DEBG [sc] ox waiting for prompt |
| 411 | 2026-01-17T20:50:16.751Z | Jan 17 20:50:16.728 DEBG [sc] ox: logging in |
| 412 | 2026-01-17T20:50:16.816Z | Jan 17 20:50:16.805 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp0_0/ll` |
| 413 | 2026-01-17T20:50:16.883Z | Jan 17 20:50:16.872 DEBG [sc] ox: starting |
| 414 | 2026-01-17T20:50:16.883Z | Jan 17 20:50:16.872 DEBG [sc] ox: connecting to [::1]:59573 |
| 415 | 2026-01-17T20:50:16.906Z | Jan 17 20:50:16.872 DEBG [sc] ox waiting for prompt |
| 416 | 2026-01-17T20:50:16.906Z | Jan 17 20:50:16.883 DEBG [sc] ox: logging in |
| 417 | 2026-01-17T20:50:16.971Z | Jan 17 20:50:16.960 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp0_0/ll -p -o addr` |
| 418 | 2026-01-17T20:50:17.026Z | Jan 17 20:50:17.015 DEBG [sc] ox: starting |
| 419 | 2026-01-17T20:50:17.028Z | Jan 17 20:50:17.015 DEBG [sc] ox: connecting to [::1]:59573 |
| 420 | 2026-01-17T20:50:17.051Z | Jan 17 20:50:17.016 DEBG [sc] ox waiting for prompt |
| 421 | 2026-01-17T20:50:17.051Z | Jan 17 20:50:17.026 DEBG [sc] ox: logging in |
| 422 | 2026-01-17T20:50:17.115Z | Jan 17 20:50:17.104 DEBG [sc] ox: executing command `dladm show-link tfportqsfp1_0 -p -o link` |
| 423 | 2026-01-17T20:50:17.181Z | Jan 17 20:50:17.170 DEBG [sc] ox: starting |
| 424 | 2026-01-17T20:50:17.181Z | Jan 17 20:50:17.170 DEBG [sc] ox: connecting to [::1]:59573 |
| 425 | 2026-01-17T20:50:17.204Z | Jan 17 20:50:17.170 DEBG [sc] ox waiting for prompt |
| 426 | 2026-01-17T20:50:17.204Z | Jan 17 20:50:17.180 DEBG [sc] ox: logging in |
| 427 | 2026-01-17T20:50:17.280Z | Jan 17 20:50:17.269 DEBG [sc] ox: executing command `ipadm create-addr -T addrconf tfportqsfp1_0/ll` |
| 428 | 2026-01-17T20:50:17.345Z | Jan 17 20:50:17.334 DEBG [sc] ox: starting |
| 429 | 2026-01-17T20:50:17.345Z | Jan 17 20:50:17.334 DEBG [sc] ox: connecting to [::1]:59573 |
| 430 | 2026-01-17T20:50:17.369Z | Jan 17 20:50:17.335 DEBG [sc] ox waiting for prompt |
| 431 | 2026-01-17T20:50:17.369Z | Jan 17 20:50:17.346 DEBG [sc] ox: logging in |
| 432 | 2026-01-17T20:50:17.444Z | Jan 17 20:50:17.433 DEBG [sc] ox: executing command `ipadm show-addr tfportqsfp1_0/ll -p -o addr` |
| 433 | 2026-01-17T20:50:17.500Z | Jan 17 20:50:17.489 DEBG [sc] ox: starting |
| 434 | 2026-01-17T20:50:17.500Z | Jan 17 20:50:17.489 DEBG [sc] ox: connecting to [::1]:59573 |
| 435 | 2026-01-17T20:50:17.524Z | Jan 17 20:50:17.490 DEBG [sc] ox waiting for prompt |
| 436 | 2026-01-17T20:50:17.524Z | Jan 17 20:50:17.500 DEBG [sc] ox: logging in |
| 437 | 2026-01-17T20:50:17.588Z | Jan 17 20:50:17.577 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/mgd && /opt/cargo-bay/mgd run &> /tmp/mgd.log &` |
| 438 | 2026-01-17T20:50:17.645Z | Jan 17 20:50:17.634 DEBG [sc] ox: starting |
| 439 | 2026-01-17T20:50:17.645Z | Jan 17 20:50:17.634 DEBG [sc] ox: connecting to [::1]:59573 |
| 440 | 2026-01-17T20:50:17.668Z | Jan 17 20:50:17.634 DEBG [sc] ox waiting for prompt |
| 441 | 2026-01-17T20:50:17.668Z | Jan 17 20:50:17.644 DEBG [sc] ox: logging in |
| 442 | 2026-01-17T20:50:17.734Z | Jan 17 20:50:17.723 DEBG [sc] ox: executing command `chmod +x /opt/cargo-bay/ddmd && /opt/cargo-bay/ddmd &> /tmp/ddm.log &` |
| 443 | 2026-01-17T20:50:17.804Z | Jan 17 20:50:17.793 INFO adding BGP router to mgd |
| 444 | 2026-01-17T20:50:23.956Z | Jan 17 20:50:23.945 INFO cr1: executing frr script show ip bgp json |
| 445 | 2026-01-17T20:50:23.956Z | Jan 17 20:50:23.945 DEBG [sc] cr1: starting |
| 446 | 2026-01-17T20:50:23.956Z | Jan 17 20:50:23.945 DEBG [sc] cr1: connecting to [::1]:52021 |
| 447 | 2026-01-17T20:50:23.979Z | Jan 17 20:50:23.945 DEBG [sc] cr1 waiting for prompt |
| 448 | 2026-01-17T20:50:23.979Z | Jan 17 20:50:23.956 DEBG [sc] cr1: logging in |
| 449 | 2026-01-17T20:50:24.310Z | Jan 17 20:50:24.299 DEBG [sc] cr1: executing command `vtysh -c 'show ip bgp json'` |
| 450 | 2026-01-17T20:50:26.457Z | Jan 17 20:50:26.446 INFO cr1: executing frr script show bgp json |
| 451 | 2026-01-17T20:50:26.457Z | Jan 17 20:50:26.446 DEBG [sc] cr1: starting |
| 452 | 2026-01-17T20:50:26.457Z | Jan 17 20:50:26.446 DEBG [sc] cr1: connecting to [::1]:52021 |
| 453 | 2026-01-17T20:50:26.480Z | Jan 17 20:50:26.447 DEBG [sc] cr1 waiting for prompt |
| 454 | 2026-01-17T20:50:26.480Z | Jan 17 20:50:26.457 DEBG [sc] cr1: logging in |
| 455 | 2026-01-17T20:50:26.601Z | Jan 17 20:50:26.590 DEBG [sc] cr1: executing command `vtysh -c 'show bgp json'` |
| 456 | 2026-01-17T20:50:28.721Z | Jan 17 20:50:28.710 INFO cr2: executing eos script show ip bgp | json |
| 457 | 2026-01-17T20:50:28.722Z | Jan 17 20:50:28.710 DEBG [sc] cr2: starting |
| 458 | 2026-01-17T20:50:28.722Z | Jan 17 20:50:28.710 DEBG [sc] cr2: connecting to [::1]:55207 |
| 459 | 2026-01-17T20:50:28.746Z | Jan 17 20:50:28.711 DEBG [sc] cr2 waiting for prompt |
| 460 | 2026-01-17T20:50:28.746Z | Jan 17 20:50:28.721 DEBG [sc] cr2: logging in |
| 461 | 2026-01-17T20:50:29.092Z | Jan 17 20:50:29.081 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ip bgp | json'` |
| 462 | 2026-01-17T20:50:31.497Z | Jan 17 20:50:31.485 INFO cr2: executing eos script show ipv6 bgp | json |
| 463 | 2026-01-17T20:50:31.498Z | Jan 17 20:50:31.485 DEBG [sc] cr2: starting |
| 464 | 2026-01-17T20:50:31.498Z | Jan 17 20:50:31.485 DEBG [sc] cr2: connecting to [::1]:55207 |
| 465 | 2026-01-17T20:50:31.522Z | Jan 17 20:50:31.486 DEBG [sc] cr2 waiting for prompt |
| 466 | 2026-01-17T20:50:31.522Z | Jan 17 20:50:31.496 DEBG [sc] cr2: logging in |
| 467 | 2026-01-17T20:50:31.641Z | Jan 17 20:50:31.630 DEBG [sc] cr2: executing command `docker exec ceos Cli -c 'show ipv6 bgp | json'` |
| 468 | 2026-01-17T20:50:34.058Z | Jan 17 20:50:34.046 INFO trio bgp unnumbered test passed 🎉 |
| 469 | 2026-01-17T20:50:34.059Z | Jan 17 20:50:34.046 INFO destroying runner for deployment mgtriou |
| 470 | 2026-01-17T20:50:34.059Z | Jan 17 20:50:34.046 INFO destroying deployment mgtriou |
| 471 | 2026-01-17T20:50:34.059Z | Jan 17 20:50:34.046 INFO destroying nodes |
| 472 | 2026-01-17T20:50:34.163Z | Jan 17 20:50:34.152 INFO destroying links |
| 473 | 2026-01-17T20:50:34.163Z | Jan 17 20:50:34.152 INFO destroying link mgtriou_ox_sn_vnic0 |
| 474 | 2026-01-17T20:50:34.188Z | Jan 17 20:50:34.162 INFO destroying link mgtriou_ox_sn_sim0 |
| 475 | 2026-01-17T20:50:34.188Z | Jan 17 20:50:34.164 INFO destroying link mgtriou_cr1_vn_vnic0 |
| 476 | 2026-01-17T20:50:35.178Z | Jan 17 20:50:35.167 INFO destroying link mgtriou_cr1_vn_sim0 |
| 477 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.168 INFO destroying link mgtriou_ox_sn_vnic1 |
| 478 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.169 INFO destroying link mgtriou_ox_sn_sim1 |
| 479 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.171 INFO destroying link mgtriou_cr2_vn_vnic0 |
| 480 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.172 INFO destroying link mgtriou_cr2_vn_sim0 |
| 481 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.173 INFO destroying external links |
| 482 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.173 INFO destroying external link mgtriou_ox_vn_vnic2 |
| 483 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.174 INFO destroying external link mgtriou_cr1_vn_vnic1 |
| 484 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.176 INFO destroying external link mgtriou_cr2_vn_vnic1 |
| 485 | 2026-01-17T20:50:35.203Z | Jan 17 20:50:35.177 INFO destroying images |
| 486 | 2026-01-17T20:50:35.633Z | Jan 17 20:50:35.622 INFO destroying workspace at .falcon |
| 487 | 2026-01-17T20:50:35.658Z | process exited: duration 646497 ms, exit code 0 |
| |
| 488 | 2026-01-17T20:50:35.707Z | found 0 output files |