[stdout] Docker app's state test === RUN TestAppStatus apps: '[local-manager]' state: 'RUNNING' secs: 600 time: 2023-10-12T10:29:19.557365428Z out: appName local-manager state changed to UNKNOWN time: 2023-10-12T10:29:22.528692246Z out: appName local-manager state changed to INITIAL time: 2023-10-12T10:29:27.530886533Z out: appName local-manager state changed to RESOLVING_TAG time: 2023-10-12T10:29:37.536335608Z out: appName local-manager state changed to DOWNLOAD_STARTED time: 2023-10-12T10:29:37.536369009Z out: appName local-manager state changed to DOWNLOAD_STARTED (0%) time: 2023-10-12T10:30:42.57153451Z out: appName local-manager state changed to DOWNLOAD_STARTED (100%) time: 2023-10-12T10:30:52.577592448Z out: appName local-manager state changed to DOWNLOAD_STARTED (0%) time: 2023-10-12T10:31:03.584975701Z out: appName local-manager state changed to DOWNLOAD_STARTED (2%) time: 2023-10-12T10:31:12.589713265Z out: appName local-manager state changed to DOWNLOAD_STARTED (3%) time: 2023-10-12T10:31:16.596259128Z out: appName local-manager state changed to DOWNLOAD_STARTED (7%) time: 2023-10-12T10:31:20.598271387Z out: appName local-manager state changed to DOWNLOAD_STARTED (9%) time: 2023-10-12T10:31:23.599580335Z out: appName local-manager state changed to DOWNLOAD_STARTED (11%) time: 2023-10-12T10:31:31.603923773Z out: appName local-manager state changed to DOWNLOAD_STARTED (12%) time: 2023-10-12T10:31:41.609270486Z out: appName local-manager state changed to DOWNLOAD_STARTED (24%) time: 2023-10-12T10:31:44.61101017Z out: appName local-manager state changed to DOWNLOAD_STARTED (56%) time: 2023-10-12T10:31:53.616474341Z out: appName local-manager state changed to DOWNLOAD_STARTED (58%) time: 2023-10-12T10:31:57.619474065Z out: appName local-manager state changed to DOWNLOAD_STARTED (60%) time: 2023-10-12T10:32:00.62102247Z out: appName local-manager state changed to DOWNLOAD_STARTED (61%) time: 2023-10-12T10:32:02.621902378Z out: appName local-manager state changed to DOWNLOAD_STARTED (62%) time: 2023-10-12T10:32:07.624526809Z out: appName local-manager state changed to DOWNLOAD_STARTED (64%) time: 2023-10-12T10:32:14.629018365Z out: appName local-manager state changed to DOWNLOAD_STARTED (66%) time: 2023-10-12T10:32:16.630056707Z out: appName local-manager state changed to DOWNLOAD_STARTED (67%) time: 2023-10-12T10:32:17.630510623Z out: appName local-manager state changed to DOWNLOAD_STARTED (69%) time: 2023-10-12T10:32:19.631888863Z out: appName local-manager state changed to DOWNLOAD_STARTED (91%) time: 2023-10-12T10:32:20.632351978Z out: appName local-manager state changed to DOWNLOAD_STARTED (93%) time: 2023-10-12T10:32:24.634988754Z out: appName local-manager state changed to DOWNLOAD_STARTED (94%) time: 2023-10-12T10:32:27.636819382Z out: appName local-manager state changed to DOWNLOAD_STARTED (95%) time: 2023-10-12T10:32:30.638540307Z out: appName local-manager state changed to DOWNLOAD_STARTED (96%) time: 2023-10-12T10:32:32.639599121Z out: appName local-manager state changed to DOWNLOAD_STARTED (97%) time: 2023-10-12T10:32:34.640995127Z out: appName local-manager state changed to DOWNLOAD_STARTED (98%) time: 2023-10-12T10:33:30.672292829Z out: appName local-manager state changed to DOWNLOAD_STARTED (100%) time: 2023-10-12T10:33:31.672712228Z out: appName local-manager state changed to LOADING time: 2023-10-12T10:34:19.696838339Z out: appName local-manager state changed to DELIVERED time: 2023-10-12T10:34:23.698595557Z out: appName local-manager state changed to CREATING_VOLUME time: 2023-10-12T10:38:32.828136363Z out: appName local-manager state changed to INSTALLED app_test.go:171: ASSERTION FAILED (2023-10-12T10:39:19.558016315Z): expected apps [local-manager] in RUNNING state app_test.go:173: actual local-manager: INSTALLED app_test.go:175: history of states for local-manager: app_test.go:177: state: no info from controller received in: 2023-10-12T10:29:19.522615739Z app_test.go:177: state: UNKNOWN received in: 2023-10-12T10:29:19.557363528Z

Process completed with exit code 1.

[stdout] "state": "PURGING" > exec -t 5m bash wait-for-app-state.sh app1 RUNNING [context deadline exceeded] FAIL: ../eclient/testdata/app_local_info.txt:77: command failure

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[nginx]' state: 'RUNNING' secs: 1200 time: 2023-10-12T13:32:09.736919362Z out: appName nginx state changed to UNKNOWN time: 2023-10-12T13:32:20.034829073Z out: appName nginx state changed to INITIAL time: 2023-10-12T13:32:21.036217251Z out: appName nginx state changed to RESOLVING_TAG time: 2023-10-12T13:32:26.041511043Z out: appName nginx state changed to DOWNLOAD_STARTED time: 2023-10-12T13:32:43.056508611Z out: appName nginx state changed to DOWNLOAD_STARTED (0%) time: 2023-10-12T13:33:38.093195842Z out: appName nginx state changed to DOWNLOAD_STARTED (100%) time: 2023-10-12T13:34:12.115864822Z out: appName nginx state changed to DOWNLOAD_STARTED (0%) time: 2023-10-12T13:34:33.133100449Z out: appName nginx state changed to DOWNLOAD_STARTED (1%) time: 2023-10-12T13:34:50.145671164Z out: appName nginx state changed to DOWNLOAD_STARTED (13%) time: 2023-10-12T13:35:07.157745748Z out: appName nginx state changed to DOWNLOAD_STARTED (25%) time: 2023-10-12T13:35:19.164868926Z out: appName nginx state changed to DOWNLOAD_STARTED (26%) time: 2023-10-12T13:35:28.172846158Z out: appName nginx state changed to DOWNLOAD_STARTED (27%) time: 2023-10-12T13:35:37.178116798Z out: appName nginx state changed to DOWNLOAD_STARTED (28%) time: 2023-10-12T13:35:50.189336034Z out: appName nginx state changed to DOWNLOAD_STARTED (39%) time: 2023-10-12T13:35:53.192613857Z out: appName nginx state changed to DOWNLOAD_STARTED (49%) time: 2023-10-12T13:35:59.198074896Z out: appName nginx state changed to DOWNLOAD_STARTED (50%) time: 2023-10-12T13:36:13.210169287Z out: appName nginx state changed to DOWNLOAD_STARTED (51%) time: 2023-10-12T13:36:15.211901611Z out: appName nginx state changed to DOWNLOAD_STARTED (57%) time: 2023-10-12T13:36:21.21781538Z out: appName nginx state changed to DOWNLOAD_STARTED (64%) time: 2023-10-12T13:36:28.224119725Z out: appName nginx state changed to DOWNLOAD_STARTED (65%) time: 2023-10-12T13:36:40.23418599Z out: appName nginx state changed to DOWNLOAD_STARTED (66%) time: 2023-10-12T13:36:49.242659963Z out: appName nginx state changed to DOWNLOAD_STARTED (71%) time: 2023-10-12T13:36:53.244279126Z out: appName nginx state changed to DOWNLOAD_STARTED (77%) time: 2023-10-12T13:37:04.250851785Z out: appName nginx state changed to DOWNLOAD_STARTED (78%) time: 2023-10-12T13:37:13.256949257Z out: appName nginx state changed to DOWNLOAD_STARTED (79%) time: 2023-10-12T13:37:18.260425385Z out: appName nginx state changed to DOWNLOAD_STARTED (85%) time: 2023-10-12T13:37:24.263530464Z out: appName nginx state changed to DOWNLOAD_STARTED (86%) time: 2023-10-12T13:37:32.268664111Z out: appName nginx state changed to DOWNLOAD_STARTED (87%) time: 2023-10-12T13:37:36.270702023Z out: appName nginx state changed to DOWNLOAD_STARTED (93%) time: 2023-10-12T13:37:38.271650279Z out: appName nginx state changed to DOWNLOAD_STARTED (94%) time: 2023-10-12T13:37:40.272998149Z out: appName nginx state changed to DOWNLOAD_STARTED (95%) time: 2023-10-12T13:37:41.274276451Z out: appName nginx state changed to DOWNLOAD_STARTED (96%) time: 2023-10-12T13:37:53.282894631Z out: appName nginx state changed to DOWNLOAD_STARTED (97%) time: 2023-10-12T13:38:01.287107483Z out: appName nginx state changed to DOWNLOAD_STARTED (98%) time: 2023-10-12T13:38:08.293034044Z out: appName nginx state changed to DOWNLOAD_STARTED (99%) time: 2023-10-12T13:38:48.315892733Z out: appName nginx state changed to LOADING time: 2023-10-12T13:39:59.377607959Z out: appName nginx state changed to DELIVERED time: 2023-10-12T13:40:06.382546785Z out: appName nginx state changed to CREATING_VOLUME time: 2023-10-12T13:51:03.795060051Z out: appName nginx state changed to INSTALLED app_test.go:171: ASSERTION FAILED (2023-10-12T13:52:09.73721686Z): expected apps [nginx] in RUNNING state app_test.go:173: actual nginx: INSTALLED app_test.go:175: history of states for nginx: app_test.go:177: state: no info from controller received in: 2023-10-12T13:32:09.664453745Z

Process completed with exit code 1.

[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2023-10-12T13:12:31.191137218Z out: Wait for state of e16bbfdd-ed01-4ac3-9a4d-03f05f42f756 testContext.go:380: done waiting for State reboot_test.go:166: time: 2023-10-12T13:12:31.191187819Z out: timewait: 1h40m0s reboot_test.go:167: time: 2023-10-12T13:12:31.191195919Z out: reboot: false reboot_test.go:168: time: 2023-10-12T13:12:31.191201719Z out: count: 3 reboot_test.go:172: time: 2023-10-12T13:12:31.191210619Z out: LastRebootTime: 2023-10-12 09:06:25.286063146 +0000 UTC reboot_test.go:174: time: 2023-10-12T13:12:31.191216819Z out: LastRebootReason: NORMAL: First boot of device - at 2023-10-12T09:06:25.286063146Z rebooted with reason NORMAL: controller reboot at EVE version 10.9.0-kvm-amd64 at 2023-10-12T13:28:03.052563086Z at 2023-10-12 13:28:03.343380453 +0000 UTC/nrebooted with reason NORMAL: local profile server power off at EVE version 10.9.0-kvm-amd64 at 2023-10-12T13:39:33.757585751Z at 2023-10-12 13:39:34.290836779 +0000 UTC/nrebooted with reason Reboot reason - kernel crash (no kdump) - at 2023-10-12T13:53:04.003980757Z at 2023-10-12 13:53:04.003980757 +0000 UTC/n testProc.go:84: time: 2023-10-12T13:54:26.322588885Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - kernel crash (no kdump) - at 2023-10-12T13:53:04.003980757Z testContext.go:271: done for device e16bbfdd-ed01-4ac3-9a4d-03f05f42f756 reboot_test.go:186: time: 2023-10-12T13:54:26.322658287Z out: Number of reboots: 2 --- PASS: TestReboot (2515.13s) PASS [stderr] time="2023-10-12T13:54:26Z" level=info msg="time: 2023-10-12T13:54:26.322588885Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - kernel crash (no kdump) - at 2023-10-12T13:53:04.003980757Z" FAIL: ../eclient/testdata/dev_local_info.txt:128: unexpected command success

[stdout] Docker app's state test === RUN TestAppStatus apps: '[app1]' state: 'RUNNING' secs: 1200 time: 2023-10-12T13:42:01.533453527Z out: appName app1 state changed to HALTED time: 2023-10-12T13:45:28.601558218Z out: appName app1 state changed to UNKNOWN time: 2023-10-12T13:45:47.617952387Z out: appName app1 state changed to INITIAL time: 2023-10-12T13:45:49.620561003Z out: appName app1 state changed to UNKNOWN time: 2023-10-12T13:46:55.667941734Z out: appName app1 state changed to INSTALLED time: 2023-10-12T13:54:25.961242485Z out: appName app1 state changed to UNKNOWN [context canceled] FAIL: ../eclient/testdata/dev_local_info.txt:128: test interrupted while running command