[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2024-11-07T10:28:11.148488005Z out: Wait for state of 089ec059-0767-4cd0-9b27-daf4271aec88 testContext.go:369: done waiting for State reboot_test.go:166: time: 2024-11-07T10:28:11.148513685Z out: timewait: 2562047h47m16.854775807s reboot_test.go:167: time: 2024-11-07T10:28:11.148518495Z out: reboot: false reboot_test.go:168: time: 2024-11-07T10:28:11.148521749Z out: count: 1 reboot_test.go:172: time: 2024-11-07T10:28:11.148528514Z out: LastRebootTime: 2024-11-07 10:26:57.650845343 +0000 UTC reboot_test.go:174: time: 2024-11-07T10:28:11.148532864Z out: LastRebootReason: NORMAL: First boot of device - at 2024-11-07T10:26:57.650845343Z rebooted with reason Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-07T10:35:08.528467855Z at 2024-11-07 10:35:08.528467855 +0000 UTC/n testProc.go:84: time: 2024-11-07T10:35:47.113973595Z 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 2024-11-07T10:35:08.528467855Z testContext.go:260: done for device 089ec059-0767-4cd0-9b27-daf4271aec88 reboot_test.go:186: time: 2024-11-07T10:35:47.114069003Z out: Number of reboots: 0 --- PASS: TestReboot (455.97s) PASS [stderr] time="2024-11-07T10:35:47Z" level=info msg="time: 2024-11-07T10:35:47.113973595Z 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 2024-11-07T10:35:08.528467855Z" 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-07T10:31:18.534243565Z out: appName eclient-mount state changed to CREATING_VOLUME time: 2024-11-07T10:31:19.819694358Z out: appName eclient-mount state changed to INSTALLED time: 2024-11-07T10:35:46.988300431Z 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] time: 2024-11-07T11:05:46.152400861Z out: VCOM Test started configName: default configFile: /home/runner/.eden/contexts/default.yml time="2024-11-07T11:05:46Z" level=debug msg="Will use config from /home/runner/.eden/contexts/default.yml" time="2024-11-07T11:05:46Z" level=debug msg="Try to add config from /tmp/go-test-script1255492030/script-eden_vcom/eden-config.yml" --- FAIL: TestVcomLinkTpmRequestEK (0.55s) vcom_test.go:41: time: 2024-11-07T11:05:46.189118744Z out: TestvComLinkTpmRequestEK started vcom_test.go:41: time: 2024-11-07T11:05:46.189132562Z out: Checking if vComLink is running on EVE... vcom_test.go:41: time: 2024-11-07T11:05:46.742299487Z out: vComLink agent is running vcom_test.go:41: time: 2024-11-07T11:05:46.742324303Z out: Checking if vComLink is reachable from a VM, deploying ubuntu-22.04-server-cloudimg-amd64... vcom_test.go:41: time: 2024-11-07T11:05:46.742356063Z 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=0xdc0a17] goroutine 45 [running]: testing.tRunner.func1.2({0xecde40, 0x19b54b0}) /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({0xecde40?, 0x19b54b0?}) /opt/hostedtoolcache/go/1.22.3/x64/src/runtime/panic.go:770 +0x132 command-line-arguments.TestVcomLinkTpmRequestEK.WithSSH.func2(0xc0008bdaa8?, {0xc00059b4b8?, 0xc800000?}) /home/runner/actions-runner/_work/eden/eden/eden/pkg/evetestkit/utils.go:451 +0x57 github.com/lf-edge/eden/pkg/evetestkit.(*EveNode).EveDeployApp(_, {_, _}, _, {{0xc00059b4b8, 0x17}, {0x10a8ea7, 0x4e}, {0x106b5df, 0x6}, ...}, ...) /home/runner/actions-runner/_work/eden/eden/eden/pkg/evetestkit/utils.go:501 +0xc4 command-line-arguments.TestVcomLinkTpmRequestEK(0xc000365520) /home/runner/actions-runner/_work/eden/eden/eden/tests/vcom/vcom_test.go:125 +0x51a testing.tRunner(0xc000365520, 0x10d26b8) /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.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[t1 t2]' state: 'RUNNING' secs: 900 time: 2024-11-07T10:50:41.302285304Z out: appName t1 state changed to UNKNOWN time: 2024-11-07T10:50:41.302297937Z out: appName t2 state changed to UNKNOWN time: 2024-11-07T10:50:48.842365073Z out: appName t2 state changed to RESOLVING_TAG time: 2024-11-07T10:50:48.842381823Z out: appName t1 state changed to RESOLVING_TAG time: 2024-11-07T10:50:50.843842504Z out: appName t1 state changed to DOWNLOAD_STARTED time: 2024-11-07T10:50:50.843858493Z out: appName t2 state changed to DOWNLOAD_STARTED time: 2024-11-07T10:50:50.843971279Z out: appName t1 state changed to UNKNOWN time: 2024-11-07T10:50:50.84398833Z out: appName t2 state changed to DOWNLOAD_STARTED (0%) time: 2024-11-07T10:51:10.85797489Z out: appName t2 state changed to DOWNLOAD_STARTED (100%) time: 2024-11-07T10:51:17.864308865Z out: appName t2 state changed to DOWNLOAD_STARTED (0%) time: 2024-11-07T10:51:26.870640676Z out: appName t2 state changed to DOWNLOAD_STARTED (5%) time: 2024-11-07T10:51:29.87335015Z out: appName t2 state changed to DOWNLOAD_STARTED (6%) time: 2024-11-07T10:51:32.876252479Z out: appName t2 state changed to DOWNLOAD_STARTED (8%) time: 2024-11-07T10:51:33.877418914Z out: appName t2 state changed to DOWNLOAD_STARTED (10%) time: 2024-11-07T10:51:35.87861664Z out: appName t2 state changed to DOWNLOAD_STARTED (13%) time: 2024-11-07T10:51:41.883971006Z out: appName t2 state changed to DOWNLOAD_STARTED (87%) time: 2024-11-07T10:51:42.884446629Z out: appName t2 state changed to DOWNLOAD_STARTED (93%) time: 2024-11-07T10:51:42.884491681Z out: appName t2 state changed to DOWNLOAD_STARTED (96%) time: 2024-11-07T10:51:43.885580621Z out: appName t2 state changed to DOWNLOAD_STARTED (100%) time: 2024-11-07T10:51:58.896617168Z out: appName t1 state changed to LOADING time: 2024-11-07T10:51:58.896693827Z out: appName t2 state changed to VERIFIED time: 2024-11-07T10:52:39.934171688Z out: appName t2 state changed to LOADING time: 2024-11-07T10:52:39.934193628Z out: appName t1 state changed to DELIVERED time: 2024-11-07T10:52:44.938499129Z out: appName t1 state changed to CREATING_VOLUME time: 2024-11-07T10:52:44.938516601Z out: appName t2 state changed to CREATING_VOLUME time: 2024-11-07T10:56:02.079472148Z out: appName t1 state changed to CREATING_VOLUME: [description:"PrepareContainerRootDir: Could not create snapshot 3d213280-72eb-4ace-9cb2-db2d25098f71#0.container. CreateSnapshotForImage: could not unpack clientImageObj deb3f956-81b3-4d82-9ec7-a0fae580bd4d-library/nginx@sha256:367678a80c0be120f67f3adfccc2f408bd2c1319ed98c1975ac88e750d0efe26: UnpackClientImage: unable to unpack image: deb3f956-81b3-4d82-9ec7-a0fae580bd4d-library/nginx@sha256:367678a80c0be120f67f3adfccc2f408bd2c1319ed98c1975ac88e750d0efe26: failed to extract layer sha256:b33db0c3c3a85f397c49b1bf862e0472bb39388bd7102c743660c9a22a124597: failed to unmount /persist/vault/containerd/tmpmounts/containerd-mount548374331: failed to unmount target /persist/vault/containerd/tmpmounts/containerd-mount548374331: device or resource busy: unknown\n\n" timestamp:{seconds:1730976960 nanos:736713688} severity:SEVERITY_ERROR entities:{entity:ENTITY_VOLUME entity_id:"3d213280-72eb-4ace-9cb2-db2d25098f71"}] time: 2024-11-07T10:56:15.085955566Z out: appName t2 state changed to INSTALLED time: 2024-11-07T10:56:35.102356364Z out: appName t2 state changed to BOOTING time: 2024-11-07T10:56:35.102372894Z out: appName t2 state changed to RUNNING app_test.go:178: ASSERTION FAILED (2024-11-07T11:05:41.302705577Z): expected apps [t1 t2] in RUNNING state app_test.go:180: actual t1: CREATING_VOLUME: [description:"PrepareContainerRootDir: Could not create snapshot 3d213280-72eb-4ace-9cb2-db2d25098f71#0.container. CreateSnapshotForImage: could not unpack clientImageObj deb3f956-81b3-4d82-9ec7-a0fae580bd4d-library/nginx@sha256:367678a80c0be120f67f3adfccc2f408bd2c1319ed98c1975ac88e750d0efe26: UnpackClientImage: unable to unpack image: deb3f956-81b3-4d82-9ec7-a0fae580bd4d-library/nginx@sha

Process completed with exit code 1.

[stdout] Reboot Test === RUN TestReboot reboot_test.go:162: time: 2024-11-07T11:03:39.442916532Z out: Wait for state of 4b588d2e-7f00-437d-8897-f6feeef22117 testContext.go:369: done waiting for State reboot_test.go:166: time: 2024-11-07T11:03:39.442935808Z out: timewait: 20m0s reboot_test.go:167: time: 2024-11-07T11:03:39.442939515Z out: reboot: true reboot_test.go:168: time: 2024-11-07T11:03:39.442950566Z out: count: 1 reboot_test.go:172: time: 2024-11-07T11:03:39.442956207Z out: LastRebootTime: 2024-11-07 10:46:15.458059392 +0000 UTC reboot_test.go:174: time: 2024-11-07T11:03:39.442959393Z out: LastRebootReason: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-07T10:46:15.458059392Z config changed, to see config run 'eden controller edge-node get-config' rebooted with reason Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-07T11:10:32.018871031Z at 2024-11-07 11:10:32.018871031 +0000 UTC/n reboot_test.go:64: abnormal reboot: Reboot reason - system reset, reboot or kernel panic due to watchdog or kernel bug (no kdump) - at 2024-11-07T11:10:32.018871031Z testContext.go:291: WaitForProc terminated by timeout 20m0s reboot_test.go:186: time: 2024-11-07T11:11:20.478038032Z out: Number of reboots: 0 --- FAIL: TestReboot (461.04s) FAIL FAIL: ../eclient/testdata/ctrl_cert_change.txt:62: command failure

Process completed with exit code 1.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[eclient1 eclient2]' state: 'RUNNING' secs: 1200 time: 2024-11-07T11:01:44.862313709Z out: appName eclient1 state changed to UNKNOWN time: 2024-11-07T11:01:44.862318038Z out: appName eclient2 state changed to UNKNOWN time: 2024-11-07T11:01:49.278739966Z out: appName eclient2 state changed to RUNNING time: 2024-11-07T11:01:56.285119428Z out: appName eclient1 state changed to HALTING time: 2024-11-07T11:01:56.285135188Z out: appName eclient2 state changed to HALTING time: 2024-11-07T11:02:00.287201732Z out: appName eclient2 state changed to HALTED time: 2024-11-07T11:02:01.287599454Z out: appName eclient1 state changed to HALTED time: 2024-11-07T11:05:00.410475174Z out: appName eclient1 state changed to UNKNOWN time: 2024-11-07T11:05:00.410485484Z out: appName eclient2 state changed to UNKNOWN time: 2024-11-07T11:05:03.413469064Z out: appName eclient1 state changed to INITIAL time: 2024-11-07T11:05:03.413479895Z out: appName eclient2 state changed to INITIAL time: 2024-11-07T11:05:25.428675577Z out: appName eclient2 state changed to INSTALLED time: 2024-11-07T11:05:26.429393373Z out: appName eclient1 state changed to INSTALLED app_test.go:178: ASSERTION FAILED (2024-11-07T11:21:44.862923458Z): expected apps [eclient1 eclient2] in RUNNING state app_test.go:180: actual eclient1: INSTALLED app_test.go:182: history of states for eclient1: app_test.go:184: state: no info from controller received in: 2024-11-07T11:01:44.862306897Z app_test.go:184: state: UNKNOWN received in: 2024-11-07T11:01:44.862311556Z app_test.go:184: state: HALTING received in: 2024-11-07T11:01:56.285114479Z app_test.go:184: state: HALTED received in: 2024-11-07T11:02:01.287595617Z app_test.go:184: state: UNKNOWN received in: 2024-11-07T11:05:00.410470225Z app_test.go:184: state: INITIAL received in: 2024-11-07T11:05:03.413465137Z app_test.go:184: state: INSTALLED received in: 2024-11-07T11:05:26.429390298Z --- app eclient1 logs --- {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Found user 'avahi' (UID 86) and group 'avahi' (GID 86).","msgid":"1","timestamp":"2024-11-07T11:00:57.595830009Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Successfully dropped root privileges.","msgid":"2","timestamp":"2024-11-07T11:00:57.604682744Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"avahi-daemon 0.8 starting up.","msgid":"3","timestamp":"2024-11-07T11:00:57.604784814Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"WARNING: No NSS support for mDNS detected, consider installing nss-mdns!","msgid":"4","timestamp":"2024-11-07T11:00:57.606177569Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Successfully called chroot().","msgid":"5","timestamp":"2024-11-07T11:00:57.610210793Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Successfully dropped remaining capabilities.","msgid":"6","timestamp":"2024-11-07T11:00:57.610317833Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Loading service file /services/http.service.","msgid":"7","timestamp":"2024-11-07T11:00:57.620471963Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Loading service file /services/sftp-ssh.service.","msgid":"8","timestamp":"2024-11-07T11:00:57.626775759Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Loading service file /services/ssh.service.","msgid":"9","timestamp":"2024-11-07T11:00:57.630044326Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Joining mDNS multicast group on interface nbu1x1.1.IPv4 with address 10.11.12.2.","msgid":"10","timestamp":"2024-11-07T11:00:57.638739063Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"New relevant interface nbu1x1.1.IPv4 for mDNS.","msgid":"11","timestamp":"2024-11-07T11:00:57.640105727Z"} {"source":"04537852-bec7-4303-a83c-5c45704fde51.1.1","content":"Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.","

Process completed with exit code 1.

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/