[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2025-02-01T06:42:22.754485206Z out: Wait for state of 1b0f19be-7e98-4348-83fa-192ed5483595 testContext.go:369: done waiting for State reboot_test.go:166: time: 2025-02-01T06:42:22.754516959Z out: timewait: 2562047h47m16.854775807s reboot_test.go:167: time: 2025-02-01T06:42:22.754526959Z out: reboot: false reboot_test.go:168: time: 2025-02-01T06:42:22.754536497Z out: count: 1 reboot_test.go:172: time: 2025-02-01T06:42:22.754547379Z out: LastRebootTime: 2025-02-01 06:40:12.907718185 +0000 UTC reboot_test.go:174: time: 2025-02-01T06:42:22.754556807Z out: LastRebootReason: NORMAL: First boot of device - at 2025-02-01T06:40:12.907718185Z rebooted with reason Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2025-02-01T06:49:19.934202847Z at 2025-02-01 06:49:19.934202847 +0000 UTC/n testProc.go:84: time: 2025-02-01T06:50:04.774137356Z out: testcontext.ProcInfoFunc done with return: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2025-02-01T06:49:19.934202847Z testContext.go:260: done for device 1b0f19be-7e98-4348-83fa-192ed5483595 reboot_test.go:186: time: 2025-02-01T06:50:04.774207024Z out: Number of reboots: 0 --- PASS: TestReboot (462.02s) PASS [stderr] time="2025-02-01T06:50:04Z" level=info msg="time: 2025-02-01T06:50:04.774137356Z out: testcontext.ProcInfoFunc done with return: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2025-02-01T06:49:19.934202847Z" FAIL: ../eclient/testdata/acl.txt:48: unexpected command success

[stdout] Docker app's state test === RUN TestAppStatus apps: '[curl-acl1 curl-acl2]' state: 'RUNNING' secs: 900 time: 2025-02-01T06:43:06.042014478Z out: appName curl-acl1 state changed to RESOLVING_TAG time: 2025-02-01T06:43:06.042023635Z out: appName curl-acl2 state changed to UNKNOWN time: 2025-02-01T06:43:09.299302393Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED time: 2025-02-01T06:43:12.301507289Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (0%) time: 2025-02-01T06:43:13.30189548Z out: appName curl-acl2 state changed to INITIAL time: 2025-02-01T06:43:14.302159838Z out: appName curl-acl2 state changed to RESOLVING_TAG time: 2025-02-01T06:43:15.302611209Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED time: 2025-02-01T06:43:17.303481191Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (100%) time: 2025-02-01T06:43:22.30737744Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (100%) time: 2025-02-01T06:43:27.310399382Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (0%) time: 2025-02-01T06:43:33.316434867Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (0%) time: 2025-02-01T06:43:39.321281588Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (1%) time: 2025-02-01T06:43:40.322579078Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (1%) time: 2025-02-01T06:43:41.323608887Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (5%) time: 2025-02-01T06:43:44.326562895Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (5%) time: 2025-02-01T06:43:45.327434173Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (11%) time: 2025-02-01T06:43:46.32869015Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (8%) time: 2025-02-01T06:43:48.330498265Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (20%) time: 2025-02-01T06:43:50.332781907Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (19%) time: 2025-02-01T06:43:51.333577068Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (26%) time: 2025-02-01T06:43:53.334640308Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (25%) time: 2025-02-01T06:43:53.334661479Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (27%) time: 2025-02-01T06:43:54.335011493Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (27%) time: 2025-02-01T06:43:55.336279389Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (29%) time: 2025-02-01T06:43:57.337317106Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (29%) time: 2025-02-01T06:43:57.337336364Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (87%) time: 2025-02-01T06:43:59.338302906Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (87%) time: 2025-02-01T06:43:59.338332863Z out: appName curl-acl2 state changed to DOWNLOAD_STARTED (100%) time: 2025-02-01T06:44:02.340378727Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (100%) time: 2025-02-01T06:44:02.340404477Z out: appName curl-acl1 state changed to LOADING time: 2025-02-01T06:44:02.340411551Z out: appName curl-acl2 state changed to VERIFIED time: 2025-02-01T06:44:13.347945187Z out: appName curl-acl1 state changed to DELIVERED time: 2025-02-01T06:44:14.348318394Z out: appName curl-acl2 state changed to LOADING time: 2025-02-01T06:44:19.352468955Z out: appName curl-acl1 state changed to CREATING_VOLUME time: 2025-02-01T06:44:19.352478603Z out: appName curl-acl2 state changed to CREATING_VOLUME time: 2025-02-01T06:45:16.390294162Z out: appName curl-acl1 state changed to INSTALLED time: 2025-02-01T06:45:19.3922359Z out: appName curl-acl2 state changed to INSTALLED time: 2025-02-01T06:50:04.571370306Z out: appName curl-acl1 state changed to UNKNOWN time: 2025-02-01T06:50:04.571381959Z out: appName curl-acl2 state changed to UNKNOWN [context canceled] FAIL: ../eclient/testdata/acl.txt:48: 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: 2025-02-01T06:46:28.339281397Z out: appName eclient state changed to UNKNOWN time: 2025-02-01T06:46:34.593996059Z out: appName eclient state changed to INITIAL time: 2025-02-01T06:46:35.594964854Z out: appName eclient state changed to RESOLVING_TAG time: 2025-02-01T06:46:37.595981045Z out: appName eclient state changed to DOWNLOAD_STARTED time: 2025-02-01T06:46:38.596280616Z out: appName eclient state changed to DOWNLOAD_STARTED (0%) time: 2025-02-01T06:46:48.605518839Z out: appName eclient state changed to DOWNLOAD_STARTED (100%) time: 2025-02-01T06:46:52.608734657Z out: appName eclient state changed to DOWNLOAD_STARTED (0%) time: 2025-02-01T06:47:01.61666698Z out: appName eclient state changed to DOWNLOAD_STARTED (2%) time: 2025-02-01T06:47:02.61768829Z out: appName eclient state changed to DOWNLOAD_STARTED (4%) time: 2025-02-01T06:47:04.619170693Z out: appName eclient state changed to DOWNLOAD_STARTED (13%) time: 2025-02-01T06:47:05.61992748Z out: appName eclient state changed to DOWNLOAD_STARTED (20%) time: 2025-02-01T06:47:07.62165912Z out: appName eclient state changed to DOWNLOAD_STARTED (26%) time: 2025-02-01T06:47:10.62514542Z out: appName eclient state changed to DOWNLOAD_STARTED (33%) time: 2025-02-01T06:47:10.625167873Z out: appName eclient state changed to DOWNLOAD_STARTED (42%) time: 2025-02-01T06:47:12.626461023Z out: appName eclient state changed to DOWNLOAD_STARTED (76%) time: 2025-02-01T06:47:15.629633863Z out: appName eclient state changed to DOWNLOAD_STARTED (95%) time: 2025-02-01T06:47:17.632092206Z out: appName eclient state changed to DOWNLOAD_STARTED (100%) time: 2025-02-01T06:47:17.632107025Z out: appName eclient state changed to LOADING time: 2025-02-01T06:47:27.640536703Z out: appName eclient state changed to DELIVERED time: 2025-02-01T06:47:29.642941134Z out: appName eclient state changed to CREATING_VOLUME time: 2025-02-01T06:48:06.667432098Z out: appName eclient state changed to INSTALLED time: 2025-02-01T06:52:36.812895201Z out: appName eclient state changed to UNKNOWN time: 2025-02-01T06:52:37.81325853Z out: appName eclient state changed to INITIAL time: 2025-02-01T06:53:07.831612898Z out: appName eclient state changed to INSTALLED time: 2025-02-01T06:53:26.844729555Z out: appName eclient state changed to BOOTING time: 2025-02-01T06:53:29.847028898Z out: appName eclient state changed to HALTING: [description:"task broke with exit status 1" timestamp:{seconds:1738392806 nanos:119154470} severity:SEVERITY_ERROR] time: 2025-02-01T07:04:00.325920096Z out: appName eclient state changed to BOOTING time: 2025-02-01T07:04:05.330115545Z out: appName eclient state changed to HALTING: [description:"task broke with exit status 1" timestamp:{seconds:1738393440 nanos:591608922} severity:SEVERITY_ERROR] app_test.go:178: ASSERTION FAILED (2025-02-01T07:06:28.339557483Z): expected apps [eclient] in RUNNING state app_test.go:180: actual eclient: HALTING: [description:"task broke with exit status 1" timestamp:{seconds:1738393440 nanos:591608922} severity:SEVERITY_ERROR] app_test.go:182: history of states for eclient: app_test.go:184: state: no info from controller received in: 2025-02-01T06:46:28.33569469Z app_test.go:184: state: UNKNOWN received in: 2025-02-01T06:46:28.339279223Z app_test.go:184: state: INITIAL received in: 2025-02-01T06:46:34.593990819Z app_test.go:184: state: RESOLVING_TAG received in: 2025-02-01T06:46:35.594961879Z app_test.go:184: state: DOWNLOAD_STARTED received in: 2025-02-01T06:46:37.595976407Z app_test.go:184: state: DOWNLOAD_STARTED (0%) received in: 2025-02-01T06:46:38.596279032Z app_test.go:184: state: DOWNLOAD_STARTED (100%) received in: 2025-02-01T06:46:48.605517085Z app_test.go:184: state: DOWNLOAD_STARTED (0%) received in: 2025-02-01T06:46:52.60873327Z app_test.go:184: state: DOWNLOAD_STARTED (2%) received in: 2025-02-01T06:47:01.616662692Z app_test.go:184: state: DOWN

Process completed with exit code 1.

[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2025-02-01T07:07:54.77529263Z out: Wait for state of 54bbe9f1-0aec-4835-a4e1-d74f117db6f1 testContext.go:369: done waiting for State reboot_test.go:166: time: 2025-02-01T07:07:54.775316836Z out: timewait: 1h40m0s reboot_test.go:167: time: 2025-02-01T07:07:54.775323018Z out: reboot: false reboot_test.go:168: time: 2025-02-01T07:07:54.775326714Z out: count: 3 reboot_test.go:172: time: 2025-02-01T07:07:54.775333407Z out: LastRebootTime: 2025-02-01 06:40:14.398037682 +0000 UTC reboot_test.go:174: time: 2025-02-01T07:07:54.775336653Z out: LastRebootReason: NORMAL: First boot of device - at 2025-02-01T06:40:14.398037682Z rebooted with reason NORMAL: controller reboot at EVE version 0.0.0-pr4501-0ad79a54-kvm-amd64 at 2025-02-01T07:14:04.806054256Z at 2025-02-01 07:14:04.844944915 +0000 UTC/nrebooted with reason NORMAL: local profile server power off at EVE version 0.0.0-pr4501-0ad79a54-kvm-amd64 at 2025-02-01T07:20:39.840201474Z at 2025-02-01 07:20:39.890517927 +0000 UTC/nrebooted with reason Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2025-02-01T07:30:14.493347388Z at 2025-02-01 07:30:14.493347388 +0000 UTC/n testProc.go:84: time: 2025-02-01T07:31:01.914314133Z out: testcontext.ProcInfoFunc done with return: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2025-02-01T07:30:14.493347388Z testContext.go:260: done for device 54bbe9f1-0aec-4835-a4e1-d74f117db6f1 reboot_test.go:186: time: 2025-02-01T07:31:01.914395677Z out: Number of reboots: 2 --- PASS: TestReboot (1387.14s) PASS [stderr] time="2025-02-01T07:31:01Z" level=info msg="time: 2025-02-01T07:31:01.914314133Z out: testcontext.ProcInfoFunc done with return: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2025-02-01T07:30:14.493347388Z" 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: 2025-02-01T07:22:07.575146197Z out: appName app1 state changed to HALTED time: 2025-02-01T07:25:41.868326959Z out: appName app1 state changed to UNKNOWN time: 2025-02-01T07:25:51.875811921Z out: appName app1 state changed to INITIAL time: 2025-02-01T07:25:53.876732653Z out: appName app1 state changed to UNKNOWN time: 2025-02-01T07:26:17.896729272Z out: appName app1 state changed to INSTALLED time: 2025-02-01T07:31:01.072464218Z out: appName app1 state changed to UNKNOWN [context canceled] FAIL: ../eclient/testdata/dev_local_info.txt:128: test interrupted while running command