[stdout] Docker app's state test === RUN TestAppStatus apps: '[eclient]' state: 'RUNNING' secs: 600 time: 2023-07-31T09:25:23.961357895Z out: appName eclient state changed to UNKNOWN time: 2023-07-31T09:25:27.549166499Z out: appName eclient state changed to INITIAL time: 2023-07-31T09:25:51.563365016Z out: appName eclient state changed to INSTALLED time: 2023-07-31T09:30:43.699067431Z out: appName eclient state changed to INITIAL time: 2023-07-31T09:31:08.712337568Z out: appName eclient state changed to INSTALLED app_test.go:171: ASSERTION FAILED (2023-07-31T09:35:23.961681889Z): expected apps [eclient] in RUNNING state app_test.go:173: actual eclient: INSTALLED app_test.go:175: history of states for eclient: app_test.go:177: state: no info from controller received in: 2023-07-31T09:25:23.961356002Z app_test.go:177: state: UNKNOWN received in: 2023-07-31T09:25:23.961357184Z app_test.go:177: state: INITIAL received in: 2023-07-31T09:25:27.549162381Z app_test.go:177: state: INSTALLED received in: 2023-07-31T09:25:51.5633618Z app_test.go:177: state: INITIAL received in: 2023-07-31T09:30:43.699054677Z app_test.go:177: state: INSTALLED received in: 2023-07-31T09:31:08.712334663Z --- app eclient logs --- {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Found user 'avahi' (UID 86) and group 'avahi' (GID 86).","msgid":"1","timestamp":"2023-07-31T09:17:20.680797891Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Successfully dropped root privileges.","msgid":"2","timestamp":"2023-07-31T09:17:20.687463955Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"avahi-daemon 0.8 starting up.","msgid":"3","timestamp":"2023-07-31T09:17:20.693913541Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"WARNING: No NSS support for mDNS detected, consider installing nss-mdns!","msgid":"4","timestamp":"2023-07-31T09:17:20.702421457Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Successfully called chroot().","msgid":"5","timestamp":"2023-07-31T09:17:20.707831086Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Successfully dropped remaining capabilities.","msgid":"6","timestamp":"2023-07-31T09:17:20.710973212Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Loading service file /services/http.service.","msgid":"7","timestamp":"2023-07-31T09:17:20.735742901Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Loading service file /services/sftp-ssh.service.","msgid":"8","timestamp":"2023-07-31T09:17:20.735818390Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Loading service file /services/ssh.service.","msgid":"9","timestamp":"2023-07-31T09:17:20.736064241Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Joining mDNS multicast group on interface nbu1x1.1.IPv4 with address 10.11.12.2.","msgid":"10","timestamp":"2023-07-31T09:17:20.745842907Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"New relevant interface nbu1x1.1.IPv4 for mDNS.","msgid":"11","timestamp":"2023-07-31T09:17:20.748311177Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.","msgid":"12","timestamp":"2023-07-31T09:17:20.749979780Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"New relevant interface lo.IPv4 for mDNS.","msgid":"13","timestamp":"2023-07-31T09:17:20.755023156Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Network interface enumeration completed.","msgid":"14","timestamp":"2023-07-31T09:17:20.755359713Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Registering new address record for fe80::90da:3aff:fe45:ac28 on nbu1x1.1.*.","msgid":"15","timestamp":"2023-07-31T09:17:20.755431645Z"} {"source":"5228beee-9305-44db-823e-888c2e2d4d0a.1.1","content":"Registering new address record for 10.11.12.2 on nbu1x1.1.IPv4.","msgid":"16","timestamp":"2023-07-31T09:17:20.755612157Z"} {"source":"5

Process completed with exit code 1.

[stdout] time="2023-07-31T10:18:41Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:18:55Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:19:09Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:19:22Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:19:36Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:19:49Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:20:03Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:20:17Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:20:30Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:20:44Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:20:58Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:21:11Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:21:25Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:21:39Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:21:53Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:22:06Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:22:20Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:22:34Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:22:47Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:23:01Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:23:15Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:23:28Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:23:42Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:23:56Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:24:09Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:24:23Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:24:37Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:24:50Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:25:04Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:25:18Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:25:31Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:25:45Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:25:59Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:26:12Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:26:26Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:26:40Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:26:53Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:27:07Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:27:21Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:27:34Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:27:48Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:28:02Z" level=fatal msg="command ssh failed: exit status 1" time="2023-07-31T10:28:15Z" level=fatal msg="command ssh failed: exit status 1" [stderr] ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server on '10.11.12.3' (115) ERROR 2002 (HY000): Can't connect to server

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[t1 t2]' state: 'RUNNING' secs: 600 time: 2023-07-31T10:31:22.044767094Z out: appName t1 state changed to UNKNOWN time: 2023-07-31T10:31:22.044777794Z out: appName t2 state changed to UNKNOWN time: 2023-07-31T10:31:26.828738511Z out: appName t1 state changed to INITIAL time: 2023-07-31T10:31:27.829570175Z out: appName t2 state changed to RESOLVING_TAG time: 2023-07-31T10:31:27.829604199Z out: appName t1 state changed to RESOLVING_TAG time: 2023-07-31T10:31:29.830750191Z out: appName t2 state changed to DOWNLOAD_STARTED time: 2023-07-31T10:31:29.830774668Z out: appName t1 state changed to DOWNLOAD_STARTED time: 2023-07-31T10:31:29.830806587Z out: appName t1 state changed to DOWNLOAD_STARTED (0%) time: 2023-07-31T10:31:29.830810625Z out: appName t2 state changed to DOWNLOAD_STARTED (0%) time: 2023-07-31T10:31:37.835669657Z out: appName t1 state changed to DOWNLOAD_STARTED (100%) time: 2023-07-31T10:31:52.84584656Z out: appName t2 state changed to DOWNLOAD_STARTED (100%) time: 2023-07-31T10:31:59.850662534Z out: appName t2 state changed to DOWNLOAD_STARTED (0%) time: 2023-07-31T10:32:02.852697434Z out: appName t1 state changed to DOWNLOAD_STARTED (0%) time: 2023-07-31T10:32:07.856720274Z out: appName t2 state changed to DOWNLOAD_STARTED (3%) time: 2023-07-31T10:32:08.857830205Z out: appName t1 state changed to DOWNLOAD_STARTED (4%) time: 2023-07-31T10:32:08.857846396Z out: appName t2 state changed to DOWNLOAD_STARTED (4%) time: 2023-07-31T10:32:09.858504474Z out: appName t2 state changed to DOWNLOAD_STARTED (8%) time: 2023-07-31T10:32:12.861267089Z out: appName t1 state changed to DOWNLOAD_STARTED (8%) time: 2023-07-31T10:32:15.86389572Z out: appName t1 state changed to DOWNLOAD_STARTED (9%) time: 2023-07-31T10:32:15.863940564Z out: appName t2 state changed to DOWNLOAD_STARTED (10%) time: 2023-07-31T10:32:17.865403618Z out: appName t1 state changed to DOWNLOAD_STARTED (10%) time: 2023-07-31T10:32:18.865810683Z out: appName t2 state changed to DOWNLOAD_STARTED (12%) time: 2023-07-31T10:32:19.866407493Z out: appName t1 state changed to DOWNLOAD_STARTED (14%) time: 2023-07-31T10:32:19.86644865Z out: appName t2 state changed to DOWNLOAD_STARTED (14%) time: 2023-07-31T10:32:19.866458959Z out: appName t1 state changed to DOWNLOAD_STARTED (15%) time: 2023-07-31T10:32:22.868190381Z out: appName t2 state changed to DOWNLOAD_STARTED (15%) time: 2023-07-31T10:32:23.86936898Z out: appName t2 state changed to DOWNLOAD_STARTED (47%) time: 2023-07-31T10:32:24.870132167Z out: appName t1 state changed to DOWNLOAD_STARTED (47%) time: 2023-07-31T10:32:24.870155471Z out: appName t2 state changed to DOWNLOAD_STARTED (80%) time: 2023-07-31T10:32:25.871228509Z out: appName t1 state changed to DOWNLOAD_STARTED (80%) time: 2023-07-31T10:32:27.87297117Z out: appName t2 state changed to DOWNLOAD_STARTED (82%) time: 2023-07-31T10:32:28.874158289Z out: appName t1 state changed to DOWNLOAD_STARTED (82%) time: 2023-07-31T10:32:29.875125091Z out: appName t1 state changed to DOWNLOAD_STARTED (83%) time: 2023-07-31T10:32:29.875156329Z out: appName t2 state changed to DOWNLOAD_STARTED (83%) time: 2023-07-31T10:32:30.876014655Z out: appName t1 state changed to DOWNLOAD_STARTED (86%) time: 2023-07-31T10:32:31.87634252Z out: appName t2 state changed to DOWNLOAD_STARTED (85%) time: 2023-07-31T10:32:32.877116324Z out: appName t2 state changed to DOWNLOAD_STARTED (89%) time: 2023-07-31T10:32:32.877155598Z out: appName t1 state changed to DOWNLOAD_STARTED (89%) time: 2023-07-31T10:32:32.877168712Z out: appName t1 state changed to DOWNLOAD_STARTED (93%) time: 2023-07-31T10:32:33.877577316Z out: appName t2 state changed to DOWNLOAD_STARTED (93%) time: 2023-07-31T10:33:20.908394111Z out: appName t1 state changed to DOWNLOAD_STARTED (100%) time: 2023-07-31T10:33:36.918110925Z out: appName t2 state changed to LOADING time: 2023-07-31T10:33:36.918124402Z out: appName t1 state changed to VERIFIED time: 2023-07-31T10:34:16.941732499Z out: appName t1 state changed to

Process completed with exit code 1.

[stdout] VNC access to app Test === RUN TestVNCVMStart vnc_test.go:245: Add app to list config changed, to see config run 'eden controller edge-node get-config' vnc_test.go:251: Add processing of app running messages vnc_test.go:101: state: INITIAL received in: 2023-07-31T10:35:15.159991319Z vnc_test.go:101: state: DOWNLOAD_STARTED received in: 2023-07-31T10:35:18.162686738Z vnc_test.go:101: state: LOADING received in: 2023-07-31T10:37:37.260175384Z vnc_test.go:101: state: CREATING_VOLUME received in: 2023-07-31T10:40:05.345944203Z vnc_test.go:101: state: INSTALLED received in: 2023-07-31T10:42:19.42610415Z --- app vnc-app logs --- ------ testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 15m0s --- FAIL: TestVNCVMStart (901.03s) FAIL [stderr] time="2023-07-31T10:35:06Z" level=info msg="Starting download of image from https://cloud-images.ubuntu.com/releases/groovy/release-20210108/ubuntu-20.10-server-cloudimg-amd64.img" [exit status 1] FAIL: ../vnc/testdata/vnc_test.txt:7: command failure