[stdout] time="2024-06-14T18:43:37Z" level=info msg="Adam waiting for EVE registration (0) of (20)" time="2024-06-14T18:43:57Z" level=info msg="Adam waiting for EVE registration (1) of (20)" time="2024-06-14T18:44:17Z" level=info msg="Adam waiting for EVE registration (2) of (20)" time="2024-06-14T18:44:37Z" level=info msg="Adam waiting for EVE registration (3) of (20)" time="2024-06-14T18:44:57Z" level=info msg="Adam waiting for EVE registration (4) of (20)" time="2024-06-14T18:45:17Z" level=info msg="Adam waiting for EVE registration (5) of (20)" time="2024-06-14T18:45:37Z" level=info msg="Adam waiting for EVE registration (6) of (20)" time="2024-06-14T18:45:57Z" level=info msg="Adam waiting for EVE registration (7) of (20)" time="2024-06-14T18:46:17Z" level=info msg="Adam waiting for EVE registration (8) of (20)" time="2024-06-14T18:46:37Z" level=info msg="Adam waiting for EVE registration (9) of (20)" time="2024-06-14T18:46:57Z" level=info msg="Adam waiting for EVE registration (10) of (20)" time="2024-06-14T18:47:17Z" level=info msg="Adam waiting for EVE registration (11) of (20)" time="2024-06-14T18:47:37Z" level=info msg="Adam waiting for EVE registration (12) of (20)" time="2024-06-14T18:47:57Z" level=info msg="Adam waiting for EVE registration (13) of (20)" time="2024-06-14T18:48:17Z" level=info msg="Adam waiting for EVE registration (14) of (20)" time="2024-06-14T18:48:37Z" level=info msg="Adam waiting for EVE registration (15) of (20)" time="2024-06-14T18:48:57Z" level=info msg="Adam waiting for EVE registration (16) of (20)" time="2024-06-14T18:49:17Z" level=info msg="Adam waiting for EVE registration (17) of (20)" time="2024-06-14T18:49:37Z" level=info msg="Adam waiting for EVE registration (18) of (20)" time="2024-06-14T18:49:57Z" level=info msg="Adam waiting for EVE registration (19) of (20)" time="2024-06-14T18:50:17Z" level=fatal msg="Eve onboard failed: error onboarding onboarding timeout. You may try to run 'eden eve onboard' command again in several minutes. If not successful see logs of adam/eve" [exit status 1] FAIL: testdata/eden_onboard.txt:13: command failure

Process completed with exit code 1.

Process completed with exit code 1.

[stdout] /home/runner/actions-runner/_work/eden/eden/eden/dist/bin/eden sdn fwd eth0 2223 -- ssh -o ConnectTimeout=10 -o StrictHostKeyChecking=no -i /home/runner/actions-runner/_work/eden/eden/eden/dist/tests/eclient/image/cert/id_rsa root@FWD_IP -p FWD_PORT grep -q "before_restart" /etc/injected_file.txt Try 1 time="2024-06-14T19:26:52Z" level=fatal msg="command ssh failed: exit status 255" Try 2 time="2024-06-14T19:27:04Z" level=fatal msg="command ssh failed: exit status 255" Try 3 time="2024-06-14T19:27:16Z" level=fatal msg="command ssh failed: exit status 255" Try 4 time="2024-06-14T19:27:28Z" level=fatal msg="command ssh failed: exit status 255" Try 5 time="2024-06-14T19:27:40Z" level=fatal msg="command ssh failed: exit status 255" Try 6 time="2024-06-14T19:27:52Z" level=fatal msg="command ssh failed: exit status 255" Try 7 time="2024-06-14T19:28:04Z" level=fatal msg="command ssh failed: exit status 255" Try 8 time="2024-06-14T19:28:16Z" level=fatal msg="command ssh failed: exit status 255" Try 9 time="2024-06-14T19:28:28Z" level=fatal msg="command ssh failed: exit status 255" Try 10 time="2024-06-14T19:28:40Z" level=fatal msg="command ssh failed: exit status 255" Try 11 time="2024-06-14T19:28:52Z" level=fatal msg="command ssh failed: exit status 255" Try 12 time="2024-06-14T19:29:04Z" level=fatal msg="command ssh failed: exit status 255" Try 13 time="2024-06-14T19:29:16Z" level=fatal msg="command ssh failed: exit status 255" Try 14 time="2024-06-14T19:29:28Z" level=fatal msg="command ssh failed: exit status 255" Try 15 time="2024-06-14T19:29:40Z" level=fatal msg="command ssh failed: exit status 255" Try 16 time="2024-06-14T19:29:52Z" level=fatal msg="command ssh failed: exit status 255" Try 17 time="2024-06-14T19:30:04Z" level=fatal msg="command ssh failed: exit status 255" Try 18 time="2024-06-14T19:30:16Z" level=fatal msg="command ssh failed: exit status 255" Try 19 time="2024-06-14T19:30:28Z" level=fatal msg="command ssh failed: exit status 255" Try 20 time="2024-06-14T19:30:40Z" level=fatal msg="command ssh failed: exit status 255" Try 21 time="2024-06-14T19:30:52Z" level=fatal msg="command ssh failed: exit status 255" Try 22 time="2024-06-14T19:31:04Z" level=fatal msg="command ssh failed: exit status 255" Try 23 time="2024-06-14T19:31:16Z" level=fatal msg="command ssh failed: exit status 255" Try 24 time="2024-06-14T19:31:28Z" level=fatal msg="command ssh failed: exit status 255" Try 25 time="2024-06-14T19:31:40Z" level=fatal msg="command ssh failed: exit status 255" Try 26 time="2024-06-14T19:31:52Z" level=fatal msg="command ssh failed: exit status 255" Try 27 time="2024-06-14T19:32:04Z" level=fatal msg="command ssh failed: exit status 255" Try 28 time="2024-06-14T19:32:17Z" level=fatal msg="command ssh failed: exit status 255" Try 29 time="2024-06-14T19:32:29Z" level=fatal msg="command ssh failed: exit status 255" Try 30 time="2024-06-14T19:32:41Z" level=fatal msg="command ssh failed: exit status 255" Try 31 time="2024-06-14T19:32:53Z" level=fatal msg="command ssh failed: exit status 255" Try 32 time="2024-06-14T19:33:05Z" level=fatal msg="command ssh failed: exit status 255" Try 33 time="2024-06-14T19:33:17Z" level=fatal msg="command ssh failed: exit status 255" Try 34 [stderr] Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner exchange Connection to 127.0.0.1 port 2223 timed out Connection timed out during banner

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestAppLog lim_test.go:232: time: 2024-06-14T19:34:00.151942698Z out: Wait for app log of 1e18dcc5-7422-4996-88bd-9170ed5b03ca app eclient number=1 timewait=15m0s testContext.go:302: WaitForProc terminated by timeout 15m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 15m0s --- FAIL: TestAppLog (900.00s) FAIL [exit status 1] FAIL: ../eclient/testdata/app_logs.txt:12: command failure

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestAppLog lim_test.go:232: time: 2024-06-14T19:44:23.282451541Z out: Wait for app log of 55838e18-2b2d-4cbc-98c2-a357aa7260ad app eclient number=1 timewait=15m0s testContext.go:302: WaitForProc terminated by timeout 15m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 15m0s --- FAIL: TestAppLog (900.00s) FAIL [exit status 1] FAIL: ../eclient/testdata/app_logs.txt:12: command failure

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[dhcp-server]' state: 'RUNNING' secs: 600 time: 2024-06-14T20:05:19.376507275Z out: appName dhcp-server state changed to UNKNOWN time: 2024-06-14T20:05:21.248726247Z out: appName dhcp-server state changed to RESOLVING_TAG time: 2024-06-14T20:05:22.249540692Z out: appName dhcp-server state changed to DOWNLOAD_STARTED time: 2024-06-14T20:05:22.249571228Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (50%) time: 2024-06-14T20:05:23.249872094Z out: appName dhcp-server state changed to DOWNLOAD_STARTED time: 2024-06-14T20:05:23.249922187Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (50%) time: 2024-06-14T20:05:26.251745664Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (0%) time: 2024-06-14T20:05:27.252246997Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (4%) time: 2024-06-14T20:05:28.253409893Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (14%) time: 2024-06-14T20:05:29.254049724Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (24%) time: 2024-06-14T20:05:29.254092603Z out: appName dhcp-server state changed to DOWNLOAD_STARTED (43%) time: 2024-06-14T20:05:29.254102422Z out: appName dhcp-server state changed to LOADING time: 2024-06-14T20:05:31.255132222Z out: appName dhcp-server state changed to CREATING_VOLUME time: 2024-06-14T20:05:47.263878228Z out: appName dhcp-server state changed to INSTALLED time: 2024-06-14T20:05:48.265262572Z out: appName dhcp-server state changed to BOOTING time: 2024-06-14T20:05:49.265577703Z out: appName dhcp-server state changed to RUNNING testProc.go:84: time: 2024-06-14T20:05:49.265588103Z out: projects.ProcInfoFunc done with return: time: 2024-06-14T20:05:49.265585087Z out: app dhcp-server state RUNNING testContext.go:271: done for device 61c9ef2e-7e79-43cd-82f7-be0ce67ad411 --- PASS: TestAppStatus (30.89s) PASS [stderr] time="2024-06-14T20:05:49Z" level=info msg="time: 2024-06-14T20:05:49.265588103Z out: projects.ProcInfoFunc done with return: \ntime: 2024-06-14T20:05:49.265585087Z out: app dhcp-server state RUNNING\n" > exec -t 2m bash wait_and_get_ifconfig.sh dhcp-server 8027 [stderr] % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:10 --:--:-- 0 curl: (28) Operation timed out after 10001 milliseconds with 0 bytes received % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-06-14T20:15:33.864664311Z out: Wait for info of e45a5662-74e1-4dd2-8fe3-ac7ccb51dbca number=1 timewait=5m0s >>> got info ztype:ZiDevice devId:"e45a5662-74e1-4dd2-8fe3-ac7ccb51dbca" dinfo:{machineArch:"x86_64" cpuArch:"x86_64" platform:"x86_64" ncpu:2 memory:7817 storage:2150 minfo:{manufacturer:"QEMU" productName:"Standard PC (Q35 + ICH9, 2009)" version:"pc-q35-6.2" serialNumber:"31415926" UUID:"Not Settable" biosVendor:"EFI Development Kit II / OVMF" biosVersion:"0.0.0" biosReleaseDate:"02/06/2015"} network:{macAddr:"02:fe:24:97:b1:67" devName:"eth0" IPAddrs:"192.168.0.10" IPAddrs:"fe80::7377:9d76:ffa6:252c" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} ipv4_up:true uplink:true networkErr:{timestamp:{seconds:1718396070 nanos:694602424}} localName:"eth0" proxy:{}} network:{macAddr:"02:fe:b7:98:b1:68" devName:"eth1" IPAddrs:"192.168.0.11" IPAddrs:"fe80::2d49:c281:607d:6e1d" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} ipv4_up:true networkErr:{timestamp:{seconds:1718396070 nanos:579534130}} localName:"eth1" proxy:{}} network:{devName:"eth2" dns:{} networkErr:{description:"link not found for interface eth2" timestamp:{seconds:1718396070 nanos:579653876} severity:SEVERITY_ERROR} localName:"eth2" proxy:{}} assignableAdapters:{type:PhyIoNetEth name:"eth0" members:"eth0" usedByBaseOS:true ioAddressList:{macAddress:"02:fe:24:97:b1:67"} usage:PhyIoUsageMgmtAndApps} assignableAdapters:{type:PhyIoNetEth name:"eth1" members:"eth1" usedByBaseOS:true ioAddressList:{macAddress:"02:fe:b7:98:b1:68"} usage:PhyIoUsageShared} assignableAdapters:{type:PhyIoNetEth name:"eth2" members:"eth2" usedByBaseOS:true usage:PhyIoUsageShared} assignableAdapters:{type:PhyIoUSB name:"USB0" members:"USB0:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB1" members:"USB0:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB2" members:"USB0:3" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB3" members:"USB1:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB4" members:"USB1:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB5" members:"USB1:3" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB6" members:"USB2:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB7" members:"USB2:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB8" members:"USB2:3" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB9" members:"USB3:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB10" members:"USB3:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB11" members:"USB3:3" usage:PhyIoUsageDedicated} dns:{DNSservers:"192.168.0.3:53"} storageList:{device:"sde" total:4096} storageList:{mountPath:"/persist/certs"} storageList:{device:"nbd2"} storageList:{device:"nbd5"} storageList:{device:"sdc" total:4096} storageList:{device:"sdb1" total:4086} storageList:{device:"nbd1"} storageList:{mountPath:"/persist/vault/volumes"} storageList:{device:"nbd11"} storageList:{mountPath:"/persist/status"} storageList:{device:"sda2" total:512} storageList:{device:"nbd12"} storageList:{mountPath:"/persist/log"} storageList:{mountPath:"/" total:3909} storageList:{device:"nbd15"} storageList:{mountPath:"/config"} storageList:{device:"sda3" total:512} storageList:{device:"sdb" total:4096} storageList:{mountPath:"/persist/containerd"} storageList:{device:"nbd9"} storageList:{device:"nbd0"} storageList:{mountPath:"/persist/tmp"} storageList:{device:"nbd6"} storageList:{mountPath:"/persist/newlog"} storageList:{device:"sda4" total:1} storageList:{device:"sda" total:4096} storageList:{device:"sdb9" total:8} storageList:{device:"sdd" total:4096} storageList:{device:"nbd10"} storageList:{device:"nbd3"} storageList:{device:"nbd13"} storageList:{mountPath:"/persist/vault/downloader"} storageList:{mountPath:"/persist/checkpoint"} storageList:{device:"sda1" total:36}

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[eclient-mount]' state: 'RUNNING' secs: 600 time: 2024-06-14T20:15:50.702737985Z out: appName eclient-mount state changed to HALTING app_test.go:171: ASSERTION FAILED (2024-06-14T20:25:50.702984936Z): expected apps [eclient-mount] in RUNNING state app_test.go:173: actual eclient-mount: HALTING app_test.go:175: history of states for eclient-mount: app_test.go:177: state: no info from controller received in: 2024-06-14T20:15:50.697170018Z app_test.go:177: state: HALTING received in: 2024-06-14T20:15:50.702736542Z --- app eclient-mount logs --- {"source":"fe53c9c1-1f4e-41f9-a8d0-3ec14e116d93.1.1","content":"brctl: bridge bn1: Resource busy","msgid":"1","timestamp":"2024-06-14T20:15:26.401196882Z"} ------ testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 10m0s --- FAIL: TestAppStatus (601.01s) FAIL [exit status 1] FAIL: ../volume/testdata/volumes_test.txt:67: command failure

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[app-profile-1 app-profile-2 app-profile-1-2 local-manager]' state: 'RUNNING' secs: 1200 time: 2024-06-14T20:06:59.744302391Z out: appName local-manager state changed to RUNNING time: 2024-06-14T20:06:59.744315564Z out: appName app-profile-1 state changed to INITIAL time: 2024-06-14T20:06:59.744317388Z out: appName app-profile-2 state changed to UNKNOWN time: 2024-06-14T20:06:59.744318891Z out: appName app-profile-1-2 state changed to UNKNOWN time: 2024-06-14T20:06:59.751096823Z out: appName app-profile-2 state changed to INITIAL time: 2024-06-14T20:07:00.751696365Z out: appName app-profile-1 state changed to RESOLVING_TAG time: 2024-06-14T20:07:00.751765866Z out: appName app-profile-1-2 state changed to RESOLVING_TAG time: 2024-06-14T20:07:00.751826508Z out: appName app-profile-2 state changed to RESOLVING_TAG time: 2024-06-14T20:07:00.751959117Z out: appName app-profile-2 state changed to UNKNOWN time: 2024-06-14T20:07:00.751966441Z out: appName app-profile-1-2 state changed to UNKNOWN time: 2024-06-14T20:07:00.752061738Z out: appName app-profile-1-2 state changed to LOADING time: 2024-06-14T20:07:00.752123284Z out: appName app-profile-1 state changed to LOADING time: 2024-06-14T20:07:01.752881723Z out: appName app-profile-2 state changed to CREATING_VOLUME time: 2024-06-14T20:07:01.75289604Z out: appName app-profile-1 state changed to CREATING_VOLUME time: 2024-06-14T20:07:01.752900268Z out: appName app-profile-1-2 state changed to CREATING_VOLUME time: 2024-06-14T20:07:02.753575282Z out: appName app-profile-1 state changed to INSTALLED time: 2024-06-14T20:07:02.753633632Z out: appName app-profile-1-2 state changed to INSTALLED time: 2024-06-14T20:07:02.753647518Z out: appName app-profile-2 state changed to INSTALLED time: 2024-06-14T20:07:03.754100205Z out: appName app-profile-1 state changed to BOOTING time: 2024-06-14T20:07:03.754142955Z out: appName app-profile-1-2 state changed to BOOTING time: 2024-06-14T20:07:04.754553563Z out: appName app-profile-2 state changed to BOOTING time: 2024-06-14T20:08:42.851549542Z out: appName app-profile-1 state changed to HALTING: [description:"Giving up waiting to connect to QEMU Monitor Protocol socket /run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp from VM 3395c34c-d8c3-4788-89ec-011f30c23257.1.2, error: [attempt 1] qmp status failed for QMP socket '/run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp': err: 'dial unix /run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp: connect: connection refused'; (JSON response: ''); [attempt 2] qmp status failed for QMP socket '/run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp': err: 'dial unix /run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp: connect: connection refused'; (JSON response: ''); [attempt 3] qmp status failed for QMP socket '/run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp': err: 'dial unix /run/hypervisor/kvm/3395c34c-d8c3-4788-89ec-011f30c23257.1.2/qmp: connect: connection refused'; (JSON response: '')" timestamp:{seconds:1718395697 nanos:708695848} severity:SEVERITY_ERROR] time: 2024-06-14T20:08:42.851613832Z out: appName app-profile-1-2 state changed to HALTING: [description:"Giving up waiting to connect to QEMU Monitor Protocol socket /run/hypervisor/kvm/c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3/qmp from VM c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3, error: [attempt 1] qmp status failed for QMP socket '/run/hypervisor/kvm/c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3/qmp': err: 'dial unix /run/hypervisor/kvm/c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3/qmp: connect: connection refused'; (JSON response: ''); [attempt 2] qmp status failed for QMP socket '/run/hypervisor/kvm/c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3/qmp': err: 'dial unix /run/hypervisor/kvm/c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3/qmp: connect: connection refused'; (JSON response: ''); [attempt 3] qmp status failed for QMP socket '/run/hypervisor/kvm/c3e89a46-4251-4b82-a3c2-7e08d2f72f22.1.3/qmp':

Process completed with exit code 1.

[stdout] Docker app deployment Test === RUN TestDockerStart docker_test.go:189: time: 2024-06-14T20:10:49.883628708Z out: Add app to list config changed, to see config run 'eden controller edge-node get-config' docker_test.go:195: time: 2024-06-14T20:10:50.902155633Z out: Add processing of app messages docker_test.go:199: time: 2024-06-14T20:10:50.902188484Z out: Add processing of app running messages docker_test.go:203: time: 2024-06-14T20:10:50.902199194Z out: Add function to obtain EVE IP docker_test.go:207: time: 2024-06-14T20:10:50.90220757Z out: Add trying to access app via http testProc.go:84: time: 2024-06-14T20:10:54.424713475Z out: projects.ProcInfoFunc done with return: app found with name t2 testProc.go:84: time: 2024-06-14T20:10:58.411265311Z out: projects.ProcTimerFunc done with return: external ip is: 127.0.0.1 testContext.go:302: WaitForProc terminated by timeout 20m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 20m0s --- FAIL: TestDockerStart (1202.49s) FAIL [stderr] time="2024-06-14T20:10:54Z" level=info msg="time: 2024-06-14T20:10:54.424713475Z out: projects.ProcInfoFunc done with return: app found with name t2" time="2024-06-14T20:10:58Z" level=info msg="time: 2024-06-14T20:10:58.411265311Z out: projects.ProcTimerFunc done with return: external ip is: 127.0.0.1" [exit status 1] FAIL: ../docker/testdata/2dockers_test.txt:15: command failure

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-06-14T20:04:54.880885136Z out: Wait for info of 797b7383-1b90-48b6-b4b8-c5b6b5fe98e3 number=1 timewait=30m0s >>> got info ztype:ZiDevice devId:"797b7383-1b90-48b6-b4b8-c5b6b5fe98e3" dinfo:{machineArch:"x86_64" cpuArch:"x86_64" platform:"x86_64" ncpu:2 memory:7817 minfo:{manufacturer:"QEMU" productName:"Standard PC (Q35 + ICH9, 2009)" version:"pc-q35-6.2" serialNumber:"31415926" UUID:"Not Settable" biosVendor:"EFI Development Kit II / OVMF" biosVersion:"0.0.0" biosReleaseDate:"02/06/2015"} network:{macAddr:"02:fe:24:97:b1:67" devName:"eth0" IPAddrs:"192.168.0.10" IPAddrs:"fe80::daaf:de1:47d0:72c8" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} ipv4_up:true uplink:true networkErr:{timestamp:{seconds:1718395414 nanos:458927024}} localName:"eth0" proxy:{}} network:{macAddr:"02:fe:b7:98:b1:68" devName:"eth1" IPAddrs:"192.168.0.11" IPAddrs:"fe80::41b5:3773:f959:3ed7" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} ipv4_up:true uplink:true networkErr:{timestamp:{seconds:1718395407 nanos:284714480}} localName:"eth1" proxy:{}} dns:{DNSservers:"192.168.0.3:53"} bootTime:{seconds:1718395379} swList:{activated:true partitionLabel:"IMGA" partitionDevice:"/dev/sda2" partitionState:"active" status:INSTALLED shortVersion:"10.4.1-12-gbf81b337c-20-g0c123dcd5-16-g1dc475d1d-1-g7cb44c48c-1-g0742065b8-1-g085bfb6c2-671-gfbeb08573f-kvm-amd64" downloadProgress:100 userStatus:UPDATED} swList:{partitionLabel:"IMGB" partitionDevice:"/dev/sda3" partitionState:"unused" status:INITIAL} HostName:"797b7383-1b90-48b6-b4b8-c5b6b5fe98e3" lastRebootReason:"NORMAL: First boot of device - at 2024-06-14T20:03:24.60408741Z" lastRebootTime:{seconds:1718395404 nanos:604087410} systemAdapter:{status:{version:1 key:"lastresort" timePriority:{} lastSucceeded:{seconds:1718395414 nanos:458983622} ports:{ifname:"eth0" name:"eth0" isMgmt:true free:true dhcpType:4 subnet:"192.168.0.0/24" proxy:{} macAddr:"02:fe:24:97:b1:67" IPAddrs:"192.168.0.10" IPAddrs:"fe80::daaf:de1:47d0:72c8" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} up:true err:{timestamp:{seconds:1718395414 nanos:458927024}} networkUUID:"00000000-0000-0000-0000-000000000000" mtu:1500} ports:{ifname:"eth1" name:"eth1" isMgmt:true free:true dhcpType:4 subnet:"192.168.0.0/24" proxy:{} macAddr:"02:fe:b7:98:b1:68" IPAddrs:"192.168.0.11" IPAddrs:"fe80::41b5:3773:f959:3ed7" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} up:true err:{timestamp:{seconds:1718395407 nanos:284714480}} networkUUID:"00000000-0000-0000-0000-000000000000" mtu:1500}}} HSMStatus:ENABLED HSMInfo:"IBM-SW TPM, FW Version 8217.4131.22.13878" dataSecAtRestInfo:{status:DATASEC_AT_REST_ENABLED info:"Fscrypt is enabled and active" vaultList:{name:"Application Data Store" status:DATASEC_AT_REST_ENABLED pcrStatus:PCR_ENABLED}} sec_info:{sha_root_ca:"\x95\xa2-I\x06\x8b\x8d\x91Q\x9b\x8c\x06\x96u:\xa8\xa9\xc0\xfa&\xecv\x8e\x88S\xa2\xe9Z\xf6\x08\x16\xdd" sha_tls_root_ca:"\xef\x02^\xbd\xe1\x1e\xce\xd2\x08\xc0\x90\xb7\xf8\xe3\xc9O\x99Ӵ3\x90C\xcfԔ,\n7\xf6\n\x1aJ"} configItemStatus:{configItems:{key:"app.allow.vnc" value:{value:"true"}} configItems:{key:"debug.default.loglevel" value:{value:"info"}} configItems:{key:"debug.default.remote.loglevel" value:{value:"warning"}} configItems:{key:"debug.enable.console" value:{value:"true"}} configItems:{key:"debug.enable.ssh" value:{value:"ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDFU950y80nhnaqFfDsNUgaoB+rl93Q2q9mDbT7zhD6UE0AfDKXR6X7djL43CN1OqOC8mL8jRJH6PvWINlDAjX5oaN73SjpKgb3kAgR5pwAsjG1GjIMiHfOe0Gi++feS+MpQ8sqxogRcpmUz1tZMX83pYMo4MGvhHeBYis7IPc9UR1SYfacxQwhNovmmqehvF8dpf8y8d6yfjPeBVzLRUizwvYWjKdUi7mCAYnx5zP/mrfpaLeiXgyi+PqzcXCzZotU3DDvirFQpoNDxckdOMcObMgTy6r+jz5qx7fxUk6tkKGqAEmAouWoIzrF7friZyotOk//NXX362vuCrWzqZQ6zYoAy5aVjksxGYiQmdImTGbA3ZcPUgoQN+VCrV6SqNTV+ZhjEUdjYILMXK5CSgV3l3qClzZsQ+h8X2NS+4H2toXq4cgIuI+paWxtvn346KlJ0iwxoqKHxxaILeVDNubbScP84aN3a/8RjswNKJi2hiDXQNm3zkgXDjdZx4JBTRRD8hDSi3R+EGqEwxHcFdRru60mcX5/l12hr5V1jvW7poYFqenHVell0kUripjknfO52ztr/gjXfl+oTVQYk+aQIXCNiDUA+QPmuQS

Process completed with exit code 1.

Process completed with exit code 1.

[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-06-14T20:10:55.080901574Z out: Wait for info of 2b17c1e4-98f3-484b-a249-1c552f9c75df number=1 timewait=30m0s >>> got info ztype:ZiContentTree devId:"2b17c1e4-98f3-484b-a249-1c552f9c75df" cinfo:{uuid:"5b6a5c89-7439-4f23-92d7-d1796e9d1a53" displayName:"lfedge/eve:9.6.0-kvm-amd64" resources:{} usage:{createTime:{seconds:-62135596800} refCount:1 lastRefcountChangeTime:{seconds:-62135596800}} state:RESOLVING_TAG} atTimeStamp:{seconds:1718395858 nanos:432193530} >>> got info ztype:ZiDevice devId:"2b17c1e4-98f3-484b-a249-1c552f9c75df" dinfo:{machineArch:"x86_64" cpuArch:"x86_64" platform:"x86_64" ncpu:2 memory:7817 storage:2150 minfo:{manufacturer:"QEMU" productName:"Standard PC (Q35 + ICH9, 2009)" version:"pc-q35-6.2" serialNumber:"31415926" UUID:"Not Settable" biosVendor:"EFI Development Kit II / OVMF" biosVersion:"0.0.0" biosReleaseDate:"02/06/2015"} network:{macAddr:"02:fe:24:97:b1:67" devName:"eth0" IPAddrs:"192.168.0.10" IPAddrs:"fe80::b1a4:503d:ea5f:27c9" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} ipv4_up:true uplink:true networkErr:{timestamp:{seconds:1718395823 nanos:796995120}} localName:"eth0" proxy:{}} network:{macAddr:"02:fe:b7:98:b1:68" devName:"eth1" IPAddrs:"192.168.0.11" IPAddrs:"fe80::8746:785c:f08b:2cc1" defaultRouters:"192.168.0.2" dns:{DNSservers:"192.168.0.3"} ipv4_up:true networkErr:{timestamp:{seconds:1718395823 nanos:797021620}} localName:"eth1" proxy:{}} network:{devName:"eth2" dns:{} networkErr:{description:"link not found for interface eth2" timestamp:{seconds:1718395823 nanos:769045050} severity:SEVERITY_ERROR} localName:"eth2" proxy:{}} assignableAdapters:{type:PhyIoNetEth name:"eth0" members:"eth0" usedByBaseOS:true ioAddressList:{macAddress:"02:fe:24:97:b1:67"} usage:PhyIoUsageMgmtAndApps} assignableAdapters:{type:PhyIoNetEth name:"eth1" members:"eth1" usedByBaseOS:true ioAddressList:{macAddress:"02:fe:b7:98:b1:68"} usage:PhyIoUsageShared} assignableAdapters:{type:PhyIoNetEth name:"eth2" members:"eth2" usedByBaseOS:true usage:PhyIoUsageShared} assignableAdapters:{type:PhyIoUSB name:"USB0" members:"USB0:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB1" members:"USB0:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB2" members:"USB0:3" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB3" members:"USB1:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB4" members:"USB1:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB5" members:"USB1:3" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB6" members:"USB2:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB7" members:"USB2:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB8" members:"USB2:3" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB9" members:"USB3:1" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB10" members:"USB3:2" usage:PhyIoUsageDedicated} assignableAdapters:{type:PhyIoUSB name:"USB11" members:"USB3:3" usage:PhyIoUsageDedicated} dns:{DNSservers:"192.168.0.3:53"} storageList:{mountPath:"/persist/vault/downloader"} storageList:{mountPath:"/" total:3909} storageList:{device:"sdb" total:4096} storageList:{mountPath:"/persist/checkpoint"} storageList:{device:"nbd15"} storageList:{device:"nbd3"} storageList:{mountPath:"/persist/status"} storageList:{device:"nbd8"} storageList:{device:"sda3" total:512} storageList:{device:"sda4" total:1} storageList:{device:"sdc" total:4096} storageList:{device:"sda2" total:512} storageList:{mountPath:"/persist/clear/volumes"} storageList:{device:"sda1" total:36} storageList:{device:"nbd5"} storageList:{mountPath:"/persist/tmp"} storageList:{device:"nbd2"} storageList:{device:"sda" total:4096} storageList:{device:"sda9" total:3033} storageList:{device:"nbd0"} storageList:{mountPath:"/persist/log"} storageList:{mountPath:"/persist/vault/volumes"} storageList:{device:"nbd13"} storageList:{de

Process completed with exit code 1.

Process completed with exit code 1.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.

This job failure may be caused by using an out of date self-hosted runner. You are currently using runner version 2.316.1. Please update to the latest version 2.317.0

Node.js 16 actions are deprecated. Please update the following actions to use Node.js 20: actions/setup-go@v3, actions/upload-artifact@v3. For more information see: https://github.blog/changelog/2023-09-22-github-actions-transitioning-from-node-16-to-node-20/.