[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2024-11-11T08:44:37.827289531Z out: Wait for state of 6c8ca4a1-3430-4cf0-b9ae-a543146464c7 testContext.go:380: done waiting for State reboot_test.go:166: time: 2024-11-11T08:44:37.827303388Z out: timewait: 2562047h47m16.854775807s reboot_test.go:167: time: 2024-11-11T08:44:37.827306935Z out: reboot: false reboot_test.go:168: time: 2024-11-11T08:44:37.827310121Z out: count: 1 reboot_test.go:172: time: 2024-11-11T08:44:37.827314338Z out: LastRebootTime: 2024-11-11 08:42:32.410525475 +0000 UTC reboot_test.go:174: time: 2024-11-11T08:44:37.827317755Z out: LastRebootReason: NORMAL: First boot of device - at 2024-11-11T08:42:32.410525475Z rebooted with reason Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-11T08:52:25.69974833Z at 2024-11-11 08:52:25.69974833 +0000 UTC/n testProc.go:84: time: 2024-11-11T08:53:04.616986112Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-11T08:52:25.69974833Z testContext.go:271: done for device 6c8ca4a1-3430-4cf0-b9ae-a543146464c7 reboot_test.go:186: time: 2024-11-11T08:53:04.617049112Z out: Number of reboots: 0 --- PASS: TestReboot (506.79s) PASS [stderr] time="2024-11-11T08:53:04Z" level=info msg="time: 2024-11-11T08:53:04.616986112Z out: projects.ProcInfoFunc done with return: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-11T08:52:25.69974833Z" FAIL: ../volume/testdata/volumes_test.txt:60: unexpected command success

[stdout] Docker app's state test === RUN TestAppStatus apps: '[eclient-mount]' state: 'RUNNING' secs: 900 time: 2024-11-11T08:48:29.275063626Z out: appName eclient-mount state changed to CREATING_VOLUME time: 2024-11-11T08:48:30.396606095Z out: appName eclient-mount state changed to INSTALLED time: 2024-11-11T08:53:04.543397052Z out: appName eclient-mount state changed to UNKNOWN [context canceled] FAIL: ../volume/testdata/volumes_test.txt:60: 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-11-11T08:47:02.786884729Z out: appName eclient state changed to UNKNOWN time: 2024-11-11T08:47:12.005556504Z out: appName eclient state changed to RESOLVING_TAG time: 2024-11-11T08:47:15.008527042Z out: appName eclient state changed to DOWNLOAD_STARTED time: 2024-11-11T08:47:17.010377922Z out: appName eclient state changed to DOWNLOAD_STARTED (0%) time: 2024-11-11T08:47:20.013487741Z out: appName eclient state changed to DOWNLOAD_STARTED (100%) time: 2024-11-11T08:47:25.016748972Z out: appName eclient state changed to DOWNLOAD_STARTED (0%) time: 2024-11-11T08:47:27.017995646Z out: appName eclient state changed to DOWNLOAD_STARTED (4%) time: 2024-11-11T08:47:29.019503576Z out: appName eclient state changed to DOWNLOAD_STARTED (11%) time: 2024-11-11T08:47:31.02128687Z out: appName eclient state changed to DOWNLOAD_STARTED (21%) time: 2024-11-11T08:47:32.021866897Z out: appName eclient state changed to DOWNLOAD_STARTED (36%) time: 2024-11-11T08:47:33.022583369Z out: appName eclient state changed to DOWNLOAD_STARTED (46%) time: 2024-11-11T08:47:34.023203201Z out: appName eclient state changed to DOWNLOAD_STARTED (64%) time: 2024-11-11T08:47:35.024348867Z out: appName eclient state changed to DOWNLOAD_STARTED (73%) time: 2024-11-11T08:47:35.024363255Z out: appName eclient state changed to DOWNLOAD_STARTED (84%) time: 2024-11-11T08:47:37.025867597Z out: appName eclient state changed to DOWNLOAD_STARTED (97%) time: 2024-11-11T08:47:38.026180504Z out: appName eclient state changed to LOADING time: 2024-11-11T08:47:45.031014511Z out: appName eclient state changed to CREATING_VOLUME time: 2024-11-11T08:48:14.047896603Z out: appName eclient state changed to INSTALLED time: 2024-11-11T08:52:38.218089175Z out: appName eclient state changed to UNKNOWN time: 2024-11-11T08:52:40.218866246Z out: appName eclient state changed to INITIAL time: 2024-11-11T08:52:42.21963545Z out: appName eclient state changed to UNKNOWN time: 2024-11-11T08:53:09.242553082Z out: appName eclient state changed to INSTALLED time: 2024-11-11T08:53:23.25531816Z out: appName eclient state changed to BOOTING time: 2024-11-11T08:53:26.257478007Z out: appName eclient state changed to HALTING: [description:"task broke with exit status 1" timestamp:{seconds:1731315203 nanos:315850556} severity:SEVERITY_ERROR] time: 2024-11-11T09:03:38.839899172Z out: appName eclient state changed to BOOTING time: 2024-11-11T09:03:41.842056607Z out: appName eclient state changed to HALTING: [description:"task broke with exit status 1" timestamp:{seconds:1731315818 nanos:360367054} severity:SEVERITY_ERROR] app_test.go:178: ASSERTION FAILED (2024-11-11T09:07:02.787449711Z): expected apps [eclient] in RUNNING state app_test.go:180: actual eclient: HALTING: [description:"task broke with exit status 1" timestamp:{seconds:1731315818 nanos:360367054} severity:SEVERITY_ERROR] app_test.go:182: history of states for eclient: app_test.go:184: state: no info from controller received in: 2024-11-11T08:47:02.782334669Z app_test.go:184: state: UNKNOWN received in: 2024-11-11T08:47:02.786881663Z app_test.go:184: state: RESOLVING_TAG received in: 2024-11-11T08:47:12.005552496Z app_test.go:184: state: DOWNLOAD_STARTED received in: 2024-11-11T08:47:15.008525058Z app_test.go:184: state: DOWNLOAD_STARTED (0%) received in: 2024-11-11T08:47:17.010376231Z app_test.go:184: state: DOWNLOAD_STARTED (100%) received in: 2024-11-11T08:47:20.013486338Z app_test.go:184: state: DOWNLOAD_STARTED (0%) received in: 2024-11-11T08:47:25.01674789Z app_test.go:184: state: DOWNLOAD_STARTED (4%) received in: 2024-11-11T08:47:27.017993983Z app_test.go:184: state: DOWNLOAD_STARTED (11%) received in: 2024-11-11T08:47:29.019499608Z app_test.go:184: state: DOWNLOAD_STARTED (21%) received in: 2024-11-11T08:47:31.021284836Z app_test.go:184: state: DOWNLOAD_STARTED (36%) received in: 2024-11-11T08:47:32.02186596

Process completed with exit code 1.

[stdout] time: 2024-11-11T09:09:14.100877721Z out: VCOM Test started configName: default configFile: /home/runner/.eden/contexts/default.yml time="2024-11-11T09:09:14Z" level=debug msg="Will use config from /home/runner/.eden/contexts/default.yml" time="2024-11-11T09:09:14Z" level=debug msg="Try to add config from /tmp/go-test-script2664949169/script-eden_vcom/eden-config.yml" --- FAIL: TestVcomLinkTpmRequestEK (0.60s) vcom_test.go:41: time: 2024-11-11T09:09:14.137626873Z out: TestvComLinkTpmRequestEK started vcom_test.go:41: time: 2024-11-11T09:09:14.137642715Z out: Checking if vComLink is running on EVE... vcom_test.go:41: time: 2024-11-11T09:09:14.739202413Z out: vComLink agent is running vcom_test.go:41: time: 2024-11-11T09:09:14.739251972Z out: Checking if vComLink is reachable from a VM, deploying ubuntu-22.04-server-cloudimg-amd64... vcom_test.go:41: time: 2024-11-11T09:09:14.73928179Z out: TestvComLinkTpmRequestEK finished [stderr] Warning: Permanently added '[127.0.0.1]:2222' (ED25519) to the list of known hosts. panic: runtime error: invalid memory address or nil pointer dereference [recovered] panic: runtime error: invalid memory address or nil pointer dereference [signal SIGSEGV: segmentation violation code=0x1 addr=0x28 pc=0xdc06d7] goroutine 80 [running]: testing.tRunner.func1.2({0xecdd00, 0x19b44b0}) /opt/hostedtoolcache/go/1.22.3/x64/src/testing/testing.go:1631 +0x24a testing.tRunner.func1() /opt/hostedtoolcache/go/1.22.3/x64/src/testing/testing.go:1634 +0x377 panic({0xecdd00?, 0x19b44b0?}) /opt/hostedtoolcache/go/1.22.3/x64/src/runtime/panic.go:770 +0x132 command-line-arguments.TestVcomLinkTpmRequestEK.WithSSH.func2(0xc0008e3aa8?, {0xc000306ee0?, 0xc800000?}) /home/runner/actions-runner/_work/eden/eden/eden/pkg/evetestkit/utils.go:448 +0x57 github.com/lf-edge/eden/pkg/evetestkit.(*EveNode).EveDeployApp(_, {_, _}, _, {{0xc000306ee0, 0x1a}, {0x10a8c8b, 0x4e}, {0x106b3bf, 0x6}, ...}, ...) /home/runner/actions-runner/_work/eden/eden/eden/pkg/evetestkit/utils.go:498 +0xc4 command-line-arguments.TestVcomLinkTpmRequestEK(0xc000179860) /home/runner/actions-runner/_work/eden/eden/eden/tests/vcom/vcom_test.go:125 +0x51a testing.tRunner(0xc000179860, 0x10d2498) /opt/hostedtoolcache/go/1.22.3/x64/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 1 /opt/hostedtoolcache/go/1.22.3/x64/src/testing/testing.go:1742 +0x390 [exit status 2] FAIL: testdata/eden_vcom.txt:1: command failure

Process completed with exit code 1.

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/

The following actions use a deprecated Node.js version and will be forced to run on node20: actions/setup-go@v3, actions/upload-artifact@v3. For more info: https://github.blog/changelog/2024-03-07-github-actions-all-actions-will-run-on-node20-instead-of-node16-by-default/