[stdout] time="2024-06-26T16:24:58Z" level=info msg="Adam waiting for EVE registration (0) of (20)" time="2024-06-26T16:25:18Z" level=info msg="Adam waiting for EVE registration (1) of (20)" time="2024-06-26T16:25:38Z" level=info msg="Adam waiting for EVE registration (2) of (20)" time="2024-06-26T16:25:58Z" level=info msg="Adam waiting for EVE registration (3) of (20)" time="2024-06-26T16:26:18Z" level=info msg="Adam waiting for EVE registration (4) of (20)" time="2024-06-26T16:26:38Z" level=info msg="Adam waiting for EVE registration (5) of (20)" time="2024-06-26T16:26:58Z" level=info msg="Adam waiting for EVE registration (6) of (20)" time="2024-06-26T16:27:18Z" level=info msg="Adam waiting for EVE registration (7) of (20)" time="2024-06-26T16:27:38Z" level=info msg="Adam waiting for EVE registration (8) of (20)" time="2024-06-26T16:27:58Z" level=info msg="Adam waiting for EVE registration (9) of (20)" time="2024-06-26T16:28:18Z" level=info msg="Adam waiting for EVE registration (10) of (20)" time="2024-06-26T16:28:38Z" level=info msg="Adam waiting for EVE registration (11) of (20)" time="2024-06-26T16:28:58Z" level=info msg="Adam waiting for EVE registration (12) of (20)" time="2024-06-26T16:29:18Z" level=info msg="Adam waiting for EVE registration (13) of (20)" time="2024-06-26T16:29:38Z" level=info msg="Adam waiting for EVE registration (14) of (20)" time="2024-06-26T16:29:58Z" level=info msg="Adam waiting for EVE registration (15) of (20)" time="2024-06-26T16:30:18Z" level=info msg="Adam waiting for EVE registration (16) of (20)" time="2024-06-26T16:30:38Z" level=info msg="Adam waiting for EVE registration (17) of (20)" time="2024-06-26T16:30:58Z" level=info msg="Adam waiting for EVE registration (18) of (20)" time="2024-06-26T16:31:18Z" level=info msg="Adam waiting for EVE registration (19) of (20)" time="2024-06-26T16:31:38Z" 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] time="2024-06-26T16:25:10Z" level=info msg="Adam waiting for EVE registration (0) of (20)" time="2024-06-26T16:25:30Z" level=info msg="Adam waiting for EVE registration (1) of (20)" time="2024-06-26T16:25:50Z" level=info msg="Adam waiting for EVE registration (2) of (20)" time="2024-06-26T16:26:10Z" level=info msg="Adam waiting for EVE registration (3) of (20)" time="2024-06-26T16:26:30Z" level=info msg="Adam waiting for EVE registration (4) of (20)" time="2024-06-26T16:26:50Z" level=info msg="Adam waiting for EVE registration (5) of (20)" time="2024-06-26T16:27:10Z" level=info msg="Adam waiting for EVE registration (6) of (20)" time="2024-06-26T16:27:30Z" level=info msg="Adam waiting for EVE registration (7) of (20)" time="2024-06-26T16:27:50Z" level=info msg="Adam waiting for EVE registration (8) of (20)" time="2024-06-26T16:28:10Z" level=info msg="Adam waiting for EVE registration (9) of (20)" time="2024-06-26T16:28:30Z" level=info msg="Adam waiting for EVE registration (10) of (20)" time="2024-06-26T16:28:50Z" level=info msg="Adam waiting for EVE registration (11) of (20)" time="2024-06-26T16:29:10Z" level=info msg="Adam waiting for EVE registration (12) of (20)" time="2024-06-26T16:29:30Z" level=info msg="Adam waiting for EVE registration (13) of (20)" time="2024-06-26T16:29:50Z" level=info msg="Adam waiting for EVE registration (14) of (20)" time="2024-06-26T16:30:10Z" level=info msg="Adam waiting for EVE registration (15) of (20)" time="2024-06-26T16:30:30Z" level=info msg="Adam waiting for EVE registration (16) of (20)" time="2024-06-26T16:30:50Z" level=info msg="Adam waiting for EVE registration (17) of (20)" time="2024-06-26T16:31:10Z" level=info msg="Adam waiting for EVE registration (18) of (20)" time="2024-06-26T16:31:30Z" level=info msg="Adam waiting for EVE registration (19) of (20)" time="2024-06-26T16:31:50Z" 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] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2024-06-26T16:30:23.817500641Z out: Wait for state of 55f72fca-5799-4bff-bba5-d37a52d76f46 testContext.go:380: done waiting for State reboot_test.go:166: time: 2024-06-26T16:30:23.817564711Z out: timewait: 20m0s reboot_test.go:167: time: 2024-06-26T16:30:23.817581663Z out: reboot: false reboot_test.go:168: time: 2024-06-26T16:30:23.817590149Z out: count: 2 reboot_test.go:172: time: 2024-06-26T16:30:23.817600738Z out: LastRebootTime: 2024-06-26 16:27:41.578367855 +0000 UTC reboot_test.go:174: time: 2024-06-26T16:30:23.81761742Z out: LastRebootReason: NORMAL: First boot of device - at 2024-06-26T16:27:41.578367855Z rebooted with reason Reboot reason - kernel crash (no kdump) - at 2024-06-26T16:43:40.713309547Z at 2024-06-26 16:43:40.713309547 +0000 UTC/n testProc.go:84: time: 2024-06-26T16:44:50.78134095Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - kernel crash (no kdump) - at 2024-06-26T16:43:40.713309547Z testContext.go:271: done for device 55f72fca-5799-4bff-bba5-d37a52d76f46 reboot_test.go:186: time: 2024-06-26T16:44:50.7814209Z out: Number of reboots: 0 --- PASS: TestReboot (866.96s) PASS [stderr] time="2024-06-26T16:44:50Z" level=info msg="time: 2024-06-26T16:44:50.78134095Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - kernel crash (no kdump) - at 2024-06-26T16:43:40.713309547Z" FAIL: ../docker/testdata/2dockers_test.txt:13: unexpected command success

[stdout] Docker app deployment Test === RUN TestDockerStart docker_test.go:189: time: 2024-06-26T16:30:24.25835943Z out: Add app to list config changed, to see config run 'eden controller edge-node get-config' docker_test.go:195: time: 2024-06-26T16:30:25.266671967Z out: Add processing of app messages docker_test.go:199: time: 2024-06-26T16:30:25.266701623Z out: Add processing of app running messages docker_test.go:203: time: 2024-06-26T16:30:25.266715118Z out: Add function to obtain EVE IP docker_test.go:207: time: 2024-06-26T16:30:25.266724626Z out: Add trying to access app via http testProc.go:84: time: 2024-06-26T16:30:33.821020684Z out: projects.ProcTimerFunc done with return: external ip is: 127.0.0.1 testProc.go:84: time: 2024-06-26T16:31:01.230999008Z out: projects.ProcInfoFunc done with return: app found with name t1 [stderr] time="2024-06-26T16:30:33Z" level=info msg="time: 2024-06-26T16:30:33.821020684Z out: projects.ProcTimerFunc done with return: external ip is: 127.0.0.1" time="2024-06-26T16:31:01Z" level=info msg="time: 2024-06-26T16:31:01.230999008Z out: projects.ProcInfoFunc done with return: app found with name t1" [context canceled] FAIL: ../docker/testdata/2dockers_test.txt:13: test interrupted while running command

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[eclient]' state: 'RUNNING' secs: 1200 time: 2024-06-26T16:31:08.467647785Z out: appName eclient state changed to UNKNOWN time: 2024-06-26T16:31:16.790466814Z out: appName eclient state changed to INITIAL time: 2024-06-26T16:31:16.790504975Z out: appName eclient state changed to RESOLVING_TAG time: 2024-06-26T16:31:23.794613521Z out: appName eclient state changed to DOWNLOAD_STARTED time: 2024-06-26T16:31:30.799199444Z out: appName eclient state changed to DOWNLOAD_STARTED (0%) time: 2024-06-26T16:31:34.802192277Z out: appName eclient state changed to DOWNLOAD_STARTED (100%) time: 2024-06-26T16:31:40.807710314Z out: appName eclient state changed to DOWNLOAD_STARTED (0%) time: 2024-06-26T16:31:50.815353244Z out: appName eclient state changed to DOWNLOAD_STARTED (2%) time: 2024-06-26T16:31:53.817917895Z out: appName eclient state changed to DOWNLOAD_STARTED (4%) time: 2024-06-26T16:31:57.82203614Z out: appName eclient state changed to DOWNLOAD_STARTED (7%) time: 2024-06-26T16:31:59.824343777Z out: appName eclient state changed to DOWNLOAD_STARTED (11%) time: 2024-06-26T16:32:03.82813281Z out: appName eclient state changed to DOWNLOAD_STARTED (15%) time: 2024-06-26T16:32:04.828443881Z out: appName eclient state changed to DOWNLOAD_STARTED (17%) time: 2024-06-26T16:32:07.831101541Z out: appName eclient state changed to DOWNLOAD_STARTED (38%) time: 2024-06-26T16:32:11.834435629Z out: appName eclient state changed to DOWNLOAD_STARTED (49%) time: 2024-06-26T16:32:11.834491793Z out: appName eclient state changed to DOWNLOAD_STARTED (55%) time: 2024-06-26T16:32:12.835562532Z out: appName eclient state changed to DOWNLOAD_STARTED (63%) time: 2024-06-26T16:32:14.837769139Z out: appName eclient state changed to DOWNLOAD_STARTED (68%) time: 2024-06-26T16:32:14.837825744Z out: appName eclient state changed to DOWNLOAD_STARTED (73%) time: 2024-06-26T16:32:14.837846563Z out: appName eclient state changed to DOWNLOAD_STARTED (76%) time: 2024-06-26T16:32:16.839953997Z out: appName eclient state changed to DOWNLOAD_STARTED (79%) time: 2024-06-26T16:32:17.841098452Z out: appName eclient state changed to DOWNLOAD_STARTED (83%) time: 2024-06-26T16:32:17.841129069Z out: appName eclient state changed to DOWNLOAD_STARTED (88%) time: 2024-06-26T16:32:19.842760135Z out: appName eclient state changed to DOWNLOAD_STARTED (92%) time: 2024-06-26T16:32:25.847248207Z out: appName eclient state changed to DOWNLOAD_STARTED (95%) time: 2024-06-26T16:32:27.849372816Z out: appName eclient state changed to LOADING time: 2024-06-26T16:32:45.865099474Z out: appName eclient state changed to CREATING_VOLUME time: 2024-06-26T16:34:05.931681231Z out: appName eclient state changed to INSTALLED time: 2024-06-26T16:40:10.220086505Z out: appName eclient state changed to INITIAL time: 2024-06-26T16:41:05.256229148Z out: appName eclient state changed to INSTALLED time: 2024-06-26T16:41:32.273786077Z out: appName eclient state changed to INSTALLED: [description:"setting up OCI spec for domain c2844855-04ea-4c5b-9433-11b20fd537f1.1.1 failed unexpected end of JSON input" timestamp:{seconds:1719420091 nanos:793993359} severity:SEVERITY_ERROR] app_test.go:171: ASSERTION FAILED (2024-06-26T16:51:08.468321059Z): expected apps [eclient] in RUNNING state app_test.go:173: actual eclient: INSTALLED: [description:"setting up OCI spec for domain c2844855-04ea-4c5b-9433-11b20fd537f1.1.1 failed unexpected end of JSON input" timestamp:{seconds:1719420091 nanos:793993359} severity:SEVERITY_ERROR] app_test.go:175: history of states for eclient: app_test.go:177: state: no info from controller received in: 2024-06-26T16:31:08.464289835Z app_test.go:177: state: UNKNOWN received in: 2024-06-26T16:31:08.467645411Z app_test.go:177: state: INITIAL received in: 2024-06-26T16:31:16.790462365Z app_test.go:177: state: RESOLVING_TAG received in: 2024-06-26T16:31:16.790504063Z app_test.go:177: state: DOWNLOAD_STARTED received in: 2024-06-26T16:31:23.794609383Z

Process completed with exit code 1.

[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2024-06-26T17:09:28.80408305Z out: Wait for state of 8fc5a2e7-363c-4d89-bedd-7c1c546d067b testContext.go:380: done waiting for State reboot_test.go:166: time: 2024-06-26T17:09:28.804128094Z out: timewait: 1h40m0s reboot_test.go:167: time: 2024-06-26T17:09:28.804142762Z out: reboot: false reboot_test.go:168: time: 2024-06-26T17:09:28.804158401Z out: count: 3 reboot_test.go:172: time: 2024-06-26T17:09:28.804172487Z out: LastRebootTime: 2024-06-26 16:27:22.983990114 +0000 UTC reboot_test.go:174: time: 2024-06-26T17:09:28.804180983Z out: LastRebootReason: NORMAL: First boot of device - at 2024-06-26T16:27:22.983990114Z rebooted with reason NORMAL: controller reboot at EVE version 0.0.0-master-3a02e3f9-kvm-amd64 at 2024-06-26T17:17:34.359105247Z at 2024-06-26 17:17:34.432178819 +0000 UTC/nrebooted with reason Reboot reason - kernel crash (no kdump) - at 2024-06-26T17:28:07.733497851Z at 2024-06-26 17:28:07.733497851 +0000 UTC/n testProc.go:84: time: 2024-06-26T17:29:24.05590745Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - kernel crash (no kdump) - at 2024-06-26T17:28:07.733497851Z testContext.go:271: done for device 8fc5a2e7-363c-4d89-bedd-7c1c546d067b reboot_test.go:186: time: 2024-06-26T17:29:24.056010473Z out: Number of reboots: 1 --- PASS: TestReboot (1195.25s) PASS [stderr] time="2024-06-26T17:29:24Z" level=info msg="time: 2024-06-26T17:29:24.05590745Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - kernel crash (no kdump) - at 2024-06-26T17:28:07.733497851Z" FAIL: ../eclient/testdata/dev_local_info.txt:84: unexpected command success

[stdout] Docker app's state test === RUN TestAppStatus apps: '[app1 local-manager]' state: 'RUNNING' secs: 1200 time: 2024-06-26T17:16:58.64432548Z out: appName local-manager state changed to RUNNING time: 2024-06-26T17:16:58.644339857Z out: appName app1 state changed to HALTED time: 2024-06-26T17:17:14.423336555Z out: appName local-manager state changed to HALTING time: 2024-06-26T17:17:21.430615654Z out: appName local-manager state changed to HALTED time: 2024-06-26T17:21:59.638494007Z out: appName local-manager state changed to UNKNOWN time: 2024-06-26T17:21:59.638513794Z out: appName app1 state changed to UNKNOWN time: 2024-06-26T17:22:01.640969526Z out: appName local-manager state changed to INITIAL time: 2024-06-26T17:22:03.642798298Z out: appName app1 state changed to INITIAL time: 2024-06-26T17:23:03.692304663Z out: appName app1 state changed to INSTALLED time: 2024-06-26T17:23:05.694828679Z out: appName local-manager state changed to INSTALLED time: 2024-06-26T17:29:24.000635388Z out: appName app1 state changed to UNKNOWN time: 2024-06-26T17:29:24.000649184Z out: appName local-manager state changed to UNKNOWN [context canceled] FAIL: ../eclient/testdata/dev_local_info.txt:84: test interrupted while running command

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[t1 t2]' state: 'RUNNING' secs: 600 time: 2024-06-26T17:25:04.458572595Z out: appName t1 state changed to UNKNOWN time: 2024-06-26T17:25:04.458584677Z out: appName t2 state changed to UNKNOWN time: 2024-06-26T17:25:11.739539211Z out: appName t1 state changed to INITIAL time: 2024-06-26T17:25:14.741947489Z out: appName t2 state changed to INITIAL time: 2024-06-26T17:25:14.741972496Z out: appName t1 state changed to RESOLVING_TAG time: 2024-06-26T17:25:18.744984387Z out: appName t1 state changed to DOWNLOAD_STARTED time: 2024-06-26T17:25:18.745007801Z out: appName t2 state changed to DOWNLOAD_STARTED time: 2024-06-26T17:25:19.745719605Z out: appName t1 state changed to DOWNLOAD_STARTED (0%) time: 2024-06-26T17:25:19.745739051Z out: appName t2 state changed to DOWNLOAD_STARTED (0%) time: 2024-06-26T17:25:30.752097726Z out: appName t2 state changed to DOWNLOAD_STARTED (100%) time: 2024-06-26T17:26:06.780851454Z out: appName t1 state changed to DOWNLOAD_STARTED (100%) time: 2024-06-26T17:26:16.790874495Z out: appName t1 state changed to DOWNLOAD_STARTED (0%) time: 2024-06-26T17:26:25.800270561Z out: appName t2 state changed to DOWNLOAD_STARTED (0%) time: 2024-06-26T17:26:40.813523213Z out: appName t2 state changed to DOWNLOAD_STARTED (1%) time: 2024-06-26T17:26:47.818899903Z out: appName t1 state changed to DOWNLOAD_STARTED (1%) time: 2024-06-26T17:26:50.821347093Z out: appName t2 state changed to DOWNLOAD_STARTED (3%) time: 2024-06-26T17:26:52.823214116Z out: appName t1 state changed to DOWNLOAD_STARTED (3%) time: 2024-06-26T17:26:55.826367106Z out: appName t1 state changed to DOWNLOAD_STARTED (4%) time: 2024-06-26T17:26:59.83028234Z out: appName t2 state changed to DOWNLOAD_STARTED (5%) time: 2024-06-26T17:27:00.831051103Z out: appName t1 state changed to DOWNLOAD_STARTED (6%) time: 2024-06-26T17:27:06.837042858Z out: appName t2 state changed to DOWNLOAD_STARTED (6%) time: 2024-06-26T17:27:08.83878974Z out: appName t1 state changed to DOWNLOAD_STARTED (7%) time: 2024-06-26T17:27:09.83996208Z out: appName t2 state changed to DOWNLOAD_STARTED (7%) time: 2024-06-26T17:27:14.845022639Z out: appName t2 state changed to DOWNLOAD_STARTED (8%) time: 2024-06-26T17:27:17.847582472Z out: appName t1 state changed to DOWNLOAD_STARTED (51%) time: 2024-06-26T17:27:18.848029051Z out: appName t2 state changed to DOWNLOAD_STARTED (51%) time: 2024-06-26T17:27:19.848737429Z out: appName t2 state changed to DOWNLOAD_STARTED (87%) time: 2024-06-26T17:27:20.849702985Z out: appName t1 state changed to DOWNLOAD_STARTED (87%) time: 2024-06-26T17:27:30.859370903Z out: appName t2 state changed to DOWNLOAD_STARTED (88%) time: 2024-06-26T17:27:32.861575344Z out: appName t1 state changed to DOWNLOAD_STARTED (88%) time: 2024-06-26T17:27:33.8623157Z out: appName t2 state changed to DOWNLOAD_STARTED (89%) time: 2024-06-26T17:27:34.863646699Z out: appName t1 state changed to DOWNLOAD_STARTED (89%) time: 2024-06-26T17:27:40.869742244Z out: appName t2 state changed to DOWNLOAD_STARTED (90%) time: 2024-06-26T17:27:42.870826253Z out: appName t1 state changed to DOWNLOAD_STARTED (90%) time: 2024-06-26T17:27:47.876171634Z out: appName t2 state changed to DOWNLOAD_STARTED (91%) time: 2024-06-26T17:27:48.876708567Z out: appName t1 state changed to DOWNLOAD_STARTED (91%) time: 2024-06-26T17:27:49.877635431Z out: appName t2 state changed to DOWNLOAD_STARTED (92%) time: 2024-06-26T17:27:51.879712804Z out: appName t1 state changed to DOWNLOAD_STARTED (92%) time: 2024-06-26T17:27:52.880834531Z out: appName t2 state changed to DOWNLOAD_STARTED (93%) time: 2024-06-26T17:28:00.887700117Z out: appName t1 state changed to DOWNLOAD_STARTED (100%) time: 2024-06-26T17:28:15.89969773Z out: appName t2 state changed to LOADING time: 2024-06-26T17:28:15.899719601Z out: appName t1 state changed to VERIFIED time: 2024-06-26T17:29:35.97338512Z out: appName t2 state changed to DELIVERED time: 2024-06-26T17:29:35.973404186Z out: appName t1 state changed to DELIVERED time: 2024-06-2

Process completed with exit code 1.

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/.

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/.

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/.

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/.

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/.

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/.

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/.

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/.

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/.

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/.

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/.

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/.