[stdout] time: 2024-10-23T09:16:11.402585346Z out: VCOM Test started configName: default configFile: /home/runner/.eden/contexts/default.yml time="2024-10-23T09:16:11Z" level=debug msg="Will use config from /home/runner/.eden/contexts/default.yml" time="2024-10-23T09:16:11Z" level=debug msg="Try to add config from /tmp/go-test-script1452809322/script-eden_vcom/eden-config.yml" --- FAIL: TestVcomLinkTpmRequestEK (0.63s) vcom_test.go:41: time: 2024-10-23T09:16:11.435650095Z out: TestvComLinkTpmRequestEK started vcom_test.go:41: time: 2024-10-23T09:16:11.435667377Z out: Checking if vComLink is running on EVE... vcom_test.go:41: time: 2024-10-23T09:16:12.062526533Z out: vComLink agent is running vcom_test.go:41: time: 2024-10-23T09:16:12.062559304Z out: Checking if vComLink is reachable from a VM, deploying ubuntu-22.04-server-cloudimg-amd64... vcom_test.go:41: time: 2024-10-23T09:16:12.062607865Z 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 27 [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(0xc000861aa8?, {0xc0006be138?, 0xc800000?}) /home/runner/actions-runner/_work/eden/eden/eden/pkg/evetestkit/utils.go:448 +0x57 github.com/lf-edge/eden/pkg/evetestkit.(*EveNode).EveDeployApp(_, {_, _}, _, {{0xc0006be138, 0x13}, {0x10a8c87, 0x4e}, {0x106b3bf, 0x6}, ...}, ...) /home/runner/actions-runner/_work/eden/eden/eden/pkg/evetestkit/utils.go:498 +0xc4 command-line-arguments.TestVcomLinkTpmRequestEK(0xc0007ac820) /home/runner/actions-runner/_work/eden/eden/eden/tests/vcom/vcom_test.go:125 +0x51a testing.tRunner(0xc0007ac820, 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.

[stdout] Docker app's state test === RUN TestAppStatus apps: '[curl-acl1 curl-acl2]' state: 'RUNNING' secs: 900 time: 2024-10-23T09:04:26.482239629Z out: appName curl-acl1 state changed to RESOLVING_TAG: [description:"Found error in content tree lfedge/eden-eclient:b1c1de6 attached to volume curl-acl1_0_m_0: lookupDatastoreConfig(780753ac-adac-47aa-a72e-8948892d8e01) error: Get(zedagent/DatastoreConfig) unknown key 780753ac-adac-47aa-a72e-8948892d8e01\n\n" timestamp:{seconds:1729674263 nanos:796774181} severity:SEVERITY_NOTICE entities:{entity:ENTITY_VOLUME entity_id:"34258498-cb32-4846-9da9-31e334bcc972"} retry_condition:"Will retry when datastore available"] time: 2024-10-23T09:04:26.482258783Z out: appName curl-acl2 state changed to UNKNOWN time: 2024-10-23T09:04:29.099916403Z out: appName curl-acl2 state changed to INITIAL time: 2024-10-23T09:04:30.101236764Z out: appName curl-acl2 state changed to INITIAL: [description:"Found error in content tree lfedge/eden-eclient:b1c1de6 attached to volume curl-acl2_0_m_0: lookupDatastoreConfig(780753ac-adac-47aa-a72e-8948892d8e01) error: Get(zedagent/DatastoreConfig) unknown key 780753ac-adac-47aa-a72e-8948892d8e01\n\n" timestamp:{seconds:1729674263 nanos:796774181} severity:SEVERITY_NOTICE entities:{entity:ENTITY_VOLUME entity_id:"dc501286-249d-42ab-b3cd-d2bc214b41a3"} retry_condition:"Will retry when datastore available"] time: 2024-10-23T09:05:48.164824843Z out: appName curl-acl1 state changed to RESOLVING_TAG: [description:"Found error in content tree lfedge/eden-eclient:b1c1de6 attached to volume curl-acl1_0_m_0: lookupDatastoreConfig(780753ac-adac-47aa-a72e-8948892d8e01) error: Get(zedagent/DatastoreConfig) unknown key 780753ac-adac-47aa-a72e-8948892d8e01\n\n" timestamp:{seconds:1729674346 nanos:833097279} severity:SEVERITY_NOTICE entities:{entity:ENTITY_VOLUME entity_id:"34258498-cb32-4846-9da9-31e334bcc972"} retry_condition:"Retrying; attempt 1"] time: 2024-10-23T09:05:49.16592988Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED time: 2024-10-23T09:05:50.167031031Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (0%) time: 2024-10-23T09:05:54.169987108Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (100%) time: 2024-10-23T09:06:00.175398677Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (0%) time: 2024-10-23T09:06:09.183479303Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (11%) time: 2024-10-23T09:06:11.185423869Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (25%) time: 2024-10-23T09:06:14.187878923Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (32%) time: 2024-10-23T09:06:16.189806836Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (55%) time: 2024-10-23T09:06:17.190155599Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (68%) time: 2024-10-23T09:06:17.190185191Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (80%) time: 2024-10-23T09:06:23.196461677Z out: appName curl-acl1 state changed to DOWNLOAD_STARTED (89%) time: 2024-10-23T09:06:25.19897943Z out: appName curl-acl1 state changed to LOADING time: 2024-10-23T09:06:39.212114365Z out: appName curl-acl1 state changed to CREATING_VOLUME time: 2024-10-23T09:07:09.234884963Z out: appName curl-acl1 state changed to INSTALLED time: 2024-10-23T09:07:26.24843541Z out: appName curl-acl1 state changed to BOOTING time: 2024-10-23T09:07:28.250202557Z out: appName curl-acl1 state changed to RUNNING app_test.go:178: ASSERTION FAILED (2024-10-23T09:19:26.482457595Z): expected apps [curl-acl1 curl-acl2] in RUNNING state app_test.go:180: actual curl-acl2: INITIAL: [description:"Found error in content tree lfedge/eden-eclient:b1c1de6 attached to volume curl-acl2_0_m_0: lookupDatastoreConfig(780753ac-adac-47aa-a72e-8948892d8e01) error: Get(zedagent/DatastoreConfig) unknown key 780753ac-adac-47aa-a72e-8948892d8e01\n\n" timestamp:{seconds:1729674263 nanos:796774181} severity:SEVERITY_NOTICE entities:{entity:ENTITY_VOLUME entity_id:"dc501286-249d-42ab-b3cd-d2bc214b41a3"} retry_con

Process completed with exit code 1.

[stdout] === RUN TestEdenScripts === RUN TestEdenScripts/revert_eve_image_update === PAUSE TestEdenScripts/revert_eve_image_update === CONT TestEdenScripts/revert_eve_image_update > eden controller edge-node update --config timer.test.baseimage.update=30 edenProg: /home/runner/actions-runner/_work/eden/eden/eden/dist/bin/eden timewait: 0s > message 'EVE image download' > eden -t 10m utils download eve-rootfs --eve-tag=13.3.0 --eve-hv=kvm --downloader-dist=/home/runner/actions-runner/_work/eden/eden/eden/dist/default-eve -v debug edenProg: /home/runner/actions-runner/_work/eden/eden/eden/dist/bin/eden timewait: 10m0s > stdout '/home/runner/actions-runner/_work/eden/eden/eden/dist/default-eve/rootfs-13.3.0-kvm-amd64.squashfs' > message 'EVE update request' > eden -t 10m controller edge-node eveimage-update file:///home/runner/actions-runner/_work/eden/eden/eden/dist/default-eve/rootfs-13.3.0-kvm-amd64.squashfs edenProg: /home/runner/actions-runner/_work/eden/eden/eden/dist/bin/eden timewait: 10m0s > ! stderr . > message 'Waiting for EVE starting testing...' > test eden.lim.test -test.v -timewait 30m -test.run TestInfo -out InfoContent.dinfo.SwList[0].ShortVersion 'InfoContent.dinfo.SwList[0].PartitionState:inprogress' testProg: /home/runner/actions-runner/_work/eden/eden/eden/dist/bin/eden.lim.test timewait: 0s ::error file=tests/update_eve_image/testdata/revert_eve_image_update.txt,line=35::[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-10-23T09:02:54.556373141Z out: Wait for info of f1630944-1da6-40c6-8d7c-e5f2b2e9acbf number=1 timewait=30m0s testContext.go:302: WaitForProc terminated by timeout 30m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 30m0s --- FAIL: TestInfo (1800.00s) FAIL [exit status 1] FAIL: /home/runner/actions-runner/_work/eden/eden/eden/dist/tests/update_eve_image/testdata/revert_eve_image_update.txt:35: command failure testscript.go:418: WORK=$WORK PATH=/home/runner/go/bin:/opt/hostedtoolcache/go/1.22.3/x64/bin:/snap/bin:/home/runner/.local/bin:/opt/pipx_bin:/home/runner/.cargo/bin:/home/runner/.config/composer/vendor/bin:/usr/local/.ghcup/bin:/home/runner/.dotnet/tools:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin DOCKER_HOST= TMPDIR=$WORK/tmp devnull=/dev/null /=/ :=: EDEN_CONFIG=default HOME=/no-home exe= # Obtain eve.tag from config # Obtain eve.hv from config # Obtain eve.arch from config # Combine variables into $short_version # Use eden.lim.test for access Infos with timewait 30m # Decrease update testing time (0.019s) > eden controller edge-node update --config timer.test.baseimage.update=30 # Download EVE rootfs into eve-dist (1.249s) > message 'EVE image download' message: EVE image download > eden -t 10m utils download eve-rootfs --eve-tag=13.3.0 --eve-hv=kvm --downloader-dist=/home/runner/actions-runner/_work/eden/eden/eden/dist/default-eve -v debug [stdout] configName: default configFile: /home/runner/.eden/contexts/default.yml time="2024-10-23T09:02:51Z" level=debug msg="Try ImagePull with (lfedge/eve:13.3.0-kvm-amd64)" time="2024-10-23T09:02:51Z" level=debug msg="Try to get version of rootfs" time="2024-10-23T09:02:51Z" level=debug msg="Try to call 'docker run lfedge/eve:13.3.0-kvm-amd64 -f raw version' with volumes map[]" time="2024-10-23T09:02:51Z" level=debug msg="image version: 13.3.0-kvm-amd64" time="2024-10-23T09:02:51Z" level=debug msg="provided version: 13.3.0-kvm-amd64" time="2024-10-23T09:02:51Z" level=debug msg="Try to call 'docker run lfedge/eve:13.3.0-kvm-amd64 -f raw rootfs' with volumes map[/out:/home/runner/actions-runner/_work/eden/eden/eden/dist/default-eve]" time="2024-10-23T09:02:52Z" level=debug msg="245+1 records in\r\n245+1 records out\r\n257822720 bytes (258 MB, 246 MiB)

[stdout] Log/Info/Metric Test === RUN TestInfo lim_test.go:273: time: 2024-10-23T09:02:54.556373141Z out: Wait for info of f1630944-1da6-40c6-8d7c-e5f2b2e9acbf number=1 timewait=30m0s testContext.go:302: WaitForProc terminated by timeout 30m0s testContext.go:285: WaitForProcWithErrorCallback terminated by timeout 30m0s --- FAIL: TestInfo (1800.00s) FAIL [exit status 1] FAIL: /home/runner/actions-runner/_work/eden/eden/eden/dist/tests/update_eve_image/testdata/revert_eve_image_update.txt:35: 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/