Error from server (NotFound): namespaces "devfile-proxy" not found Using Devfile proxy: cp ododevapispec.yaml pkg/apiserver-impl/swagger-ui/swagger.yaml go install -mod=vendor -ldflags="-X github.com/redhat-developer/odo/pkg/version.GITCOMMIT=b0412b0ee" ./cmd/odo/ go run -mod=vendor github.com/onsi/ginkgo/v2/ginkgo --randomize-all --poll-progress-after=120s --poll-progress-interval=120s -timeout 14400s --no-color -nodes=16 --junit-report="test-integration.xml" --label-filter="!unauth && !nocluster && !podman" tests/integration Running Suite: Integration Suite - /go/odo_1/tests/integration ============================================================== Random Seed: 1694794044 - will randomize all specs Will run 502 of 954 specs Running in parallel across 16 processes SSSSSSSSSSSSSSSSSSSSSSSSSSSSS••SS•SSSS•S•S••••SSS••••S•SSS•S••S•S••••S•SSS•SS•S••SSS ------------------------------ • [13.024 seconds] odo dev interactive command tests directory is not empty when Alizer cannot determine a Devfile based on the current source code should not fail but fallback to the interactive mode /go/odo_1/tests/integration/interactive_dev_test.go:164 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo dev --random-ports' from /tmp/228387834 << Captured StdOut/StdErr Output ------------------------------ S•SSSSSSS•SS•SSSSSSSSSSS ------------------------------ • [59.170 seconds] odo dev command tests when a component with multiple endpoints is run should create state files containing information, including forwarded ports /go/odo_1/tests/integration/cmd_dev_test.go:3592 Captured StdOut/StdErr Output >> PID: 14654 << Captured StdOut/StdErr Output ------------------------------ •SS ------------------------------ • [22.633 seconds] odo dev interactive command tests when a component is bootstrapped should sync files when p is pressed /go/odo_1/tests/integration/interactive_dev_test.go:209 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo dev --random-ports --no-watch' from /tmp/2055272957 << Captured StdOut/StdErr Output ------------------------------ •S••S•••S••S••SSSS•SS•SS•••••SS•S•S•SSS•SS•S••SS•SSSS••SS•SS•SSS•••••S ------------------------------ Progress Report for Ginkgo Process #6 Automatically polling progress: odo devfile deploy command tests when using a devfile.yaml containing an outer-loop Kubernetes component referenced via an URI when running odo deploy with image build extra args when running and stopping odo dev should not delete the resources created with odo deploy (Spec Runtime: 2m6.039s) /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:176 In [BeforeEach] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:169 Begin Captured GinkgoWriter Output >> ... [odo] I0915 16:12:33.052328 16843 execute_run.go:36] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd $PROJECT_SOURCE && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid]: error while streaming command: context canceled [odo] I0915 16:12:33.066452 16843 implem.go:114] The path for preference file is /tmp/699443460/preference.yaml [odo] I0915 16:12:33.067421 16843 segment.go:268] Checking telemetry enable status [odo] I0915 16:12:33.067433 16843 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0915 16:12:33.188416 16843 delete.go:174] Ignoring resource: deployments/my-component; its mode(Deploy) does not match with the given mode(Dev) [odo] I0915 16:12:33.231087 16843 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:12:33.231294 16843 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:12:33.992715 16843 all.go:67] all goroutines have returned in 761.628357ms [odo] I0915 16:12:33.992750 16843 all.go:76] query result: objects=627 << End Captured GinkgoWriter Output Spec Goroutine goroutine 264 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00015a770, {0x30f3d88?, 0xc000abffe0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00015a770, {0x30f3d88, 0xc000abffe0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc000f45ea8?, {0xc0007ee380?, 0x4382ec8?, 0x0?}) /go/odo_1/vendor/github.com/onsi/gomega/gexec/session.go:144 github.com/redhat-developer/odo/tests/helper.DevSession.WaitEnd(...) /go/odo_1/tests/helper/helper_dev.go:255 > github.com/redhat-developer/odo/tests/integration.glob..func9.6.2.4.1() /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:173 | Expect(err).ShouldNot(HaveOccurred()) | devSession.Stop() > devSession.WaitEnd() | }) | github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2694a13, 0xc000a371a0}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ Progress Report for Ginkgo Process #10 Automatically polling progress: odo dev command tests checking if odo dev matches local Devfile K8s resources and remote resources when odo dev is executed to run a devfile containing a k8s resource with apply command should have deleted the old resource and created the new resource (Spec Runtime: 2m4.094s) /go/odo_1/tests/integration/cmd_dev_test.go:1047 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:1020 Begin Captured GinkgoWriter Output >> ... [odo] I0915 16:12:58.740866 16904 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:12:58.742414 16904 port.go:319] port 8080 not listening in container "runtime" [odo] I0915 16:13:00.318095 16904 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: whuswv-app-77cc58cbdf-wsn2c in container: runtime [odo] I0915 16:13:01.268801 16904 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:13:01.268829 16904 exec.go:96] 0: 6A2E11AC:9A0C 23021068:01BB 06 00000000:00000000 03:00000FCB 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:13:01.268834 16904 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:13:01.268843 16904 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:13:01.268847 16904 exec.go:96] 0: 00000000000000000000000000000000:0BB8 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1009290000 0 961385613 1 0000000000000000 100 0 0 10 0 [odo] I0915 16:13:01.268851 16904 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:13:01.290432 16904 port.go:319] port 8080 not listening in container "runtime" << End Captured GinkgoWriter Output Spec Goroutine goroutine 187 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000334000, {0x30f3fc8?, 0xc00136e030}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000334000, {0x30f3fc8, 0xc00136e030}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2ce96fd, 0x8}, 0xc00029f900?, 0xc00029f8e0?, 0xc00136f830) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.(*DevSession).WaitSync(0xc00042b320) /go/odo_1/tests/helper/helper_dev.go:271 > github.com/redhat-developer/odo/tests/integration.glob..func7.5.1.1() /go/odo_1/tests/integration/cmd_dev_test.go:1039 | helper.ReplaceStrings(filepath.Join(commonVar.Context, "devfile.yaml"), devfile.deploymentName, devfile.newDeploymentName) | > err := devSession.WaitSync() | Expect(err).To(BeNil()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2694a13, 0xc000394ea0}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ •SS••SSSSSSS••••••SS••••SSSS••SSSS•SS•SSS•••SSSS•SSS••• ------------------------------ Progress Report for Ginkgo Process #13 Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 2m3.309s) /go/odo_1/tests/integration/cmd_dev_test.go:2248 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:2223 Begin Captured GinkgoWriter Output >> ... [odo] I0915 16:13:55.177932 18765 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:13:55.177937 18765 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:13:55.177941 18765 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:13:55.178991 18765 port.go:319] port 3000 not listening in container "runtime" [odo] I0915 16:14:04.760846 18765 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: qkmtla-app-85d7dcf789-dklbx in container: runtime [odo] I0915 16:14:05.020578 18765 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:14:05.020611 18765 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:14:05.020627 18765 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:14:05.020634 18765 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:14:05.024976 18765 port.go:319] port 3000 not listening in container "runtime" << End Captured GinkgoWriter Output Spec Goroutine goroutine 271 [select, 2 minutes] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000f5dc0, {0x30f3fc8?, 0xc000ade0c0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000f5dc0, {0x30f3fc8, 0xc000ade0c0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cf82d7, 0xf}, 0x0?, 0x3?, 0xc000ade060) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.StartDevMode({{0x0, 0x0, 0x0}, {0x0, 0x0, 0x0}, 0x0, 0x0, 0x0, 0x0, ...}) /go/odo_1/tests/helper/helper_dev.go:188 > github.com/redhat-developer/odo/tests/integration.glob..func7.23.1() /go/odo_1/tests/integration/cmd_dev_test.go:2237 | } | var err error > devSession, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x1085f3e, 0xc000d42000}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ ••SSS•S•SSS•SSSSS•S•••SSSS•SSS•SS•SS•S••••SS•••S•S••S•••SS••SSS•SS•••S•••S•SS•SSSSSSS•SSSSS•••SSSS•• ------------------------------ Progress Report for Ginkgo Process #1 Automatically polling progress: odo dev command tests when a component is bootstrapped ensure that index information is updated (Spec Runtime: 2m2.801s) /go/odo_1/tests/integration/cmd_dev_test.go:305 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:305 Begin Captured GinkgoWriter Output >> ... [odo] I0915 16:16:23.267190 23102 watch.go:271] deployment watcher Event: Type: MODIFIED, name: cjzurn-app, rv: 69883015, generation: 1, pods: 1 [odo] I0915 16:16:23.267202 23102 watch.go:333] Dev mode interrupted by user [odo] I0915 16:16:23.267561 23102 implem.go:114] The path for preference file is /tmp/2441975334/preference.yaml [odo] Cleaning resources, please wait [odo] I0915 16:16:23.268382 23102 segment.go:268] Checking telemetry enable status [odo] I0915 16:16:23.268391 23102 segment.go:286] Sending telemetry disabled by env variable [odo] I0915 16:16:23.417729 23102 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:16:23.422201 23102 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:16:24.091058 23102 all.go:67] all goroutines have returned in 673.34073ms [odo] I0915 16:16:24.091139 23102 all.go:76] query result: objects=606 << End Captured GinkgoWriter Output Spec Goroutine goroutine 588 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0003be070, {0x30f3d88?, 0xc000e963d8}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0003be070, {0x30f3d88, 0xc000e963d8}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc0015d7e08?, {0xc000708130?, 0xc00120fd20?, 0xc0004d40e0?}) /go/odo_1/vendor/github.com/onsi/gomega/gexec/session.go:144 github.com/redhat-developer/odo/tests/helper.DevSession.WaitEnd(...) /go/odo_1/tests/helper/helper_dev.go:255 github.com/redhat-developer/odo/tests/helper.RunDevMode.func1() /go/odo_1/tests/helper/helper_dev.go:326 github.com/redhat-developer/odo/tests/helper.RunDevMode({{0x0, 0x0, 0x0}, {0x0, 0x0, 0x0}, 0x0, 0x0, 0x0, 0x0, ...}, ...) /go/odo_1/tests/helper/helper_dev.go:329 > github.com/redhat-developer/odo/tests/integration.glob..func7.4.14() /go/odo_1/tests/integration/cmd_dev_test.go:306 | | It("ensure that index information is updated", func() { > err := helper.RunDevMode(helper.DevSessionOpts{}, func(session *gexec.Session, outContents, errContents string, ports map[string]string) { | indexAfterPush, err := util.ReadFileIndex(filepath.Join(commonVar.Context, ".odo", "odo-file-index.json")) | Expect(err).ToNot(HaveOccurred()) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x1085f3e, 0xc0003ccde0}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ •••S•S ------------------------------ • [82.718 seconds] odo dev interactive command tests directory is not empty when there is a match from Alizer should run alizer to download devfile /go/odo_1/tests/integration/interactive_dev_test.go:76 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo dev --random-ports' from /tmp/2868002007 << Captured StdOut/StdErr Output ------------------------------ S•SS•••SSSSSSSS•SSS•SS••S••SSSS••S•S••SS•S•S•SS•S•S•SS••SSSSS•S••S•S••••S•S•S••SS•••SSS•S••SS•SSSSS••SSSSS••SSS•SS•S•SS••SSSSS•••S•SSSSSSSSSSSS••S•S•S•SSSSSSSSS••S••SSSS ------------------------------ • [7.042 seconds] odo deploy interactive command tests directory is not empty when Alizer cannot determine a Devfile based on the current source code should not fail but fallback to the interactive mode /go/odo_1/tests/integration/interactive_deploy_test.go:159 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo deploy' from /tmp/1996975602 << Captured StdOut/StdErr Output ------------------------------ •SSS••SS•••S•SS••SSSSSSS•••• ------------------------------ Progress Report for Ginkgo Process #3 Automatically polling progress: odo dev command tests Devfile contains pod-overrides and container-overrides attributes should override the content in the pod it creates for the component on the cluster (Spec Runtime: 2m2.841s) /go/odo_1/tests/integration/cmd_dev_test.go:4116 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:4116 Begin Captured GinkgoWriter Output >> ... [odo] Cleaning resources, please wait [odo] I0915 16:20:34.369551 30351 exec.go:54] ExecuteCommand returned an an err: error while streaming command: error sending request: Post "https://c100-e.eu-de.containers.cloud.ibm.com:31952/api/v1/namespaces/cmd-dev-test4116grn/pods/lnnjfc-app-766d878674-brlr8/exec?command=%2Fbin%2Fsh&command=-c&command=cat+%2Fopt%2Fodo%2F.odo_cmd_run.pid+%7C%7C+true&container=runtime&stderr=true&stdout=true": dial tcp: lookup c100-e.eu-de.containers.cloud.ibm.com: operation was canceled. for command '[/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true]' [odo] stdout: [] [odo] stderr: [] [odo] ✗ Finished executing the application (command: run) [1m] [odo] I0915 16:20:34.369778 30351 execute_run.go:36] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd ${PROJECT_SOURCE} && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid]: error while streaming command: context canceled [odo] I0915 16:20:34.450674 30351 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:20:34.450882 30351 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:20:35.192605 30351 all.go:67] all goroutines have returned in 741.93591ms [odo] I0915 16:20:35.192705 30351 all.go:76] query result: objects=611 << End Captured GinkgoWriter Output Spec Goroutine goroutine 784 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000582bd0, {0x30f3d88?, 0xc000c54660}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000582bd0, {0x30f3d88, 0xc000c54660}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc001265d70?, {0xc0006068e0?, 0x2ceb4b0?, 0x9?}) /go/odo_1/vendor/github.com/onsi/gomega/gexec/session.go:144 github.com/redhat-developer/odo/tests/helper.DevSession.WaitEnd(...) /go/odo_1/tests/helper/helper_dev.go:255 github.com/redhat-developer/odo/tests/helper.RunDevMode.func1() /go/odo_1/tests/helper/helper_dev.go:326 github.com/redhat-developer/odo/tests/helper.RunDevMode({{0x0, 0x0, 0x0}, {0x0, 0x0, 0x0}, 0x0, 0x0, 0x0, 0x0, ...}, ...) /go/odo_1/tests/helper/helper_dev.go:329 > github.com/redhat-developer/odo/tests/integration.glob..func7.64.2() /go/odo_1/tests/integration/cmd_dev_test.go:4117 | }) | It("should override the content in the pod it creates for the component on the cluster", func() { > err := helper.RunDevMode(helper.DevSessionOpts{ | RunOnPodman: ctx.podman, | }, func(session *gexec.Session, outContents, _ string, _ map[string]string) { github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x1085f3e, 0xc001077ce0}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ •SS•SSS•••S••••SSSS••S•S•S••SSSSSS•SSS•SS••••SS•S•SSSS•S••S••S•S•S••SSS••SS ------------------------------ • [4.392 seconds] odo deploy interactive command tests directory is not empty when there is a match from Alizer should run alizer to download devfile /go/odo_1/tests/integration/interactive_deploy_test.go:75 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo deploy' from /tmp/1623025032 << Captured StdOut/StdErr Output ------------------------------ •S•SSS••SS•SS•SSS••SSSSSS••••••SS••••S••S ------------------------------ • [57.452 seconds] odo dev command with api server tests when the component is bootstrapped when odo dev is run with --api-server flag when /instance endpoint is DELETEd should terminate the dev session /go/odo_1/tests/integration/cmd_dev_api_server_test.go:239 Captured StdOut/StdErr Output >> <<< Session terminated >>> << Captured StdOut/StdErr Output ------------------------------ •SSSSSSSSS•S•SS•SS••SSS•S••S•••S•S•S•••SSSSSSS••S••SS••SS••SS••SSSS•SS•S ------------------------------ • [FAILED] [96.766 seconds] odo logs command tests when component is created and odo logs is executed when running in Dev mode with --logs [It] 1. should successfully show logs of the running component /go/odo_1/tests/integration/cmd_logs_test.go:249 Timeline >> Created dir: /tmp/2912389663 Created dir: /tmp/3932679108 Setting KUBECONFIG=/tmp/3932679108/config Running oc with args [oc get project cmd-logs-test249kbi -o jsonpath={.metadata.name}] and odo env: [] [oc] Error from server (NotFound): namespaces "cmd-logs-test249kbi" not found Creating a new project: cmd-logs-test249kbi Running oc with args [oc new-project cmd-logs-test249kbi] and odo env: [] [oc] Now using project "cmd-logs-test249kbi" on server "https://c100-e.eu-de.containers.cloud.ibm.com:31952". [oc] [oc] You can add applications to this project with the 'new-app' command. For example, try: [oc] [oc] oc new-app rails-postgresql-example [oc] [oc] to build a new example application in Ruby. Or use kubectl to deploy a simple Kubernetes application: [oc] [oc] kubectl create deployment hello-node --image=k8s.gcr.io/e2e-test-images/agnhost:2.33 -- /agnhost serve-hostname [oc] Running oc with args [oc get project] and odo env: [] [oc] NAME DISPLAY NAME STATUS [oc] calico-system Active [oc] cmd-delete-test428rtg Active [oc] cmd-dev-debug-test501fgl Active [oc] cmd-dev-debug-test76uvj Active [oc] cmd-dev-test1183bmv Active [oc] cmd-dev-test1252ohd Active [oc] cmd-dev-test1349lid Active [oc] cmd-dev-test156wqz Active [oc] cmd-dev-test1661squ Active [oc] cmd-dev-test1687oxw Active [oc] cmd-dev-test2358orb Active [oc] cmd-dev-test2519nwe Active [oc] cmd-dev-test2644gye Active [oc] cmd-dev-test2679hmt Terminating [oc] cmd-dev-test4059atl Active [oc] cmd-dev-test4075qmw Terminating [oc] cmd-dev-test951jda Terminating [oc] cmd-devfile-list-test334ahs Active [oc] cmd-logs-test249kbi Active [oc] cmd-logs-test291ayh Active [oc] default Active [oc] ibm-cert-store Active [oc] ibm-odf-validation-webhook Active [oc] ibm-system Active [oc] kube-node-lease Active [oc] kube-public Active [oc] kube-system Active [oc] laxu-namespace Active [oc] openshift Active [oc] openshift-apiserver Active [oc] openshift-apiserver-operator Active [oc] openshift-authentication Active [oc] openshift-authentication-operator Active [oc] openshift-cloud-credential-operator Active [oc] openshift-cloud-network-config-controller Active [oc] openshift-cluster-csi-drivers Active [oc] openshift-cluster-machine-approver Active [oc] openshift-cluster-node-tuning-operator Active [oc] openshift-cluster-samples-operator Active [oc] openshift-cluster-storage-operator Active [oc] openshift-cluster-version Active [oc] openshift-config Active [oc] openshift-config-managed Active [oc] openshift-config-operator Active [oc] openshift-console Active [oc] openshift-console-operator Active [oc] openshift-console-user-settings Active [oc] openshift-controller-manager Active [oc] openshift-controller-manager-operator Active [oc] openshift-dns Active [oc] openshift-dns-operator Active [oc] openshift-etcd Active [oc] openshift-etcd-operator Active [oc] openshift-image-registry Active [oc] openshift-infra Active [oc] openshift-ingress Active [oc] openshift-ingress-canary Active [oc] openshift-ingress-operator Active [oc] openshift-insights Active [oc] openshift-kube-apiserver Active [oc] openshift-kube-apiserver-operator Active [oc] openshift-kube-controller-manager Active [oc] openshift-kube-controller-manager-operator Active [oc] openshift-kube-proxy Active [oc] openshift-kube-scheduler Active [oc] openshift-kube-scheduler-operator Active [oc] openshift-kube-storage-version-migrator Active [oc] openshift-kube-storage-version-migrator-operator Active [oc] openshift-machine-api Active [oc] openshift-machine-config-operator Active [oc] openshift-marketplace Active [oc] openshift-monitoring Active [oc] openshift-multus Active [oc] openshift-network-diagnostics Active [oc] openshift-network-operator Active [oc] openshift-node Active [oc] openshift-operator-lifecycle-manager Active [oc] openshift-operators Active [oc] openshift-roks-metrics Active [oc] openshift-route-controller-manager Active [oc] openshift-service-ca Active [oc] openshift-service-ca-operator Active [oc] openshift-user-workload-monitoring Active [oc] phmartin Active [oc] tigera-operator Active [oc] ubkm-project Active Running oc with args [oc create configmap config-map-for-cleanup --from-literal type=testing --from-literal team=odo -n cmd-logs-test249kbi] and odo env: [] [oc] configmap/config-map-for-cleanup created Current working dir: /go/odo_1/tests/integration Running odo with args [odo preference remove registry DefaultDevfileRegistry -f] and odo env: [] [odo] I0915 16:24:24.951971 8175 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] Successfully removed registry [odo] I0915 16:24:24.954623 8175 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] I0915 16:24:24.955116 8175 segment.go:268] Checking telemetry enable status [odo] I0915 16:24:24.955148 8175 segment.go:286] Sending telemetry disabled by env variable Running odo with args [odo preference add registry DefaultDevfileRegistry https://devfile-registry-ci-devfile-registry.odo-test-kubernete-449701-49529fc6e6a4a9fe7ebba9a3db5b55c4-0000.eu-de.containers.appdomain.cloud] and odo env: [] [odo] I0915 16:24:25.281970 8192 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] New registry successfully added [odo] I0915 16:24:25.288166 8192 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] I0915 16:24:25.288641 8192 segment.go:268] Checking telemetry enable status [odo] I0915 16:24:25.288667 8192 segment.go:286] Sending telemetry disabled by env variable Setting current dir to: /tmp/2912389663 Running odo with args [odo init --name lwvxbb --devfile-path /go/odo_1/tests/examples/source/devfiles/nodejs/devfile-deploy-functional-pods.yaml] and odo env: [] [odo] I0915 16:24:25.614727 8213 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] __ [odo] / \__ Initializing a new component [odo] \__/ \ [odo] / \__/ odo version: v3.14.0 (b0412b0ee) [odo] \__/ [odo] [odo] • Copying devfile from "/go/odo_1/tests/examples/source/devfiles/nodejs/devfile-deploy-functional-pods.yaml" ... [odo] ✓ Copying devfile from "/go/odo_1/tests/examples/source/devfiles/nodejs/devfile-deploy-functional-pods.yaml" [4ms] [odo] I0915 16:24:25.623344 8213 context.go:172] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:25.623355 8213 context.go:113] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:25.627317 8213 content.go:48] converted devfile YAML to JSON [odo] I0915 16:24:25.627586 8213 apiVersion.go:60] devfile schemaVersion: '2.2.0' [odo] I0915 16:24:25.627602 8213 helper.go:56] devfile apiVersion '2.2.0' is supported [odo] I0915 16:24:25.635363 8213 schema.go:61] validated devfile schema [odo] I0915 16:24:25.646423 8213 writer.go:55] devfile yaml created at: 'devfile.yaml' [odo] [odo] Your new component 'lwvxbb' is ready in the current directory. [odo] To start editing your component, use 'odo dev' and open this folder in your favorite IDE. [odo] Changes will be directly reflected on the cluster. [odo] To deploy your component to a cluster use "odo deploy". [odo] I0915 16:24:25.648697 8213 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] I0915 16:24:25.649384 8213 segment.go:268] Checking telemetry enable status [odo] I0915 16:24:25.649394 8213 segment.go:286] Sending telemetry disabled by env variable Running odo with args [odo dev --random-ports --api-server=false --logs] and odo env: [ODO_LOG_LEVEL=4 ODO_TRACKING_CONSENT=no] [odo] I0915 16:24:25.876951 8230 version.go:37] executing [podman version --format json] [odo] I0915 16:24:25.877541 8230 clientset.go:232] no Podman client initialized: exec: "podman": executable file not found in $PATH [odo] I0915 16:24:25.877563 8230 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] I0915 16:24:25.880796 8230 context.go:172] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:25.880815 8230 context.go:113] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:25.881758 8230 content.go:48] converted devfile YAML to JSON [odo] I0915 16:24:25.881858 8230 apiVersion.go:60] devfile schemaVersion: '2.2.0' [odo] I0915 16:24:25.882045 8230 helper.go:56] devfile apiVersion '2.2.0' is supported [odo] I0915 16:24:25.885491 8230 schema.go:61] validated devfile schema [odo] I0915 16:24:25.887928 8230 validate.go:45] Successfully validated devfile sections [odo] I0915 16:24:25.887947 8230 validate.go:45] Successfully validated devfile sections [odo] I0915 16:24:25.888012 8230 component.go:87] name of component is "lwvxbb", and sanitized name is "lwvxbb" [odo] I0915 16:24:25.888068 8230 kclient.go:237] Checking if "projects" resource is supported [odo] __ [odo] / \__ Developing using the "lwvxbb" Devfile [odo] \__/ \ Namespace: cmd-logs-test249kbi [odo] / \__/ odo version: v3.14.0 (b0412b0ee) [odo] \__/ [odo] [odo] ↪ Running on the cluster in Dev mode [odo] I0915 16:24:26.118080 8230 kubedev.go:79] Creating new adapter [odo] I0915 16:24:26.118125 8230 kubedev.go:87] Creating inner-loop resources for the component [odo] I0915 16:24:26.118956 8230 watch.go:105] starting WatchAndPush, path: /tmp/2912389663, component: lwvxbb, ignores [.git .odo .odo/odo-file-index.json] [odo] I0915 16:24:26.123423 8230 file_watcher.go:130] adding watch on path /tmp/2912389663 [odo] I0915 16:24:26.123524 8230 file_watcher.go:130] adding watch on path /tmp/2912389663/.odo [odo] I0915 16:24:26.136963 8230 watch.go:423] Copying files [] to pod [odo] I0915 16:24:26.138098 8230 context.go:172] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:26.138122 8230 context.go:113] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:26.139699 8230 content.go:48] converted devfile YAML to JSON [odo] I0915 16:24:26.139914 8230 apiVersion.go:60] devfile schemaVersion: '2.2.0' [odo] I0915 16:24:26.139934 8230 helper.go:56] devfile apiVersion '2.2.0' is supported [odo] I0915 16:24:26.144902 8230 schema.go:61] validated devfile schema [odo] I0915 16:24:26.147608 8230 validate.go:45] Successfully validated devfile sections [odo] I0915 16:24:26.147640 8230 components.go:69] component state: "" [odo] • Waiting for Kubernetes resources ... [odo] I0915 16:24:26.168090 8230 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:nodejs] [odo] I0915 16:24:26.217476 8230 utils.go:149] No entrypoint defined for the component, setting container runtime entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0915 16:24:26.240414 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:26.240600 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:26.284321 8230 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0915 16:24:26.307604 8230 all.go:67] all goroutines have returned in 67.483887ms [odo] I0915 16:24:26.307628 8230 all.go:76] query result: objects=0 [odo] I0915 16:24:26.338222 8230 components.go:346] Creating deployment lwvxbb-app [odo] I0915 16:24:26.338248 8230 components.go:347] The component name is lwvxbb [odo] I0915 16:24:26.342176 8230 kclient.go:286] Kubernetes version is "v1.25.11+1485cc9" [odo] I0915 16:24:26.342201 8230 kclient.go:298] Cluster has support for SSA: true [odo] Warning: would violate PodSecurity "restricted:v1.24": allowPrivilegeEscalation != false (container "runtime" must set securityContext.allowPrivilegeEscalation=false), unrestricted capabilities (container "runtime" must set securityContext.capabilities.drop=["ALL"]), runAsNonRoot != true (pod or container "runtime" must set securityContext.runAsNonRoot=true), seccompProfile (pod or container "runtime" must set securityContext.seccompProfile.type to "RuntimeDefault" or "Localhost") [odo] I0915 16:24:26.488721 8230 components.go:378] Successfully created component lwvxbb [odo] I0915 16:24:26.556179 8230 components.go:390] Successfully created Service for component lwvxbb [odo] I0915 16:24:26.582306 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:26.586811 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:26.716141 8230 all.go:67] all goroutines have returned in 133.832936ms [odo] I0915 16:24:26.718236 8230 all.go:76] query result: objects=5 [odo] I0915 16:24:26.726459 8230 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I0915 16:24:26.731427 8230 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I0915 16:24:26.745511 8230 service.go:75] Getting list of services [odo] I0915 16:24:26.745527 8230 operators.go:30] Fetching list of operators installed in cluster [odo] I0915 16:24:26.751151 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:26.751426 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:26.807789 8230 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I0915 16:24:26.808257 8230 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:26.890104 8230 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:26.901611 8230 all.go:67] all goroutines have returned in 150.46055ms [odo] I0915 16:24:26.901624 8230 all.go:76] query result: objects=6 [odo] I0915 16:24:26.905526 8230 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:26.928290 8230 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:26.950160 8230 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0915 16:24:26.950172 8230 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0915 16:24:26.953639 8230 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0915 16:24:26.956560 8230 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0915 16:24:26.961322 8230 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0915 16:24:26.987799 8230 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0915 16:24:26.990596 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:26.990726 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:27.003813 8230 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0915 16:24:27.057028 8230 all.go:67] all goroutines have returned in 66.422833ms [odo] I0915 16:24:27.057180 8230 all.go:76] query result: objects=6 [odo] I0915 16:24:27.070174 8230 components.go:134] Deployment has been updated to generation 1. Waiting new event... [odo] I0915 16:24:27.070283 8230 status.go:34] setting inner loop State "WaitDeployment" [odo] ⚠ Pod is Pending [odo] I0915 16:24:27.073625 8230 watch.go:271] deployment watcher Event: Type: ADDED, name: lwvxbb-app, rv: 69909800, generation: 1, pods: 0 [odo] I0915 16:24:27.075970 8230 watch.go:271] deployment watcher Event: Type: MODIFIED, name: lwvxbb-app, rv: 69909808, generation: 1, pods: 0 [odo] I0915 16:24:27.077943 8230 watch.go:271] deployment watcher Event: Type: MODIFIED, name: lwvxbb-app, rv: 69909815, generation: 1, pods: 0 [odo] I0915 16:24:27.079550 8230 watch.go:271] deployment watcher Event: Type: MODIFIED, name: lwvxbb-app, rv: 69909834, generation: 1, pods: 0 [odo] I0915 16:24:27.229674 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:27.229834 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:27.335288 8230 all.go:67] all goroutines have returned in 105.085891ms [odo] I0915 16:24:27.335409 8230 all.go:76] query result: objects=6 [odo] I0915 16:24:27.377589 8230 watch.go:423] Copying files [] to pod [odo] I0915 16:24:27.382319 8230 context.go:172] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:27.382494 8230 context.go:113] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:27.384735 8230 content.go:48] converted devfile YAML to JSON [odo] I0915 16:24:27.384997 8230 apiVersion.go:60] devfile schemaVersion: '2.2.0' [odo] I0915 16:24:27.385706 8230 helper.go:56] devfile apiVersion '2.2.0' is supported [odo] I0915 16:24:27.391251 8230 schema.go:61] validated devfile schema [odo] I0915 16:24:27.392892 8230 validate.go:45] Successfully validated devfile sections [odo] I0915 16:24:27.393276 8230 components.go:69] component state: "WaitDeployment" [odo] I0915 16:24:27.410056 8230 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:nodejs] [odo] I0915 16:24:27.442210 8230 utils.go:149] No entrypoint defined for the component, setting container runtime entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0915 16:24:27.476994 8230 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0915 16:24:27.523750 8230 components.go:346] Creating deployment lwvxbb-app [odo] I0915 16:24:27.523784 8230 components.go:347] The component name is lwvxbb [odo] I0915 16:24:27.523798 8230 components.go:350] The component already exists, attempting to update it [odo] I0915 16:24:27.523813 8230 components.go:352] Applying deployment [odo] I0915 16:24:27.597668 8230 components.go:361] Successfully updated component lwvxbb [odo] I0915 16:24:27.641094 8230 components.go:748] Successfully update Service for component lwvxbb [odo] I0915 16:24:27.674398 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:27.674553 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:27.746961 8230 all.go:67] all goroutines have returned in 72.551119ms [odo] I0915 16:24:27.747000 8230 all.go:76] query result: objects=6 [odo] I0915 16:24:27.749283 8230 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I0915 16:24:27.749352 8230 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I0915 16:24:27.750297 8230 service.go:75] Getting list of services [odo] I0915 16:24:27.750322 8230 operators.go:30] Fetching list of operators installed in cluster [odo] I0915 16:24:27.807800 8230 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I0915 16:24:27.807895 8230 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:27.857556 8230 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:27.890535 8230 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:27.911116 8230 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:27.920191 8230 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0915 16:24:27.920214 8230 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0915 16:24:27.924092 8230 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0915 16:24:27.927216 8230 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0915 16:24:27.931035 8230 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0915 16:24:27.939613 8230 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0915 16:24:27.946385 8230 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0915 16:24:28.005604 8230 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I0915 16:24:28.005637 8230 status.go:34] setting inner loop State "WaitDeployment" [odo] I0915 16:24:28.005677 8230 watch.go:271] deployment watcher Event: Type: MODIFIED, name: lwvxbb-app, rv: 69909865, generation: 1, pods: 0 [odo] I0915 16:24:28.249969 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:28.250091 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:28.298594 8230 all.go:67] all goroutines have returned in 48.621646ms [odo] I0915 16:24:28.298902 8230 all.go:76] query result: objects=6 [odo] I0915 16:24:28.376607 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:28.376711 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:28.514186 8230 all.go:67] all goroutines have returned in 137.576412ms [odo] I0915 16:24:28.514204 8230 all.go:76] query result: objects=6 [odo] ✓ Pod is Running [odo] I0915 16:24:29.344462 8230 watch.go:271] deployment watcher Event: Type: MODIFIED, name: lwvxbb-app, rv: 69909960, generation: 1, pods: 1 [odo] I0915 16:24:29.446196 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:29.446927 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:29.511497 8230 all.go:67] all goroutines have returned in 65.305028ms [odo] I0915 16:24:29.511528 8230 all.go:76] query result: objects=6 [odo] --> Logs for lwvxbb-app-845986b854-nl689 / runtime [odo] I0915 16:24:29.645728 8230 watch.go:423] Copying files [] to pod [odo] I0915 16:24:29.646348 8230 context.go:172] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:29.646367 8230 context.go:113] absolute devfile path: '/tmp/2912389663/devfile.yaml' [odo] I0915 16:24:29.647866 8230 content.go:48] converted devfile YAML to JSON [odo] I0915 16:24:29.648032 8230 apiVersion.go:60] devfile schemaVersion: '2.2.0' [odo] I0915 16:24:29.648045 8230 helper.go:56] devfile apiVersion '2.2.0' is supported [odo] I0915 16:24:29.652741 8230 schema.go:61] validated devfile schema [odo] I0915 16:24:29.653026 8230 validate.go:45] Successfully validated devfile sections [odo] I0915 16:24:29.653049 8230 components.go:69] component state: "WaitDeployment" [odo] I0915 16:24:29.692335 8230 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:nodejs] [odo] I0915 16:24:29.734919 8230 utils.go:149] No entrypoint defined for the component, setting container runtime entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0915 16:24:29.784304 8230 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0915 16:24:29.834540 8230 components.go:346] Creating deployment lwvxbb-app [odo] I0915 16:24:29.834556 8230 components.go:347] The component name is lwvxbb [odo] I0915 16:24:29.834562 8230 components.go:350] The component already exists, attempting to update it [odo] I0915 16:24:29.834569 8230 components.go:352] Applying deployment [odo] I0915 16:24:29.895188 8230 components.go:361] Successfully updated component lwvxbb [odo] I0915 16:24:29.925064 8230 components.go:748] Successfully update Service for component lwvxbb [odo] I0915 16:24:29.959821 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:29.963142 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:30.035908 8230 all.go:67] all goroutines have returned in 76.086118ms [odo] I0915 16:24:30.035932 8230 all.go:76] query result: objects=6 [odo] I0915 16:24:30.041389 8230 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I0915 16:24:30.041629 8230 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I0915 16:24:30.042276 8230 service.go:75] Getting list of services [odo] I0915 16:24:30.042305 8230 operators.go:30] Fetching list of operators installed in cluster [odo] I0915 16:24:30.084985 8230 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I0915 16:24:30.085051 8230 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:30.105543 8230 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:30.118861 8230 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:30.131081 8230 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0915 16:24:30.140564 8230 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0915 16:24:30.140589 8230 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0915 16:24:30.144451 8230 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0915 16:24:30.148509 8230 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0915 16:24:30.152654 8230 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0915 16:24:30.163885 8230 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0915 16:24:30.179719 8230 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] • Syncing files into the container ... [odo] I0915 16:24:30.268393 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663, destBase: ., destFile: . [odo] I0915 16:24:30.268407 8230 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I0915 16:24:30.269209 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/.gitignore, destBase: ., destFile: .gitignore [odo] I0915 16:24:30.269219 8230 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I0915 16:24:30.269432 8230 file_indexer.go:453] file added: /tmp/2912389663/.gitignore [odo] I0915 16:24:30.269466 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/.odo, destBase: ., destFile: .odo [odo] I0915 16:24:30.269487 8230 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I0915 16:24:30.269786 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/Dockerfile, destBase: ., destFile: Dockerfile [odo] I0915 16:24:30.269794 8230 file_indexer.go:406] Corrected destinations: base: . file: Dockerfile [odo] I0915 16:24:30.269990 8230 file_indexer.go:453] file added: /tmp/2912389663/Dockerfile [odo] I0915 16:24:30.270004 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I0915 16:24:30.270009 8230 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I0915 16:24:30.270340 8230 file_indexer.go:453] file added: /tmp/2912389663/devfile.yaml [odo] I0915 16:24:30.270380 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/package.json, destBase: ., destFile: package.json [odo] I0915 16:24:30.270398 8230 file_indexer.go:406] Corrected destinations: base: . file: package.json [odo] I0915 16:24:30.270599 8230 file_indexer.go:453] file added: /tmp/2912389663/package.json [odo] I0915 16:24:30.270635 8230 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/server.js, destBase: ., destFile: server.js [odo] I0915 16:24:30.270652 8230 file_indexer.go:406] Corrected destinations: base: . file: server.js [odo] I0915 16:24:30.270927 8230 file_indexer.go:453] file added: /tmp/2912389663/server.js [odo] I0915 16:24:30.271379 8230 sync.go:138] List of files to be deleted: +[] [odo] I0915 16:24:30.271437 8230 sync.go:140] List of files changed: +[/tmp/2912389663/.gitignore /tmp/2912389663/Dockerfile /tmp/2912389663/devfile.yaml /tmp/2912389663/package.json /tmp/2912389663/server.js] [odo] I0915 16:24:30.271469 8230 sync.go:193] Push: componentName: lwvxbb, path: /tmp/2912389663, files: [/tmp/2912389663/.gitignore /tmp/2912389663/Dockerfile /tmp/2912389663/devfile.yaml /tmp/2912389663/package.json /tmp/2912389663/server.js], delFiles: [*], isForcePush: true [odo] I0915 16:24:30.272057 8230 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I0915 16:24:30.272191 8230 exec.go:37] Executing command [rm -rf /projects/*] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:30.523242 8230 sync.go:233] Copying files /tmp/2912389663/.gitignore /tmp/2912389663/Dockerfile /tmp/2912389663/devfile.yaml /tmp/2912389663/package.json /tmp/2912389663/server.js to pod [odo] I0915 16:24:30.523265 8230 copy.go:36] CopyFile arguments: localPath /tmp/2912389663, dest /projects/2912389663, targetPath /projects, copyFiles [/tmp/2912389663/.gitignore /tmp/2912389663/Dockerfile /tmp/2912389663/devfile.yaml /tmp/2912389663/package.json /tmp/2912389663/server.js], globalExps [.git .odo .odo/odo-file-index.json] [odo] I0915 16:24:30.523289 8230 copy.go:63] Executing command tar xf - -C /projects --no-same-owner [odo] I0915 16:24:30.524060 8230 copy.go:96] makeTar arguments: srcPath: /tmp/2912389663, destPath: /projects/2912389663, files: [/tmp/2912389663/.gitignore /tmp/2912389663/Dockerfile /tmp/2912389663/devfile.yaml /tmp/2912389663/package.json /tmp/2912389663/server.js] [odo] I0915 16:24:30.524563 8230 copy.go:127] Got abs path: /tmp/2912389663/.gitignore [odo] I0915 16:24:30.524579 8230 copy.go:128] Making /tmp/2912389663 relative to /tmp/2912389663/.gitignore [odo] I0915 16:24:30.524588 8230 copy.go:144] makeTar srcFile: 2912389663/.gitignore [odo] I0915 16:24:30.524594 8230 copy.go:145] makeTar destFile: .gitignore [odo] I0915 16:24:30.524601 8230 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/.gitignore, destBase: /projects, destFile: .gitignore [odo] I0915 16:24:30.524612 8230 copy.go:171] Corrected destinations: base: /projects file: .gitignore [odo] I0915 16:24:30.637913 8230 copy.go:127] Got abs path: /tmp/2912389663/Dockerfile [odo] I0915 16:24:30.637954 8230 copy.go:128] Making /tmp/2912389663 relative to /tmp/2912389663/Dockerfile [odo] I0915 16:24:30.638022 8230 copy.go:144] makeTar srcFile: 2912389663/Dockerfile [odo] I0915 16:24:30.638040 8230 copy.go:145] makeTar destFile: Dockerfile [odo] I0915 16:24:30.638147 8230 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/Dockerfile, destBase: /projects, destFile: Dockerfile [odo] I0915 16:24:30.638173 8230 copy.go:171] Corrected destinations: base: /projects file: Dockerfile [odo] I0915 16:24:30.639073 8230 copy.go:127] Got abs path: /tmp/2912389663/devfile.yaml [odo] I0915 16:24:30.639086 8230 copy.go:128] Making /tmp/2912389663 relative to /tmp/2912389663/devfile.yaml [odo] I0915 16:24:30.639094 8230 copy.go:144] makeTar srcFile: 2912389663/devfile.yaml [odo] I0915 16:24:30.639099 8230 copy.go:145] makeTar destFile: devfile.yaml [odo] I0915 16:24:30.639103 8230 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I0915 16:24:30.639107 8230 copy.go:171] Corrected destinations: base: /projects file: devfile.yaml [odo] I0915 16:24:30.639611 8230 copy.go:127] Got abs path: /tmp/2912389663/package.json [odo] I0915 16:24:30.639620 8230 copy.go:128] Making /tmp/2912389663 relative to /tmp/2912389663/package.json [odo] I0915 16:24:30.639626 8230 copy.go:144] makeTar srcFile: 2912389663/package.json [odo] I0915 16:24:30.639629 8230 copy.go:145] makeTar destFile: package.json [odo] I0915 16:24:30.639632 8230 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/package.json, destBase: /projects, destFile: package.json [odo] I0915 16:24:30.639640 8230 copy.go:171] Corrected destinations: base: /projects file: package.json [odo] I0915 16:24:30.640113 8230 copy.go:127] Got abs path: /tmp/2912389663/server.js [odo] I0915 16:24:30.640120 8230 copy.go:128] Making /tmp/2912389663 relative to /tmp/2912389663/server.js [odo] I0915 16:24:30.640125 8230 copy.go:144] makeTar srcFile: 2912389663/server.js [odo] I0915 16:24:30.640129 8230 copy.go:145] makeTar destFile: server.js [odo] I0915 16:24:30.640132 8230 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 2912389663/server.js, destBase: /projects, destFile: server.js [odo] I0915 16:24:30.640135 8230 copy.go:171] Corrected destinations: base: /projects file: server.js [odo] ✓ Syncing files into the container [457ms] [odo] • Building your application in container (command: install) ... [odo] I0915 16:24:30.728098 8230 innerloop.go:131] running=false, execRequired=true [odo] I0915 16:24:30.728227 8230 exec.go:37] Executing command [/bin/sh -c cd $PROJECT_SOURCE && (npm install) 1>>/proc/1/fd/1 2>>/proc/1/fd/2] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] runtime: npm notice created a lockfile as package-lock.json. You should commit this file. [odo] runtime: npm WARN optional SKIPPING OPTIONAL DEPENDENCY: fsevents@~2.3.2 (node_modules/chokidar/node_modules/fsevents): [odo] runtime: npm WARN notsup SKIPPING OPTIONAL DEPENDENCY: Unsupported platform for fsevents@2.3.3: wanted {"os":"darwin","arch":"any"} (current: {"os":"linux","arch":"x64"}) [odo] runtime: npm WARN node-echo@0.0.1 No repository field. [odo] runtime: npm WARN node-echo@0.0.1 No license field. [odo] runtime: [odo] runtime: added 33 packages from 29 contributors and audited 34 packages in 1.481s [odo] runtime: [odo] runtime: 3 packages are looking for funding [odo] runtime: run `npm fund` for details [odo] runtime: [odo] runtime: found 0 vulnerabilities [odo] runtime: [odo] ✓ Building your application in container (command: install) [2s] [odo] I0915 16:24:33.041701 8230 apply_kubernetes.go:46] Injecting labels: map[app:app app.kubernetes.io/instance:lwvxbb app.kubernetes.io/managed-by:odo app.kubernetes.io/managed-by-version:v3.14.0 app.kubernetes.io/part-of:app app.openshift.io/runtime:nodejs odo.dev/mode:Dev] into k8s artifact [odo] [odo] ↪ Deploying Kubernetes Component: myapp-inner [odo] I0915 16:24:33.042557 8230 kclient.go:237] Checking if "servicebindings" resource is supported [odo] • Creating resource Pod/myapp-inner ... [odo] ✓ Creating resource Pod/myapp-inner [odo] I0915 16:24:33.207087 8230 execute_run.go:50] restart required for command start [odo] I0915 16:24:33.207108 8230 kubeexec.go:147] StopProcessForCommand for "start" [odo] I0915 16:24:33.207124 8230 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_start.pid || true] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:33.298007 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:33.298481 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:33.406006 8230 exec.go:96] cat: /opt/odo/.odo_cmd_start.pid: No such file or directory [odo] I0915 16:24:33.408798 8230 kubeexec.go:54] StartProcessForCommand for "start" [odo] • Executing the application (command: start) ... [odo] I0915 16:24:33.409016 8230 retry.go:48] waiting for 5 second(s) before trying task "process for command \"start\"" [odo] I0915 16:24:33.409047 8230 exec.go:37] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_start.pid && cd $PROJECT_SOURCE && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_start.pid] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:33.584285 8230 all.go:67] all goroutines have returned in 286.273669ms [odo] I0915 16:24:33.584309 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:33.671421 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:33.671596 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:33.733456 8230 all.go:67] all goroutines have returned in 62.034296ms [odo] I0915 16:24:33.733491 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:33.800040 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:33.800140 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:33.849668 8230 all.go:67] all goroutines have returned in 49.627481ms [odo] I0915 16:24:33.849693 8230 all.go:76] query result: objects=7 [odo] runtime: [odo] runtime: > node-echo@0.0.1 start /projects [odo] runtime: > node server.js [odo] runtime: [odo] runtime: Server running on 0.0.0.0:8080 [odo] I0915 16:24:34.481859 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:34.481955 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:34.545249 8230 all.go:67] all goroutines have returned in 63.391126ms [odo] I0915 16:24:34.545269 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:34.613591 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:34.613686 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:34.709125 8230 all.go:67] all goroutines have returned in 95.524288ms [odo] I0915 16:24:34.709176 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:35.208024 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:35.208173 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:35.310196 8230 all.go:67] all goroutines have returned in 102.172236ms [odo] I0915 16:24:35.310231 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:36.183692 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:36.184761 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:36.284198 8230 all.go:67] all goroutines have returned in 100.573009ms [odo] I0915 16:24:36.284243 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:38.410289 8230 execute_run.go:90] checking if process for command "start" is running [odo] I0915 16:24:38.410417 8230 kubeexec.go:40] GetProcessInfoForCommand for "start" [odo] I0915 16:24:38.410524 8230 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_start.pid || true] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:38.622472 8230 exec.go:96] 44 [odo] I0915 16:24:38.631115 8230 exec.go:37] Executing command [/bin/sh -c kill -0 44; echo $?] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:38.870961 8230 exec.go:96] 0 [odo] I0915 16:24:38.871054 8230 kubeexec.go:40] GetProcessInfoForCommand for "start" [odo] I0915 16:24:38.871072 8230 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_start.pid || true] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:39.117597 8230 exec.go:96] 44 [odo] I0915 16:24:39.121298 8230 exec.go:37] Executing command [/bin/sh -c kill -0 44; echo $?] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:39.320351 8230 exec.go:96] 0 [odo] • Waiting for the application to be ready ... [odo] I0915 16:24:40.327128 8230 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:40.554269 8230 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:24:40.554303 8230 exec.go:96] 0: B02B11AC:D4D4 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554307 8230 exec.go:96] 1: B02B11AC:D4CC 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554312 8230 exec.go:96] 2: B02B11AC:D4AE 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554317 8230 exec.go:96] 3: B02B11AC:D498 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554322 8230 exec.go:96] 4: B02B11AC:D494 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554325 8230 exec.go:96] 5: B02B11AC:D4C8 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554327 8230 exec.go:96] 6: B02B11AC:D4B8 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554330 8230 exec.go:96] 7: B02B11AC:D4BA 221E1068:01BB 06 00000000:00000000 03:0000147B 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554333 8230 exec.go:96] 8: B02B11AC:D4A8 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554335 8230 exec.go:96] 9: B02B11AC:D4DA 221E1068:01BB 06 00000000:00000000 03:0000147A 00000000 0 0 0 3 0000000000000000 [odo] I0915 16:24:40.554340 8230 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0915 16:24:40.554344 8230 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0915 16:24:40.554348 8230 exec.go:96] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1012710000 0 774247877 1 0000000000000000 100 0 0 10 0 [odo] I0915 16:24:40.554352 8230 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] ✓ Waiting for the application to be ready [1s] [odo] I0915 16:24:40.558508 8230 port.go:346] ports not listening: map[] [odo] - Forwarding from 127.0.0.1:34059 -> 8080 [odo] [odo] I0915 16:24:40.669278 8230 status.go:34] setting inner loop State "Ready" [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/2912389663 [odo] [odo] Keyboard Commands: [odo] [Ctrl+c] - Exit and delete resources from the cluster [odo] [p] - Manually apply local changes to the application on the cluster [odo] I0915 16:24:40.669329 8230 watch.go:271] deployment watcher Event: Type: MODIFIED, name: lwvxbb-app, rv: 69910001, generation: 1, pods: 1 [odo] I0915 16:24:40.858035 8230 watch.go:348] filesystem watch event: CREATE "/tmp/2912389663/.odo/odo-file-index.json" [odo] I0915 16:24:40.858534 8230 watch.go:348] filesystem watch event: WRITE "/tmp/2912389663/.odo/odo-file-index.json" [odo] I0915 16:24:40.858766 8230 watch.go:348] filesystem watch event: WRITE "/tmp/2912389663/.odo/devstate.json" [odo] I0915 16:24:40.859109 8230 watch.go:348] filesystem watch event: WRITE "/tmp/2912389663/.odo/devstate.8230.json" Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Pending"Running oc with args [oc get pods -n cmd-logs-test249kbi -o jsonpath="{.items[*].status.phase}"] and odo env: [] [oc] "Running Running" [FAILED] in [It] - /go/odo_1/tests/integration/cmd_logs_test.go:253 @ 09/15/23 16:24:50.329 [odo] read: read /dev/stdin: input/output error [odo] I0915 16:24:50.330488 8230 watch.go:333] Dev mode interrupted by user [odo] I0915 16:24:50.332090 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:50.332243 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:50.338171 8230 implem.go:114] The path for preference file is /tmp/3932679108/preference.yaml [odo] I0915 16:24:50.338300 8230 exec.go:54] ExecuteCommand returned an an err: error while streaming command: context canceled. for command '[/bin/sh -c echo $$ > /opt/odo/.odo_cmd_start.pid && cd $PROJECT_SOURCE && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_start.pid]' [odo] stdout: [] [odo] stderr: [] [odo] I0915 16:24:50.338334 8230 kubeexec.go:118] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_start.pid && cd $PROJECT_SOURCE && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_start.pid]: error while streaming command: context canceled [odo] I0915 16:24:50.338351 8230 kubeexec.go:40] GetProcessInfoForCommand for "start" [odo] I0915 16:24:50.338379 8230 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_start.pid || true] for pod: lwvxbb-app-845986b854-nl689 in container: runtime [odo] I0915 16:24:50.338795 8230 segment.go:268] Checking telemetry enable status [odo] I0915 16:24:50.338807 8230 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0915 16:24:50.339078 8230 exec.go:54] ExecuteCommand returned an an err: error while streaming command: error sending request: Post "https://c100-e.eu-de.containers.cloud.ibm.com:31952/api/v1/namespaces/cmd-logs-test249kbi/pods/lwvxbb-app-845986b854-nl689/exec?command=%2Fbin%2Fsh&command=-c&command=cat+%2Fopt%2Fodo%2F.odo_cmd_start.pid+%7C%7C+true&container=runtime&stderr=true&stdout=true": dial tcp: lookup c100-e.eu-de.containers.cloud.ibm.com: operation was canceled. for command '[/bin/sh -c cat /opt/odo/.odo_cmd_start.pid || true]' [odo] stdout: [] [odo] stderr: [] [odo] ✗ Finished executing the application (command: start) [17s] [odo] I0915 16:24:50.339160 8230 execute_run.go:36] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_start.pid && cd $PROJECT_SOURCE && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_start.pid]: error while streaming command: context canceled [odo] I0915 16:24:50.412858 8230 all.go:67] all goroutines have returned in 80.729009ms [odo] I0915 16:24:50.412920 8230 all.go:76] query result: objects=7 [odo] I0915 16:24:50.419777 8230 delete.go:176] Ignoring resource: deployments/devfile-nodejs-deploy; it does not exist on the cluster [odo] I0915 16:24:50.431391 8230 delete.go:176] Ignoring resource: deployments/devfile-nodejs-deploy-2; it does not exist on the cluster [odo] I0915 16:24:50.507669 8230 delete.go:176] Ignoring resource: pods/myapp-outer; it does not exist on the cluster [odo] I0915 16:24:50.551699 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:24:50.551914 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:24:51.427605 8230 all.go:67] all goroutines have returned in 875.243843ms [odo] I0915 16:24:51.428212 8230 all.go:76] query result: objects=626 [odo] I0915 16:25:24.020541 8230 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:25:24.021504 8230 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:25:24.754517 8230 all.go:67] all goroutines have returned in 733.992509ms [odo] I0915 16:25:24.754548 8230 all.go:76] query result: objects=624 Running oc with args [oc get project cmd-logs-test249kbi -o jsonpath={.metadata.name}] and odo env: [] [oc] cmd-logs-test249kbiDeleting project: cmd-logs-test249kbi Running oc with args [oc delete project cmd-logs-test249kbi --wait=false] and odo env: [] [oc] project.project.openshift.io "cmd-logs-test249kbi" deleted Setting current dir to: /go/odo_1/tests/integration Deleting dir: /tmp/2912389663 Deleting dir: /tmp/3932679108 << Timeline [FAILED] Expected : __ / \__ Developing using the "lwvxbb" Devfile \__/ \ Namespace: cmd-logs-test249kbi / \__/ odo version: v3.14.0 (b0412b0ee) \__/ ↪ Running on the cluster in Dev mode • Waiting for Kubernetes resources ... ⚠ Pod is Pending ✓ Pod is Running --> Logs for lwvxbb-app-845986b854-nl689 / runtime • Syncing files into the container ... ✓ Syncing files into the container [457ms] • Building your application in container (command: install) ... runtime: npm notice created a lockfile as package-lock.json. You should commit this file. runtime: npm WARN optional SKIPPING OPTIONAL DEPENDENCY: fsevents@~2.3.2 (node_modules/chokidar/node_modules/fsevents): runtime: npm WARN notsup SKIPPING OPTIONAL DEPENDENCY: Unsupported platform for fsevents@2.3.3: wanted {"os":"darwin","arch":"any"} (current: {"os":"linux","arch":"x64"}) runtime: npm WARN node-echo@0.0.1 No repository field. runtime: npm WARN node-echo@0.0.1 No license field. runtime: runtime: added 33 packages from 29 contributors and audited 34 packages in 1.481s runtime: runtime: 3 packages are looking for funding runtime: run `npm fund` for details runtime: runtime: found 0 vulnerabilities runtime: ✓ Building your application in container (command: install) [2s] ↪ Deploying Kubernetes Component: myapp-inner • Creating resource Pod/myapp-inner ... ✓ Creating resource Pod/myapp-inner • Executing the application (command: start) ... runtime: runtime: > node-echo@0.0.1 start /projects runtime: > node server.js runtime: runtime: Server running on 0.0.0.0:8080 • Waiting for the application to be ready ... ✓ Waiting for the application to be ready [1s] - Forwarding from 127.0.0.1:34059 -> 8080 ↪ Dev mode Status: Watching for changes in the current directory /tmp/2912389663 Keyboard Commands: [Ctrl+c] - Exit and delete resources from the cluster [p] - Manually apply local changes to the application on the cluster to contain substring : main: In [It] at: /go/odo_1/tests/integration/cmd_logs_test.go:253 @ 09/15/23 16:24:50.329 ------------------------------ •SSSS•SSS•SS•SSS•SSSS••S••SSSSS••S••••S•S•S••••S•S• ------------------------------ • [7.004 seconds] odo deploy interactive command tests directory is not empty when there is a match from Alizer should run alizer to download devfile successfully even with -v flag /go/odo_1/tests/integration/interactive_deploy_test.go:40 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo deploy -v 4' from /tmp/3900675912 << Captured StdOut/StdErr Output ------------------------------ S•SSS•SSS•SSSS•SSSSS••SSSSSSSSSS••SS••S ------------------------------ • [5.630 seconds] odo deploy interactive command tests directory is not empty when there is a match from Alizer should display welcoming messages first /go/odo_1/tests/integration/interactive_deploy_test.go:109 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo deploy' from /tmp/1123327852 << Captured StdOut/StdErr Output ------------------------------ S•••S•••SSSS••SS•S•S•S••SSS•SS••SSSS••S••SSS•••SSS••SS•SSSS•SS••SSS••SS ------------------------------ Progress Report for Ginkgo Process #3 Automatically polling progress: odo dev debug command tests when a composite apply command is used as debug command should execute the composite apply commands successfully (Spec Runtime: 3m43.964s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:288 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:288 At [By Step] cleaning up the resources on ending the session (Step Runtime: 27.026s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:326 Begin Captured GinkgoWriter Output >> ... [odo] stderr: [] [odo] I0915 16:29:19.586905 10684 execute_run.go:36] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_start-debug.pid && cd /projects && (npm run debug) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_start-debug.pid]: error while streaming command: context canceled [odo] I0915 16:29:19.590192 10684 implem.go:114] The path for preference file is /tmp/2221929932/preference.yaml [odo] I0915 16:29:19.591093 10684 segment.go:268] Checking telemetry enable status [odo] I0915 16:29:19.591109 10684 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0915 16:29:19.673425 10684 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:29:19.676590 10684 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:29:21.907843 10684 all.go:67] all goroutines have returned in 2.234262223s [odo] I0915 16:29:21.908041 10684 all.go:76] query result: objects=648 << End Captured GinkgoWriter Output Spec Goroutine goroutine 1028 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000582000, {0x30f3d88?, 0xc001372630}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000582000, {0x30f3d88, 0xc001372630}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc00034b800?, {0xc0008063a0?, 0x0?, 0x0?}) /go/odo_1/vendor/github.com/onsi/gomega/gexec/session.go:144 github.com/redhat-developer/odo/tests/helper.DevSession.WaitEnd(...) /go/odo_1/tests/helper/helper_dev.go:255 > github.com/redhat-developer/odo/tests/integration.glob..func6.7.2.8() /go/odo_1/tests/integration/cmd_dev_debug_test.go:328 | By("cleaning up the resources on ending the session", func() { | devSession.Stop() > devSession.WaitEnd() | out := commonVar.CliRunner.Run("get", "deployments").Out.Contents() | helper.DontMatchAllInOutput(string(out), deploymentNames) github.com/onsi/ginkgo/v2/internal.(*Suite).By(0xc0001b2000, {0x2d49271, 0x2f}, {0xc000ed9e70, 0x1, 0x0?}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:309 github.com/onsi/ginkgo/v2.By({0x2d49271?, 0x0?}, {0xc000ed9e70?, 0x0?, 0x0?}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/core_dsl.go:547 > github.com/redhat-developer/odo/tests/integration.glob..func6.7.2() /go/odo_1/tests/integration/cmd_dev_debug_test.go:326 | }) | > By("cleaning up the resources on ending the session", func() { | devSession.Stop() | devSession.WaitEnd() github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ • [82.697 seconds] odo dev interactive command tests directory is not empty when there is a match from Alizer should display welcoming messages first /go/odo_1/tests/integration/interactive_dev_test.go:112 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo dev --random-ports' from /tmp/2424373915 << Captured StdOut/StdErr Output ------------------------------ •SS ------------------------------ Progress Report for Ginkgo Process #13 Automatically polling progress: odo dev command tests when doing odo dev and there is a env variable with spaces - with metadata.name should be able to exec command (Spec Runtime: 2m3.413s) /go/odo_1/tests/integration/cmd_dev_test.go:1713 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:1713 Begin Captured GinkgoWriter Output >> ... [odo] read: read /dev/stdin: input/output error [odo] I0915 16:29:27.082372 14888 watch.go:333] Dev mode interrupted by user [odo] I0915 16:29:27.098213 14888 implem.go:114] The path for preference file is /tmp/2571972721/preference.yaml [odo] I0915 16:29:27.099261 14888 segment.go:268] Checking telemetry enable status [odo] I0915 16:29:27.099273 14888 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0915 16:29:27.220113 14888 all.go:46] starting to concurrently query 199 APIs [odo] I0915 16:29:27.222849 14888 all.go:62] fired up all goroutines to query APIs [odo] I0915 16:29:28.058478 14888 all.go:67] all goroutines have returned in 838.346493ms [odo] I0915 16:29:28.058549 14888 all.go:76] query result: objects=606 << End Captured GinkgoWriter Output Spec Goroutine goroutine 3469 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00041c310, {0x30f3d88?, 0xc001127950}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00041c310, {0x30f3d88, 0xc001127950}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc00080bd90?, {0xc001319fc0?, 0xc00080bc68?, 0x2?}) /go/odo_1/vendor/github.com/onsi/gomega/gexec/session.go:144 github.com/redhat-developer/odo/tests/helper.DevSession.WaitEnd(...) /go/odo_1/tests/helper/helper_dev.go:255 github.com/redhat-developer/odo/tests/helper.RunDevMode.func1() /go/odo_1/tests/helper/helper_dev.go:326 github.com/redhat-developer/odo/tests/helper.RunDevMode({{0x0, 0x0, 0x0}, {0x0, 0x0, 0x0}, 0x0, 0x0, 0x0, 0x0, ...}, ...) /go/odo_1/tests/helper/helper_dev.go:329 > github.com/redhat-developer/odo/tests/integration.glob..func7.13.2() /go/odo_1/tests/integration/cmd_dev_test.go:1714 | | It("should be able to exec command", func() { > err := helper.RunDevMode(helper.DevSessionOpts{ | RunOnPodman: podman, | }, func(session *gexec.Session, out, err string, ports map[string]string) { github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x0, 0xc000fc04e0}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/node.go:463 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode.func3() /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:865 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:852 ------------------------------ ••SS•SSS•S•S•SSS••••S••S•S•SSSS••SS•S••••••••••••• ------------------------------ • [80.466 seconds] odo dev interactive command tests directory is not empty when there is a match from Alizer should run alizer to download devfile successfully even with -v flag /go/odo_1/tests/integration/interactive_dev_test.go:40 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo dev --random-ports -v 4' from /tmp/3768188287 << Captured StdOut/StdErr Output ------------------------------ • Summarizing 1 Failure: [FAIL] odo logs command tests when component is created and odo logs is executed when running in Dev mode with --logs [It] 1. should successfully show logs of the running component /go/odo_1/tests/integration/cmd_logs_test.go:253 Ran 404 of 954 Specs in 1409.505 seconds FAIL! -- 403 Passed | 1 Failed | 0 Pending | 550 Skipped Ginkgo ran 1 suite in 24m36.769135075s Test Suite Failed exit status 1 make: *** [Makefile:214: test-integration-cluster] Error 1