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=0e4419ceb" ./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: 1697125561 - will randomize all specs Will run 504 of 956 specs Running in parallel across 16 processes SSSSSSSSSSSSSSSSSSSSSSSSSSS•SSSSSS•S•••SSSSSS•S•S•SS••SS••S•SS••SS••SS•S•••S••SS•SSS••SSS•SS•SSS•SS• ------------------------------ Progress Report for Ginkgo Process #5 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: 2m5.017s) /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:176 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:169 Begin Captured GinkgoWriter Output >> ... [odo] I1012 15:48:58.353617 13569 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-devfile-deploy-test176pzt/pods/nlwlkf-app-769d7f5bcc-kfrxs/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] I1012 15:48:58.353779 13569 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] I1012 15:48:58.440803 13569 delete.go:174] Ignoring resource: deployments/my-component; its mode(Deploy) does not match with the given mode(Dev) [odo] I1012 15:48:58.484615 13569 all.go:46] starting to concurrently query 200 APIs [odo] I1012 15:48:58.486590 13569 all.go:62] fired up all goroutines to query APIs [odo] I1012 15:48:59.326461 13569 all.go:67] all goroutines have returned in 841.858351ms [odo] I1012 15:48:59.326525 13569 all.go:76] query result: objects=630 << End Captured GinkgoWriter Output Spec Goroutine goroutine 104 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0004f8cb0, {0x30fdae8?, 0xc0007c1860}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0004f8cb0, {0x30fdae8, 0xc0007c1860}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc000f9fea8?, {0xc0009e0660?, 0x43914a8?, 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:263 > 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({0x1088f3e, 0xc000394cc0}) /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•SSSS•S•S••SSS•SS••••••S•S•SSSSSS•SS••S•SS•S•SS••S••S•SSS••S•••S•S••S•SS•SS ------------------------------ Progress Report for Ginkgo Process #9 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: 2m3.236s) /go/odo_1/tests/integration/cmd_dev_test.go:1077 In [BeforeEach] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:1050 Begin Captured GinkgoWriter Output >> ... [odo] I1012 15:51:14.089402 15951 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 15:51:14.100704 15951 port.go:319] port 8080 not listening in container "runtime" [odo] I1012 15:51:17.794298 15951 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: gbbaqv-app-fd75565b7-k6bgn in container: runtime [odo] I1012 15:51:18.154547 15951 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 15:51:18.154596 15951 exec.go:96] 0: A62B11AC:ACAA 22191068:01BB 06 00000000:00000000 03:00000E3D 00000000 0 0 0 3 0000000000000000 [odo] I1012 15:51:18.154604 15951 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 15:51:18.154614 15951 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 15:51:18.154618 15951 exec.go:96] 0: 00000000000000000000000000000000:0BB8 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1001190000 0 958468055 1 0000000000000000 100 0 0 10 0 [odo] I1012 15:51:18.154739 15951 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 15:51:18.160114 15951 port.go:319] port 8080 not listening in container "runtime" << End Captured GinkgoWriter Output Spec Goroutine goroutine 149 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0005c9ce0, {0x30fdd28?, 0xc000ea0510}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0005c9ce0, {0x30fdd28, 0xc000ea0510}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cf2614, 0x8}, 0xc000418900?, 0xc0004188e0?, 0xc000b0f1d0) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.(*DevSession).WaitSync(0xc0004a73e0) /go/odo_1/tests/helper/helper_dev.go:279 > github.com/redhat-developer/odo/tests/integration.glob..func7.5.1.1() /go/odo_1/tests/integration/cmd_dev_test.go:1069 | 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({0x1088f3e, 0xc00010f440}) /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 ------------------------------ •••••SSSSSSS••S•SSS•••SSS•S•SS••S•SSSS•S••SS••SSSSS••SSSSS•SSSSS•SSSSSSS•S•••SSS•S•SS••SS••SSS•SS•S•SSS•SS•• ------------------------------ • [4.667 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/3048568465 << Captured StdOut/StdErr Output ------------------------------ SS••SSSSS••S•SS•SS••S••S•SSS••••SSS•••••S•SSS••••SSSS••SSS•SS•S•SS•S•••S•SS•SS••••••••S•••SS••SSSSS•SS•••SSSSS••SSS•SSSSSS••SS•SSS ------------------------------ Progress Report for Ginkgo Process #15 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.517s) /go/odo_1/tests/integration/cmd_dev_test.go:4146 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:4146 Begin Captured GinkgoWriter Output >> ... [odo] stderr: [] [odo] I1012 15:56:26.090820 26058 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] I1012 15:56:26.094779 26058 implem.go:114] The path for preference file is /tmp/3410489055/preference.yaml [odo] Cleaning resources, please wait [odo] I1012 15:56:26.095358 26058 segment.go:268] Checking telemetry enable status [odo] I1012 15:56:26.095369 26058 segment.go:286] Sending telemetry disabled by env variable [odo] I1012 15:56:26.158173 26058 all.go:46] starting to concurrently query 200 APIs [odo] I1012 15:56:26.158351 26058 all.go:62] fired up all goroutines to query APIs [odo] I1012 15:56:27.108301 26058 all.go:67] all goroutines have returned in 949.800453ms [odo] I1012 15:56:27.108377 26058 all.go:76] query result: objects=614 << End Captured GinkgoWriter Output Spec Goroutine goroutine 602 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0002ca0e0, {0x30fdae8?, 0xc000aee408}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0002ca0e0, {0x30fdae8, 0xc000aee408}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc000c7fd50?, {0xc0008cd060?, 0x2cf43e9?, 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:263 github.com/redhat-developer/odo/tests/helper.RunDevMode.func1() /go/odo_1/tests/helper/helper_dev.go:334 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:337 > github.com/redhat-developer/odo/tests/integration.glob..func7.64.2() /go/odo_1/tests/integration/cmd_dev_test.go:4147 | }) | 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({0x269b573, 0xc0008b2a80}) /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•SSS••S•S•SSSS•S•S ------------------------------ 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: 3m34.147s) /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: 26.334s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:326 Begin Captured GinkgoWriter Output >> ... [odo] I1012 15:57:02.790618 25108 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] ✗ Finished executing the application (command: start-debug) [1m] [odo] I1012 15:57:02.794688 25108 implem.go:114] The path for preference file is /tmp/2798148116/preference.yaml [odo] I1012 15:57:02.795320 25108 segment.go:268] Checking telemetry enable status [odo] I1012 15:57:02.795335 25108 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I1012 15:57:02.903826 25108 all.go:46] starting to concurrently query 200 APIs [odo] I1012 15:57:02.906934 25108 all.go:62] fired up all goroutines to query APIs [odo] I1012 15:57:03.798815 25108 all.go:67] all goroutines have returned in 895.000962ms [odo] I1012 15:57:03.798903 25108 all.go:76] query result: objects=651 << End Captured GinkgoWriter Output Spec Goroutine goroutine 719 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0002c0000, {0x30fdae8?, 0xc0010d25d0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0002c0000, {0x30fdae8, 0xc0010d25d0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc0000d4fc0?, {0xc0014ca060?, 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:263 > 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(0xc0001a8000, {0x2d5276b, 0x2f}, {0xc000ddde70, 0x1, 0x0?}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:309 github.com/onsi/ginkgo/v2.By({0x2d5276b?, 0xf7289d?}, {0xc000ddde70?, 0xdfaea0?, 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({0x1088f3e, 0xc00122aea0}) /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 ------------------------------ •SSSSSSSSSSS•SSSSS••SSSS••SS••S••S•SS•S•SS•SS•S•S••••SSSSSS••S•S••SS ------------------------------ • [5.318 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/1553247894 << Captured StdOut/StdErr Output ------------------------------ •••SS•SS•••S•SSSS ------------------------------ • [8.109 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/3289176024 << Captured StdOut/StdErr Output ------------------------------ • [57.247 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 ------------------------------ S•SSSS••SS•SSSSSSSS••SSSS•S•SSS•SSS•SSS•SS•SSSSSS•••S•SS•SSSSS•SSSSSS•SSSSS•S ------------------------------ • [9.801 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/2163131889 << Captured StdOut/StdErr Output ------------------------------ •S• ------------------------------ • [81.546 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:3622 Captured StdOut/StdErr Output >> PID: 1716 << Captured StdOut/StdErr Output ------------------------------ SS•••• ------------------------------ • [119.664 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/3022731550 << Captured StdOut/StdErr Output ------------------------------ S•SSSS ------------------------------ Progress Report for Ginkgo Process #15 Automatically polling progress: odo dev command tests when running odo dev and single env var is set - without metadata.name should be able to exec command (Spec Runtime: 2m2.786s) /go/odo_1/tests/integration/cmd_dev_test.go:1691 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:1691 Begin Captured GinkgoWriter Output >> ... [odo] read: read /dev/stdin: input/output error [odo] I1012 16:00:23.568434 1531 watch.go:333] Dev mode interrupted by user [odo] I1012 16:00:23.575235 1531 implem.go:114] The path for preference file is /tmp/1909927060/preference.yaml [odo] I1012 16:00:23.577183 1531 segment.go:268] Checking telemetry enable status [odo] I1012 16:00:23.577203 1531 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I1012 16:00:23.815757 1531 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:00:23.824054 1531 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:00:25.246924 1531 all.go:67] all goroutines have returned in 1.430639574s [odo] I1012 16:00:25.247016 1531 all.go:76] query result: objects=609 << End Captured GinkgoWriter Output Spec Goroutine goroutine 758 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000666150, {0x30fdae8?, 0xc000eead08}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000666150, {0x30fdae8, 0xc000eead08}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc000c1dd70?, {0xc0017bd8e0?, 0xc000c1dc38?, 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:263 github.com/redhat-developer/odo/tests/helper.RunDevMode.func1() /go/odo_1/tests/helper/helper_dev.go:334 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:337 > github.com/redhat-developer/odo/tests/integration.glob..func7.11.2() /go/odo_1/tests/integration/cmd_dev_test.go:1692 | | 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, 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 ------------------------------ S•••••S••SS•SS•SSS••S••SSSSSSSS•SSS•••SSS•••SSSSS•SSSSSSS ------------------------------ • [102.418 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/616886368 << Captured StdOut/StdErr Output ------------------------------ •SSS•S•SS•S•••S••SS••SS•S•SSSSS••••SS•S•S••SS•••SS• ------------------------------ • [86.302 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/1623418148 << Captured StdOut/StdErr Output ------------------------------ •••S•••S•S•S•••••S•S•S•S•••SSS•SSSS••••SS ------------------------------ • [21.629 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/1220674451 << Captured StdOut/StdErr Output ------------------------------ •SSSSSS•S•S•••S•S•SSS••S•S•S•SS•••SSS••S ------------------------------ Progress Report for Ginkgo Process #16 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 when running and stopping odo dev should not delete the resources created with odo deploy (Spec Runtime: 2m3.684s) /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] I1012 16:05:54.769283 10290 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] I1012 16:05:54.782723 10290 implem.go:114] The path for preference file is /tmp/2713899906/preference.yaml [odo] Cleaning resources, please wait [odo] I1012 16:05:54.783412 10290 segment.go:268] Checking telemetry enable status [odo] I1012 16:05:54.783423 10290 segment.go:286] Sending telemetry disabled by env variable [odo] I1012 16:05:54.951967 10290 delete.go:174] Ignoring resource: deployments/my-component; its mode(Deploy) does not match with the given mode(Dev) [odo] I1012 16:05:54.977552 10290 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:05:54.977722 10290 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:05:56.885252 10290 all.go:67] all goroutines have returned in 1.90767835s [odo] I1012 16:05:56.885328 10290 all.go:76] query result: objects=630 << End Captured GinkgoWriter Output Spec Goroutine goroutine 934 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000296e00, {0x30fdae8?, 0xc00078f650}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000296e00, {0x30fdae8, 0xc00078f650}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc00100bea8?, {0xc0008ab5c0?, 0x43914a8?, 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:263 > 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({0x1088f3e, 0xc0006ba180}) /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 ------------------------------ •SSSSSSS•••S•S••S••S••SS•S••S•SSSS•S•••SSS•SS••S•S•SS•S•SSS•S•• ------------------------------ • [7.630 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/3438231665 << Captured StdOut/StdErr Output ------------------------------ ••S••S•SSSS••SS•S•S•SSSSS•SSSSSS•SS•S•SS•••SS•S•S••S••SSS•••S••••••••••••••••• Ran 406 of 956 Specs in 1456.647 seconds SUCCESS! -- 406 Passed | 0 Failed | 0 Pending | 550 Skipped Ginkgo ran 1 suite in 25m24.931963844s Test Suite Passed 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-e2e.xml" tests/e2escenarios Running Suite: odo e2e scenarios - /go/odo_1/tests/e2escenarios =============================================================== Random Seed: 1697127094 - will randomize all specs Will run 4 of 4 specs Running in parallel across 16 processes ------------------------------ • [FAILED] [84.562 seconds] E2E Test starting with non-empty Directory add Binding [It] should verify developer workflow of using binding as env in innerloop /go/odo_1/tests/e2escenarios/e2e_test.go:461 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo init' from /tmp/448009919 << Captured StdOut/StdErr Output Timeline >> Created dir: /tmp/448009919 Created dir: /tmp/3637726993 Setting KUBECONFIG=/tmp/3637726993/config Running oc with args [oc get project e2e-test461fou -o jsonpath={.metadata.name}] and odo env: [] [oc] Error from server (NotFound): namespaces "e2e-test461fou" not found Creating a new project: e2e-test461fou Running oc with args [oc new-project e2e-test461fou] and odo env: [] [oc] Now using project "e2e-test461fou" 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] default Active [oc] e2e-test199yon Active [oc] e2e-test329jty Active [oc] e2e-test461fou Active [oc] e2e-test63zvm 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 e2e-test461fou] and odo env: [] [oc] configmap/config-map-for-cleanup created Current working dir: /go/odo_1/tests/e2escenarios Running odo with args [odo preference remove registry DefaultDevfileRegistry -f] and odo env: [] [odo] I1012 16:11:55.439829 20679 implem.go:114] The path for preference file is /tmp/3637726993/preference.yaml [odo] Successfully removed registry [odo] I1012 16:11:55.441848 20679 implem.go:114] The path for preference file is /tmp/3637726993/preference.yaml [odo] I1012 16:11:55.442391 20679 segment.go:268] Checking telemetry enable status [odo] I1012 16:11:55.442401 20679 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] I1012 16:11:55.672915 20699 implem.go:114] The path for preference file is /tmp/3637726993/preference.yaml [odo] New registry successfully added [odo] I1012 16:11:55.674500 20699 implem.go:114] The path for preference file is /tmp/3637726993/preference.yaml [odo] I1012 16:11:55.674889 20699 segment.go:268] Checking telemetry enable status [odo] I1012 16:11:55.674922 20699 segment.go:286] Sending telemetry disabled by env variable Running oc with args [oc get csv -o jsonpath={.items[?(@.status.phase=="Succeeded")].metadata.name}] and odo env: [] [oc] cloud-native-postgresql.v1.18.6 service-binding-operator.v1.3.3Running oc with args [oc get csv -o jsonpath={.items[?(@.status.phase=="Succeeded")].metadata.name}] and odo env: [] [oc] cloud-native-postgresql.v1.18.6 service-binding-operator.v1.3.3Running oc with args [oc get bindablekinds bindable-kinds -ojsonpath={.status[*].kind}] and odo env: [] [oc] ServiceBinding ServiceBinding ClusterSetting current dir to: /tmp/448009919 Running oc with args [oc apply -f /go/odo_1/tests/examples/source/devfiles/go/cluster.yaml] and odo env: [] [oc] cluster.postgresql.k8s.enterprisedb.io/cluster-example-initdb created [oc] secret/appuser-secret created [oc] secret/cluster-example-initdb-appuser created running command [odo init] with env [] Running odo with args [odo dev --random-ports --api-server=false] and odo env: [ODO_LOG_LEVEL=4 ODO_TRACKING_CONSENT=no] [odo] I1012 16:12:01.076865 20850 version.go:37] executing [podman version --format json] [odo] I1012 16:12:01.077378 20850 clientset.go:243] no Podman client initialized: exec: "podman": executable file not found in $PATH [odo] I1012 16:12:01.077513 20850 implem.go:114] The path for preference file is /tmp/3637726993/preference.yaml [odo] I1012 16:12:01.078975 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:01.078994 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:01.079569 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:01.079644 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:01.079823 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:01.082617 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:01.084801 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:01.084823 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:01.084883 20850 component.go:87] name of component is "hjeusw", and sanitized name is "hjeusw" [odo] I1012 16:12:01.084917 20850 kclient.go:237] Checking if "projects" resource is supported [odo] __ [odo] / \__ Developing using the "hjeusw" Devfile [odo] \__/ \ Namespace: e2e-test461fou [odo] / \__/ odo version: v3.15.0 (0e4419ceb) [odo] \__/ [odo] [odo] ↪ Running on the cluster in Dev mode [odo] I1012 16:12:01.198133 20850 kubedev.go:79] Creating new adapter [odo] I1012 16:12:01.198176 20850 kubedev.go:87] Creating inner-loop resources for the component [odo] I1012 16:12:01.198201 20850 watch.go:105] starting WatchAndPush, path: /tmp/448009919, component: hjeusw, ignores [.git .odo .odo/odo-file-index.json] [odo] I1012 16:12:01.200489 20850 file_watcher.go:130] adding watch on path /tmp/448009919 [odo] I1012 16:12:01.200539 20850 file_watcher.go:130] adding watch on path /tmp/448009919/.odo [odo] I1012 16:12:01.200556 20850 file_watcher.go:130] adding watch on path /tmp/448009919/middleware [odo] I1012 16:12:01.200571 20850 file_watcher.go:130] adding watch on path /tmp/448009919/models [odo] I1012 16:12:01.200600 20850 file_watcher.go:130] adding watch on path /tmp/448009919/router [odo] I1012 16:12:01.206946 20850 watch.go:423] Copying files [] to pod [odo] I1012 16:12:01.207319 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:01.207342 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:01.208025 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:01.208128 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:01.208148 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:01.216752 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:01.217075 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:01.217126 20850 components.go:69] component state: "" [odo] • Waiting for Kubernetes resources ... [odo] I1012 16:12:01.229177 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:01.281004 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:01.307569 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:01.307609 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:01.309769 20850 kclient.go:286] Kubernetes version is "v1.25.11+1485cc9" [odo] I1012 16:12:01.309799 20850 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] I1012 16:12:01.369081 20850 components.go:378] Successfully created component hjeusw [odo] I1012 16:12:01.398551 20850 components.go:390] Successfully created Service for component hjeusw [odo] I1012 16:12:01.428939 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:01.429410 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:01.498007 20850 all.go:67] all goroutines have returned in 69.040263ms [odo] I1012 16:12:01.498066 20850 all.go:76] query result: objects=5 [odo] I1012 16:12:01.498165 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:01.500750 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:01.503797 20850 service.go:75] Getting list of services [odo] I1012 16:12:01.503848 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:01.533825 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:01.533964 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:01.545203 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:01.562680 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:01.573460 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:01.585968 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:01.586110 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:01.589891 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:01.591980 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:01.593938 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:01.605341 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:01.611886 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:01.629526 20850 components.go:134] Deployment has been updated to generation 1. Waiting new event... [odo] I1012 16:12:01.629677 20850 status.go:34] setting inner loop State "WaitDeployment" [odo] =================== [odo] ⚠ Pod is Pending [odo] =================== [odo] I1012 16:12:01.633260 20850 watch.go:271] deployment watcher Event: Type: ADDED, name: hjeusw-app, rv: 87600636, generation: 1, pods: 0 [odo] I1012 16:12:01.635305 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600643, generation: 1, pods: 0 [odo] I1012 16:12:01.637108 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600648, generation: 1, pods: 0 [odo] I1012 16:12:01.638488 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600659, generation: 1, pods: 0 [odo] I1012 16:12:01.934340 20850 watch.go:423] Copying files [] to pod [odo] I1012 16:12:01.935010 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:01.935043 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:01.936482 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:01.936708 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:01.936744 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:01.943136 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:01.943546 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:01.943610 20850 components.go:69] component state: "WaitDeployment" [odo] I1012 16:12:01.951711 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:01.982552 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:02.033587 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:02.033633 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:02.033649 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:12:02.033665 20850 components.go:352] Applying deployment [odo] I1012 16:12:02.060479 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:12:02.088791 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:12:02.121933 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:02.126166 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:02.182553 20850 all.go:67] all goroutines have returned in 60.611285ms [odo] I1012 16:12:02.182647 20850 all.go:76] query result: objects=6 [odo] I1012 16:12:02.182719 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:02.182759 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:02.182779 20850 service.go:75] Getting list of services [odo] I1012 16:12:02.182788 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:02.212441 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:02.212485 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:02.219146 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:02.230297 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:02.237941 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:02.245017 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:02.245047 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:02.247290 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:02.249560 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:02.251529 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:02.262104 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:02.297917 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:02.333081 20850 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I1012 16:12:02.333231 20850 status.go:34] setting inner loop State "WaitDeployment" [odo] I1012 16:12:02.333337 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600680, generation: 1, pods: 0 [odo] ✓ Pod is Running [odo] I1012 16:12:04.102167 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600705, generation: 1, pods: 1 [odo] I1012 16:12:04.402656 20850 watch.go:423] Copying files [] to pod [odo] I1012 16:12:04.404007 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:04.404034 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:04.405502 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:04.405704 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:04.405730 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:04.413058 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:04.413517 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:04.413560 20850 components.go:69] component state: "WaitDeployment" [odo] I1012 16:12:04.424224 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:04.457908 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:04.482664 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:04.482738 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:04.482758 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:12:04.482779 20850 components.go:352] Applying deployment [odo] I1012 16:12:04.507794 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:12:04.525238 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:12:04.556700 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:04.556905 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:04.589243 20850 all.go:67] all goroutines have returned in 32.544018ms [odo] I1012 16:12:04.589276 20850 all.go:76] query result: objects=6 [odo] I1012 16:12:04.589337 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:04.589361 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:04.589371 20850 service.go:75] Getting list of services [odo] I1012 16:12:04.589376 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:04.627067 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:04.627166 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:04.634064 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:04.642673 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:04.649377 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:04.655981 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:04.656015 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:04.658322 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:04.661251 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:04.663175 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:04.672218 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:04.680653 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] • Syncing files into the container ... [odo] I1012 16:12:04.753739 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919, destBase: ., destFile: . [odo] I1012 16:12:04.753793 20850 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I1012 16:12:04.755477 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.gitignore, destBase: ., destFile: .gitignore [odo] I1012 16:12:04.755504 20850 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I1012 16:12:04.756092 20850 file_indexer.go:453] file added: /tmp/448009919/.gitignore [odo] I1012 16:12:04.756137 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.odo, destBase: ., destFile: .odo [odo] I1012 16:12:04.756146 20850 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I1012 16:12:04.756522 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/cluster.yaml, destBase: ., destFile: cluster.yaml [odo] I1012 16:12:04.756548 20850 file_indexer.go:406] Corrected destinations: base: . file: cluster.yaml [odo] I1012 16:12:04.756854 20850 file_indexer.go:453] file added: /tmp/448009919/cluster.yaml [odo] I1012 16:12:04.756920 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I1012 16:12:04.756936 20850 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I1012 16:12:04.757242 20850 file_indexer.go:453] file added: /tmp/448009919/devfile.yaml [odo] I1012 16:12:04.757277 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.mod, destBase: ., destFile: go.mod [odo] I1012 16:12:04.757286 20850 file_indexer.go:406] Corrected destinations: base: . file: go.mod [odo] I1012 16:12:04.757600 20850 file_indexer.go:453] file added: /tmp/448009919/go.mod [odo] I1012 16:12:04.757636 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.sum, destBase: ., destFile: go.sum [odo] I1012 16:12:04.757645 20850 file_indexer.go:406] Corrected destinations: base: . file: go.sum [odo] I1012 16:12:04.757933 20850 file_indexer.go:453] file added: /tmp/448009919/go.sum [odo] I1012 16:12:04.757965 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/main.go, destBase: ., destFile: main.go [odo] I1012 16:12:04.757973 20850 file_indexer.go:406] Corrected destinations: base: . file: main.go [odo] I1012 16:12:04.758467 20850 file_indexer.go:453] file added: /tmp/448009919/main.go [odo] I1012 16:12:04.758521 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware, destBase: ., destFile: middleware [odo] I1012 16:12:04.758549 20850 file_indexer.go:406] Corrected destinations: base: . file: middleware [odo] I1012 16:12:04.758889 20850 file_indexer.go:453] file added: /tmp/448009919/middleware [odo] I1012 16:12:04.759432 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware/handlers.go, destBase: ., destFile: middleware/handlers.go [odo] I1012 16:12:04.759449 20850 file_indexer.go:406] Corrected destinations: base: . file: middleware/handlers.go [odo] I1012 16:12:04.759768 20850 file_indexer.go:453] file added: /tmp/448009919/middleware/handlers.go [odo] I1012 16:12:04.759805 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models, destBase: ., destFile: models [odo] I1012 16:12:04.759820 20850 file_indexer.go:406] Corrected destinations: base: . file: models [odo] I1012 16:12:04.760107 20850 file_indexer.go:453] file added: /tmp/448009919/models [odo] I1012 16:12:04.760609 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models/models.go, destBase: ., destFile: models/models.go [odo] I1012 16:12:04.760630 20850 file_indexer.go:406] Corrected destinations: base: . file: models/models.go [odo] I1012 16:12:04.760993 20850 file_indexer.go:453] file added: /tmp/448009919/models/models.go [odo] I1012 16:12:04.761041 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router, destBase: ., destFile: router [odo] I1012 16:12:04.761059 20850 file_indexer.go:406] Corrected destinations: base: . file: router [odo] I1012 16:12:04.761396 20850 file_indexer.go:453] file added: /tmp/448009919/router [odo] I1012 16:12:04.761866 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router/router.go, destBase: ., destFile: router/router.go [odo] I1012 16:12:04.761885 20850 file_indexer.go:406] Corrected destinations: base: . file: router/router.go [odo] I1012 16:12:04.762266 20850 file_indexer.go:453] file added: /tmp/448009919/router/router.go [odo] I1012 16:12:04.762738 20850 sync.go:138] List of files to be deleted: +[] [odo] I1012 16:12:04.762773 20850 sync.go:140] List of files changed: +[/tmp/448009919/models /tmp/448009919/middleware /tmp/448009919/cluster.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.mod /tmp/448009919/main.go /tmp/448009919/models/models.go /tmp/448009919/middleware/handlers.go /tmp/448009919/router] [odo] I1012 16:12:04.762795 20850 sync.go:193] Push: componentName: hjeusw, path: /tmp/448009919, files: [/tmp/448009919/models /tmp/448009919/middleware /tmp/448009919/cluster.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.mod /tmp/448009919/main.go /tmp/448009919/models/models.go /tmp/448009919/middleware/handlers.go /tmp/448009919/router], delFiles: [*], isForcePush: true [odo] I1012 16:12:04.763410 20850 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I1012 16:12:04.763455 20850 exec.go:37] Executing command [rm -rf /projects/*] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:04.951204 20850 sync.go:233] Copying files /tmp/448009919/models /tmp/448009919/middleware /tmp/448009919/cluster.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.mod /tmp/448009919/main.go /tmp/448009919/models/models.go /tmp/448009919/middleware/handlers.go /tmp/448009919/router to pod [odo] I1012 16:12:04.951259 20850 copy.go:36] CopyFile arguments: localPath /tmp/448009919, dest /projects/448009919, targetPath /projects, copyFiles [/tmp/448009919/models /tmp/448009919/middleware /tmp/448009919/cluster.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.mod /tmp/448009919/main.go /tmp/448009919/models/models.go /tmp/448009919/middleware/handlers.go /tmp/448009919/router], globalExps [.git .odo .odo/odo-file-index.json] [odo] I1012 16:12:04.951299 20850 copy.go:63] Executing command tar xf - -C /projects --no-same-owner [odo] I1012 16:12:04.951489 20850 copy.go:96] makeTar arguments: srcPath: /tmp/448009919, destPath: /projects/448009919, files: [/tmp/448009919/models /tmp/448009919/middleware /tmp/448009919/cluster.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.mod /tmp/448009919/main.go /tmp/448009919/models/models.go /tmp/448009919/middleware/handlers.go /tmp/448009919/router] [odo] I1012 16:12:04.952134 20850 copy.go:127] Got abs path: /tmp/448009919/models [odo] I1012 16:12:04.952194 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/models [odo] I1012 16:12:04.952229 20850 copy.go:144] makeTar srcFile: 448009919/models [odo] I1012 16:12:04.952308 20850 copy.go:145] makeTar destFile: models [odo] I1012 16:12:04.952349 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models, destBase: /projects, destFile: models [odo] I1012 16:12:04.952377 20850 copy.go:171] Corrected destinations: base: /projects file: models [odo] I1012 16:12:04.953351 20850 copy.go:127] Got abs path: /tmp/448009919/middleware [odo] I1012 16:12:04.953444 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/middleware [odo] I1012 16:12:04.953477 20850 copy.go:144] makeTar srcFile: 448009919/middleware [odo] I1012 16:12:04.953516 20850 copy.go:145] makeTar destFile: middleware [odo] I1012 16:12:04.953543 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware, destBase: /projects, destFile: middleware [odo] I1012 16:12:04.953580 20850 copy.go:171] Corrected destinations: base: /projects file: middleware [odo] I1012 16:12:04.954035 20850 copy.go:127] Got abs path: /tmp/448009919/cluster.yaml [odo] I1012 16:12:04.954078 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/cluster.yaml [odo] I1012 16:12:04.954113 20850 copy.go:144] makeTar srcFile: 448009919/cluster.yaml [odo] I1012 16:12:04.954148 20850 copy.go:145] makeTar destFile: cluster.yaml [odo] I1012 16:12:04.954173 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/cluster.yaml, destBase: /projects, destFile: cluster.yaml [odo] I1012 16:12:04.954207 20850 copy.go:171] Corrected destinations: base: /projects file: cluster.yaml [odo] I1012 16:12:05.046552 20850 copy.go:127] Got abs path: /tmp/448009919/go.sum [odo] I1012 16:12:05.046666 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/go.sum [odo] I1012 16:12:05.046689 20850 copy.go:144] makeTar srcFile: 448009919/go.sum [odo] I1012 16:12:05.046704 20850 copy.go:145] makeTar destFile: go.sum [odo] I1012 16:12:05.046720 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.sum, destBase: /projects, destFile: go.sum [odo] I1012 16:12:05.046756 20850 copy.go:171] Corrected destinations: base: /projects file: go.sum [odo] I1012 16:12:05.047568 20850 copy.go:127] Got abs path: /tmp/448009919/router/router.go [odo] I1012 16:12:05.047622 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/router/router.go [odo] I1012 16:12:05.047646 20850 copy.go:144] makeTar srcFile: 448009919/router/router.go [odo] I1012 16:12:05.047664 20850 copy.go:145] makeTar destFile: router/router.go [odo] I1012 16:12:05.047703 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router/router.go, destBase: /projects, destFile: router/router.go [odo] I1012 16:12:05.047729 20850 copy.go:171] Corrected destinations: base: /projects file: router/router.go [odo] I1012 16:12:05.048351 20850 copy.go:127] Got abs path: /tmp/448009919/.gitignore [odo] I1012 16:12:05.048429 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/.gitignore [odo] I1012 16:12:05.048455 20850 copy.go:144] makeTar srcFile: 448009919/.gitignore [odo] I1012 16:12:05.048471 20850 copy.go:145] makeTar destFile: .gitignore [odo] I1012 16:12:05.048484 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.gitignore, destBase: /projects, destFile: .gitignore [odo] I1012 16:12:05.048524 20850 copy.go:171] Corrected destinations: base: /projects file: .gitignore [odo] I1012 16:12:05.049272 20850 copy.go:127] Got abs path: /tmp/448009919/devfile.yaml [odo] I1012 16:12:05.049315 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/devfile.yaml [odo] I1012 16:12:05.049333 20850 copy.go:144] makeTar srcFile: 448009919/devfile.yaml [odo] I1012 16:12:05.049347 20850 copy.go:145] makeTar destFile: devfile.yaml [odo] I1012 16:12:05.049362 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I1012 16:12:05.049388 20850 copy.go:171] Corrected destinations: base: /projects file: devfile.yaml [odo] I1012 16:12:05.050033 20850 copy.go:127] Got abs path: /tmp/448009919/go.mod [odo] I1012 16:12:05.050073 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/go.mod [odo] I1012 16:12:05.050094 20850 copy.go:144] makeTar srcFile: 448009919/go.mod [odo] I1012 16:12:05.050145 20850 copy.go:145] makeTar destFile: go.mod [odo] I1012 16:12:05.050169 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.mod, destBase: /projects, destFile: go.mod [odo] I1012 16:12:05.050192 20850 copy.go:171] Corrected destinations: base: /projects file: go.mod [odo] I1012 16:12:05.050922 20850 copy.go:127] Got abs path: /tmp/448009919/main.go [odo] I1012 16:12:05.050949 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/main.go [odo] I1012 16:12:05.050963 20850 copy.go:144] makeTar srcFile: 448009919/main.go [odo] I1012 16:12:05.051025 20850 copy.go:145] makeTar destFile: main.go [odo] I1012 16:12:05.051040 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/main.go, destBase: /projects, destFile: main.go [odo] I1012 16:12:05.051048 20850 copy.go:171] Corrected destinations: base: /projects file: main.go [odo] I1012 16:12:05.051613 20850 copy.go:127] Got abs path: /tmp/448009919/models/models.go [odo] I1012 16:12:05.051632 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/models/models.go [odo] I1012 16:12:05.051644 20850 copy.go:144] makeTar srcFile: 448009919/models/models.go [odo] I1012 16:12:05.051653 20850 copy.go:145] makeTar destFile: models/models.go [odo] I1012 16:12:05.051666 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models/models.go, destBase: /projects, destFile: models/models.go [odo] I1012 16:12:05.051739 20850 copy.go:171] Corrected destinations: base: /projects file: models/models.go [odo] I1012 16:12:05.052323 20850 copy.go:127] Got abs path: /tmp/448009919/middleware/handlers.go [odo] I1012 16:12:05.052342 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/middleware/handlers.go [odo] I1012 16:12:05.052355 20850 copy.go:144] makeTar srcFile: 448009919/middleware/handlers.go [odo] I1012 16:12:05.052372 20850 copy.go:145] makeTar destFile: middleware/handlers.go [odo] I1012 16:12:05.052386 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware/handlers.go, destBase: /projects, destFile: middleware/handlers.go [odo] I1012 16:12:05.052399 20850 copy.go:171] Corrected destinations: base: /projects file: middleware/handlers.go [odo] I1012 16:12:05.052958 20850 copy.go:127] Got abs path: /tmp/448009919/router [odo] I1012 16:12:05.052978 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/router [odo] I1012 16:12:05.052990 20850 copy.go:144] makeTar srcFile: 448009919/router [odo] I1012 16:12:05.053005 20850 copy.go:145] makeTar destFile: router [odo] I1012 16:12:05.053017 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router, destBase: /projects, destFile: router [odo] I1012 16:12:05.053030 20850 copy.go:171] Corrected destinations: base: /projects file: router [odo] ✓ Syncing files into the container [435ms] [odo] I1012 16:12:05.190718 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:12:05.190790 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:05.400729 20850 exec.go:96] cat: /opt/odo/.odo_cmd_run.pid: No such file or directory [odo] I1012 16:12:05.403918 20850 innerloop.go:131] running=false, execRequired=true [odo] • Building your application in container (command: build) ... [odo] I1012 16:12:05.404103 20850 exec.go:37] Executing command [/bin/sh -c cd ${PROJECT_SOURCE} && (export GOPATH="${PROJECT_SOURCE}/.go" GOCACHE="${PROJECT_SOURCE}/.cache" && go build main.go) 1>>/proc/1/fd/1 2>>/proc/1/fd/2] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] ✓ Building your application in container (command: build) [3s] [odo] I1012 16:12:08.782745 20850 kubeexec.go:54] StartProcessForCommand for "run" [odo] • Executing the application (command: run) ... [odo] I1012 16:12:08.782860 20850 retry.go:48] waiting for 5 second(s) before trying task "process for command \"run\"" [odo] I1012 16:12:08.782940 20850 exec.go:37] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd ${PROJECT_SOURCE} && (./main) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:13.783736 20850 execute_run.go:90] checking if process for command "run" is running [odo] I1012 16:12:13.783763 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:12:13.783789 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:14.004135 20850 exec.go:96] 159 [odo] I1012 16:12:14.007641 20850 exec.go:37] Executing command [/bin/sh -c kill -0 159; echo $?] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:14.161152 20850 exec.go:96] 0 [odo] I1012 16:12:14.163261 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:12:14.163303 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:14.386126 20850 exec.go:96] 159 [odo] I1012 16:12:14.388465 20850 exec.go:37] Executing command [/bin/sh -c kill -0 159; echo $?] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:14.565070 20850 exec.go:96] 0 [odo] • Waiting for the application to be ready ... [odo] I1012 16:12:15.568086 20850 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:15.803071 20850 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 16:12:15.803110 20850 exec.go:96] 0: 5D2E11AC:D6BC F1B5FA8E:01BB 06 00000000:00000000 03:000014B1 00000000 0 0 0 3 0000000000000000 [odo] I1012 16:12:15.803269 20850 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 16:12:15.803750 20850 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 16:12:15.803771 20850 exec.go:96] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1006740000 0 1324462279 1 0000000000000000 100 0 0 10 0 [odo] I1012 16:12:15.803782 20850 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 16:12:15.806157 20850 port.go:346] ports not listening: map[] [odo] ✓ Waiting for the application to be ready [1s] [odo] - Forwarding from 127.0.0.1:34803 -> 8080 [odo] [odo] I1012 16:12:15.942898 20850 status.go:34] setting inner loop State "Ready" [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/448009919 [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] I1012 16:12:15.954358 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600710, generation: 1, pods: 1 [odo] I1012 16:12:16.055860 20850 watch.go:348] filesystem watch event: CREATE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:12:16.056815 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:12:16.057331 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.json" [odo] I1012 16:12:16.057920 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.20850.json" Running odo with args [odo add binding --name tnscym --service cluster-example-initdb --bind-as-files=false] and odo env: [] [odo] I1012 16:12:22.137425 20884 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:22.138126 20884 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:22.138913 20884 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:22.138985 20884 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:22.139136 20884 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:22.143455 20884 schema.go:61] validated devfile schema [odo] I1012 16:12:22.147599 20884 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:22.147614 20884 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:22.147668 20884 component.go:87] name of component is "hjeusw", and sanitized name is "hjeusw" [odo] I1012 16:12:22.147701 20884 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:22.268730 20884 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:22.268747 20884 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:22.269393 20884 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:22.269511 20884 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:22.269524 20884 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:22.274318 20884 schema.go:61] validated devfile schema [odo] I1012 16:12:22.274765 20884 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:22.480726 20884 kclient.go:237] Checking if "deployments" resource is supported [odo] I1012 16:12:22.494659 20884 writer.go:55] devfile yaml created at: 'devfile.yaml' [odo] ✓ Successfully added the binding to the devfile. [odo] Run `odo dev` to create it on the cluster. [odo] I1012 16:12:22.494734 20884 implem.go:114] The path for preference file is /tmp/3637726993/preference.yaml [odo] I1012 16:12:22.495500 20884 segment.go:268] Checking telemetry enable status [odo] I1012 16:12:22.495578 20884 segment.go:286] Sending telemetry disabled by env variable [odo] Updating Component... [odo] [odo] I1012 16:12:22.595130 20850 watch.go:423] Copying files [] to pod [odo] I1012 16:12:22.595934 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:22.596019 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:22.597586 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:22.597791 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:22.597852 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:22.604177 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:22.604816 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:22.604892 20850 components.go:69] component state: "Ready" [odo] I1012 16:12:22.613527 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:22.644145 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:22.671322 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:22.671451 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:22.671489 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:12:22.671571 20850 components.go:352] Applying deployment [odo] I1012 16:12:22.696612 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:12:22.717952 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:12:22.764021 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:22.764468 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:22.850778 20850 all.go:67] all goroutines have returned in 86.773121ms [odo] I1012 16:12:22.850914 20850 all.go:76] query result: objects=6 [odo] I1012 16:12:22.854133 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:22.903989 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:22.904170 20850 service.go:75] Getting list of services [odo] I1012 16:12:22.904204 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:22.938739 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:22.938854 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:22.949244 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:22.971351 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:22.979916 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:22.987514 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:22.987555 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:22.990063 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:22.995022 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:22.997389 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:23.010190 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:23.017376 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:23.026047 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] • Creating resource ServiceBinding/tnscym ... [odo] ✓ Creating resource ServiceBinding/tnscym [odo] I1012 16:12:23.206379 20850 components.go:152] Waiting for all service bindings to be injected... [odo] I1012 16:12:23.206542 20850 watch.go:437] Error from Push: watch command was unable to push component: some servicebindings are not injected [odo] Error occurred on Push - watch command was unable to push component: some servicebindings are not injected [odo] [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/448009919 [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] I1012 16:12:23.207116 20850 watch.go:348] filesystem watch event: CREATE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:12:23.208264 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:12:23.208636 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.json" [odo] I1012 16:12:23.209167 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.20850.json" [odo] I1012 16:12:23.209768 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/devfile.yaml" [odo] I1012 16:12:23.210280 20850 file_watcher.go:64] adding watch on path /tmp/448009919/devfile.yaml [odo] I1012 16:12:23.210371 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/devfile.yaml" [odo] I1012 16:12:23.210751 20850 file_watcher.go:64] adding watch on path /tmp/448009919/devfile.yaml [odo] I1012 16:12:23.210823 20850 status.go:34] setting inner loop State "SyncOutdated" [odo] Pushing files... [odo] [odo] [odo] File /tmp/448009919/devfile.yaml changed [odo] I1012 16:12:23.210905 20850 watch.go:423] Copying files [/tmp/448009919/devfile.yaml] to pod [odo] I1012 16:12:23.211362 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:23.211410 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:23.212570 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:23.212791 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:23.212844 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:23.218196 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:23.218675 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:23.218766 20850 components.go:69] component state: "SyncOutdated" [odo] • Waiting for Kubernetes resources ... [odo] I1012 16:12:23.225940 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:23.254245 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:23.283242 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:23.283317 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:23.283334 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:12:23.283351 20850 components.go:352] Applying deployment [odo] I1012 16:12:23.335186 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:12:23.353866 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:12:23.382753 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:23.382912 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:23.438066 20850 all.go:67] all goroutines have returned in 55.288264ms [odo] I1012 16:12:23.438105 20850 all.go:76] query result: objects=7 [odo] I1012 16:12:23.439508 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:23.440824 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:23.440902 20850 service.go:75] Getting list of services [odo] I1012 16:12:23.440916 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:23.504443 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:23.504550 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:23.512472 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:23.522746 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:23.529117 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:23.545992 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:23.546036 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:23.547882 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:23.549934 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:23.551813 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:23.567710 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:23.575507 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:23.581985 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] Error occurred on Push - watch command was unable to push component: unable to get pod for component hjeusw: pod not found for the selector: component=hjeusw [odo] [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/448009919 [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] ======================= [odo] ⚠ Pod is Terminating [odo] ======================= [odo] I1012 16:12:23.710388 20850 watch.go:437] Error from Push: watch command was unable to push component: unable to get pod for component hjeusw: pod not found for the selector: component=hjeusw [odo] I1012 16:12:23.710483 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600869, generation: 1, pods: 1 [odo] I1012 16:12:23.711914 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600880, generation: 1, pods: 1 [odo] I1012 16:12:23.712692 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600890, generation: 2, pods: 1 [odo] I1012 16:12:23.713368 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600897, generation: 2, pods: 1 [odo] I1012 16:12:23.750766 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600904, generation: 2, pods: 0 [odo] I1012 16:12:24.050877 20850 watch.go:423] Copying files [] to pod [odo] I1012 16:12:24.051398 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:24.051423 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:24.052727 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:24.052939 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:24.052966 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:24.059628 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:24.060076 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:24.060124 20850 components.go:69] component state: "SyncOutdated" [odo] • Waiting for Kubernetes resources ... [odo] I1012 16:12:24.067581 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:24.117561 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:24.139715 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:24.139752 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:24.139766 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:12:24.139786 20850 components.go:352] Applying deployment [odo] I1012 16:12:24.163593 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:12:24.185064 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:12:24.211441 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:24.211596 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:24.247533 20850 all.go:67] all goroutines have returned in 36.093791ms [odo] I1012 16:12:24.247621 20850 all.go:76] query result: objects=7 [odo] I1012 16:12:24.248314 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:24.248931 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:24.248973 20850 service.go:75] Getting list of services [odo] I1012 16:12:24.249005 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:24.281535 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:24.281593 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:24.292065 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:24.302731 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:24.309256 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:24.315760 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:24.315792 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:24.318811 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:24.322203 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:24.324281 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:24.338106 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:24.361986 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:24.369895 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:24.501057 20850 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I1012 16:12:24.501133 20850 status.go:34] setting inner loop State "WaitDeployment" [odo] I1012 16:12:24.501264 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87600908, generation: 2, pods: 0 [odo] I1012 16:12:53.673438 20850 exec.go:54] ExecuteCommand returned an an err: error while streaming command: command terminated with exit code 137. for command '[/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd ${PROJECT_SOURCE} && (./main) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid]' [odo] stdout: [] [odo] stderr: [] [odo] I1012 16:12:53.673643 20850 kubeexec.go:118] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd ${PROJECT_SOURCE} && (./main) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid]: error while streaming command: command terminated with exit code 137 [odo] I1012 16:12:53.673689 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:12:53.673736 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-5bdb567bf5-lq5w2 in container: runtime [odo] I1012 16:12:53.845388 20850 exec.go:54] ExecuteCommand returned an an err: error while streaming command: Internal error occurred: error executing command in container: container is not created or running. for command '[/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true]' [odo] stdout: [] [odo] stderr: [] [odo] ✗ Finished executing the application (command: run) [45s] [odo] I1012 16:12:53.845508 20850 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} && (./main) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid]: error while streaming command: command terminated with exit code 137 [odo] ================== [odo] ⚠ No pod exists [odo] ================== [odo] I1012 16:12:55.479828 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87601183, generation: 2, pods: 0 [odo] I1012 16:12:55.591297 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87601186, generation: 2, pods: 0 [odo] =================== [odo] ⚠ Pod is Pending [odo] =================== [odo] I1012 16:12:55.744423 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87601196, generation: 2, pods: 0 [odo] ✓ Pod is Running [odo] I1012 16:12:58.377713 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87601220, generation: 2, pods: 1 [odo] I1012 16:12:58.678110 20850 watch.go:423] Copying files [] to pod [odo] I1012 16:12:58.678673 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:58.678697 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:12:58.679962 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:12:58.680112 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:12:58.680127 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:12:58.687276 20850 schema.go:61] validated devfile schema [odo] I1012 16:12:58.687739 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:12:58.687815 20850 components.go:69] component state: "WaitDeployment" [odo] I1012 16:12:58.696754 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:12:58.726214 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:12:58.748766 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:12:58.748801 20850 components.go:347] The component name is hjeusw [odo] I1012 16:12:58.748816 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:12:58.748833 20850 components.go:352] Applying deployment [odo] I1012 16:12:58.771660 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:12:58.804127 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:12:58.828852 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:12:58.830258 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:12:58.874410 20850 all.go:67] all goroutines have returned in 45.553515ms [odo] I1012 16:12:58.874503 20850 all.go:76] query result: objects=8 [odo] I1012 16:12:58.875787 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:12:58.877072 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:12:58.877189 20850 service.go:75] Getting list of services [odo] I1012 16:12:58.877212 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:12:58.906630 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:12:58.906659 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:58.918455 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:58.927091 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:58.933608 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:12:58.939525 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:12:58.939566 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:12:58.941529 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:58.943409 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:12:58.945645 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:12:58.956349 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:58.962643 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:12:58.968835 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] • Syncing files into the container ... [odo] I1012 16:12:59.256238 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919, destBase: ., destFile: . [odo] I1012 16:12:59.256297 20850 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I1012 16:12:59.257391 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.gitignore, destBase: ., destFile: .gitignore [odo] I1012 16:12:59.257444 20850 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I1012 16:12:59.257867 20850 file_indexer.go:453] file added: /tmp/448009919/.gitignore [odo] I1012 16:12:59.257930 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.odo, destBase: ., destFile: .odo [odo] I1012 16:12:59.257962 20850 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I1012 16:12:59.258241 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/cluster.yaml, destBase: ., destFile: cluster.yaml [odo] I1012 16:12:59.258278 20850 file_indexer.go:406] Corrected destinations: base: . file: cluster.yaml [odo] I1012 16:12:59.258552 20850 file_indexer.go:453] file added: /tmp/448009919/cluster.yaml [odo] I1012 16:12:59.258645 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I1012 16:12:59.258670 20850 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I1012 16:12:59.258946 20850 file_indexer.go:453] file added: /tmp/448009919/devfile.yaml [odo] I1012 16:12:59.258998 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.mod, destBase: ., destFile: go.mod [odo] I1012 16:12:59.259021 20850 file_indexer.go:406] Corrected destinations: base: . file: go.mod [odo] I1012 16:12:59.259312 20850 file_indexer.go:453] file added: /tmp/448009919/go.mod [odo] I1012 16:12:59.259371 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.sum, destBase: ., destFile: go.sum [odo] I1012 16:12:59.259399 20850 file_indexer.go:406] Corrected destinations: base: . file: go.sum [odo] I1012 16:12:59.259702 20850 file_indexer.go:453] file added: /tmp/448009919/go.sum [odo] I1012 16:12:59.259761 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/main.go, destBase: ., destFile: main.go [odo] I1012 16:12:59.259800 20850 file_indexer.go:406] Corrected destinations: base: . file: main.go [odo] I1012 16:12:59.260105 20850 file_indexer.go:453] file added: /tmp/448009919/main.go [odo] I1012 16:12:59.260162 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware, destBase: ., destFile: middleware [odo] I1012 16:12:59.260217 20850 file_indexer.go:406] Corrected destinations: base: . file: middleware [odo] I1012 16:12:59.260539 20850 file_indexer.go:453] file added: /tmp/448009919/middleware [odo] I1012 16:12:59.261132 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware/handlers.go, destBase: ., destFile: middleware/handlers.go [odo] I1012 16:12:59.261187 20850 file_indexer.go:406] Corrected destinations: base: . file: middleware/handlers.go [odo] I1012 16:12:59.261520 20850 file_indexer.go:453] file added: /tmp/448009919/middleware/handlers.go [odo] I1012 16:12:59.261591 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models, destBase: ., destFile: models [odo] I1012 16:12:59.261619 20850 file_indexer.go:406] Corrected destinations: base: . file: models [odo] I1012 16:12:59.262032 20850 file_indexer.go:453] file added: /tmp/448009919/models [odo] I1012 16:12:59.262530 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models/models.go, destBase: ., destFile: models/models.go [odo] I1012 16:12:59.262601 20850 file_indexer.go:406] Corrected destinations: base: . file: models/models.go [odo] I1012 16:12:59.262950 20850 file_indexer.go:453] file added: /tmp/448009919/models/models.go [odo] I1012 16:12:59.263022 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router, destBase: ., destFile: router [odo] I1012 16:12:59.263050 20850 file_indexer.go:406] Corrected destinations: base: . file: router [odo] I1012 16:12:59.263365 20850 file_indexer.go:453] file added: /tmp/448009919/router [odo] I1012 16:12:59.263830 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router/router.go, destBase: ., destFile: router/router.go [odo] I1012 16:12:59.263872 20850 file_indexer.go:406] Corrected destinations: base: . file: router/router.go [odo] I1012 16:12:59.264209 20850 file_indexer.go:453] file added: /tmp/448009919/router/router.go [odo] I1012 16:12:59.264593 20850 sync.go:138] List of files to be deleted: +[] [odo] I1012 16:12:59.264645 20850 sync.go:140] List of files changed: +[/tmp/448009919/middleware/handlers.go /tmp/448009919/models/models.go /tmp/448009919/main.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/cluster.yaml /tmp/448009919/go.mod /tmp/448009919/router /tmp/448009919/middleware /tmp/448009919/models] [odo] I1012 16:12:59.264685 20850 sync.go:193] Push: componentName: hjeusw, path: /tmp/448009919, files: [/tmp/448009919/middleware/handlers.go /tmp/448009919/models/models.go /tmp/448009919/main.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/cluster.yaml /tmp/448009919/go.mod /tmp/448009919/router /tmp/448009919/middleware /tmp/448009919/models], delFiles: [*], isForcePush: true [odo] I1012 16:12:59.265298 20850 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I1012 16:12:59.265368 20850 exec.go:37] Executing command [rm -rf /projects/*] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:12:59.476813 20850 sync.go:233] Copying files /tmp/448009919/middleware/handlers.go /tmp/448009919/models/models.go /tmp/448009919/main.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/cluster.yaml /tmp/448009919/go.mod /tmp/448009919/router /tmp/448009919/middleware /tmp/448009919/models to pod [odo] I1012 16:12:59.476845 20850 copy.go:36] CopyFile arguments: localPath /tmp/448009919, dest /projects/448009919, targetPath /projects, copyFiles [/tmp/448009919/middleware/handlers.go /tmp/448009919/models/models.go /tmp/448009919/main.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/cluster.yaml /tmp/448009919/go.mod /tmp/448009919/router /tmp/448009919/middleware /tmp/448009919/models], globalExps [.git .odo .odo/odo-file-index.json] [odo] I1012 16:12:59.476874 20850 copy.go:63] Executing command tar xf - -C /projects --no-same-owner [odo] I1012 16:12:59.477075 20850 copy.go:96] makeTar arguments: srcPath: /tmp/448009919, destPath: /projects/448009919, files: [/tmp/448009919/middleware/handlers.go /tmp/448009919/models/models.go /tmp/448009919/main.go /tmp/448009919/.gitignore /tmp/448009919/devfile.yaml /tmp/448009919/go.sum /tmp/448009919/router/router.go /tmp/448009919/cluster.yaml /tmp/448009919/go.mod /tmp/448009919/router /tmp/448009919/middleware /tmp/448009919/models] [odo] I1012 16:12:59.477907 20850 copy.go:127] Got abs path: /tmp/448009919/middleware/handlers.go [odo] I1012 16:12:59.477941 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/middleware/handlers.go [odo] I1012 16:12:59.477958 20850 copy.go:144] makeTar srcFile: 448009919/middleware/handlers.go [odo] I1012 16:12:59.477974 20850 copy.go:145] makeTar destFile: middleware/handlers.go [odo] I1012 16:12:59.477984 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware/handlers.go, destBase: /projects, destFile: middleware/handlers.go [odo] I1012 16:12:59.477994 20850 copy.go:171] Corrected destinations: base: /projects file: middleware/handlers.go [odo] I1012 16:12:59.626604 20850 copy.go:127] Got abs path: /tmp/448009919/models/models.go [odo] I1012 16:12:59.626617 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/models/models.go [odo] I1012 16:12:59.626624 20850 copy.go:144] makeTar srcFile: 448009919/models/models.go [odo] I1012 16:12:59.626627 20850 copy.go:145] makeTar destFile: models/models.go [odo] I1012 16:12:59.626632 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models/models.go, destBase: /projects, destFile: models/models.go [odo] I1012 16:12:59.626636 20850 copy.go:171] Corrected destinations: base: /projects file: models/models.go [odo] I1012 16:12:59.626979 20850 copy.go:127] Got abs path: /tmp/448009919/main.go [odo] I1012 16:12:59.626990 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/main.go [odo] I1012 16:12:59.626995 20850 copy.go:144] makeTar srcFile: 448009919/main.go [odo] I1012 16:12:59.627004 20850 copy.go:145] makeTar destFile: main.go [odo] I1012 16:12:59.627008 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/main.go, destBase: /projects, destFile: main.go [odo] I1012 16:12:59.627011 20850 copy.go:171] Corrected destinations: base: /projects file: main.go [odo] I1012 16:12:59.627405 20850 copy.go:127] Got abs path: /tmp/448009919/.gitignore [odo] I1012 16:12:59.627412 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/.gitignore [odo] I1012 16:12:59.627417 20850 copy.go:144] makeTar srcFile: 448009919/.gitignore [odo] I1012 16:12:59.627420 20850 copy.go:145] makeTar destFile: .gitignore [odo] I1012 16:12:59.627423 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.gitignore, destBase: /projects, destFile: .gitignore [odo] I1012 16:12:59.627426 20850 copy.go:171] Corrected destinations: base: /projects file: .gitignore [odo] I1012 16:12:59.627946 20850 copy.go:127] Got abs path: /tmp/448009919/devfile.yaml [odo] I1012 16:12:59.627954 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/devfile.yaml [odo] I1012 16:12:59.627959 20850 copy.go:144] makeTar srcFile: 448009919/devfile.yaml [odo] I1012 16:12:59.627962 20850 copy.go:145] makeTar destFile: devfile.yaml [odo] I1012 16:12:59.627999 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I1012 16:12:59.628004 20850 copy.go:171] Corrected destinations: base: /projects file: devfile.yaml [odo] I1012 16:12:59.628416 20850 copy.go:127] Got abs path: /tmp/448009919/go.sum [odo] I1012 16:12:59.628423 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/go.sum [odo] I1012 16:12:59.628428 20850 copy.go:144] makeTar srcFile: 448009919/go.sum [odo] I1012 16:12:59.628476 20850 copy.go:145] makeTar destFile: go.sum [odo] I1012 16:12:59.628481 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.sum, destBase: /projects, destFile: go.sum [odo] I1012 16:12:59.628485 20850 copy.go:171] Corrected destinations: base: /projects file: go.sum [odo] I1012 16:12:59.628865 20850 copy.go:127] Got abs path: /tmp/448009919/router/router.go [odo] I1012 16:12:59.628872 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/router/router.go [odo] I1012 16:12:59.628876 20850 copy.go:144] makeTar srcFile: 448009919/router/router.go [odo] I1012 16:12:59.628880 20850 copy.go:145] makeTar destFile: router/router.go [odo] I1012 16:12:59.628883 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router/router.go, destBase: /projects, destFile: router/router.go [odo] I1012 16:12:59.628887 20850 copy.go:171] Corrected destinations: base: /projects file: router/router.go [odo] I1012 16:12:59.629250 20850 copy.go:127] Got abs path: /tmp/448009919/cluster.yaml [odo] I1012 16:12:59.629257 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/cluster.yaml [odo] I1012 16:12:59.629262 20850 copy.go:144] makeTar srcFile: 448009919/cluster.yaml [odo] I1012 16:12:59.629303 20850 copy.go:145] makeTar destFile: cluster.yaml [odo] I1012 16:12:59.629322 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/cluster.yaml, destBase: /projects, destFile: cluster.yaml [odo] I1012 16:12:59.629326 20850 copy.go:171] Corrected destinations: base: /projects file: cluster.yaml [odo] I1012 16:12:59.629715 20850 copy.go:127] Got abs path: /tmp/448009919/go.mod [odo] I1012 16:12:59.629722 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/go.mod [odo] I1012 16:12:59.629750 20850 copy.go:144] makeTar srcFile: 448009919/go.mod [odo] I1012 16:12:59.629755 20850 copy.go:145] makeTar destFile: go.mod [odo] I1012 16:12:59.629758 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.mod, destBase: /projects, destFile: go.mod [odo] I1012 16:12:59.629760 20850 copy.go:171] Corrected destinations: base: /projects file: go.mod [odo] I1012 16:12:59.630117 20850 copy.go:127] Got abs path: /tmp/448009919/router [odo] I1012 16:12:59.630124 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/router [odo] I1012 16:12:59.630129 20850 copy.go:144] makeTar srcFile: 448009919/router [odo] I1012 16:12:59.630138 20850 copy.go:145] makeTar destFile: router [odo] I1012 16:12:59.630143 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router, destBase: /projects, destFile: router [odo] I1012 16:12:59.630145 20850 copy.go:171] Corrected destinations: base: /projects file: router [odo] I1012 16:12:59.630529 20850 copy.go:127] Got abs path: /tmp/448009919/middleware [odo] I1012 16:12:59.630535 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/middleware [odo] I1012 16:12:59.630540 20850 copy.go:144] makeTar srcFile: 448009919/middleware [odo] I1012 16:12:59.630543 20850 copy.go:145] makeTar destFile: middleware [odo] I1012 16:12:59.630552 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware, destBase: /projects, destFile: middleware [odo] I1012 16:12:59.630555 20850 copy.go:171] Corrected destinations: base: /projects file: middleware [odo] I1012 16:12:59.630931 20850 copy.go:127] Got abs path: /tmp/448009919/models [odo] I1012 16:12:59.630951 20850 copy.go:128] Making /tmp/448009919 relative to /tmp/448009919/models [odo] I1012 16:12:59.630956 20850 copy.go:144] makeTar srcFile: 448009919/models [odo] I1012 16:12:59.630959 20850 copy.go:145] makeTar destFile: models [odo] I1012 16:12:59.630962 20850 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models, destBase: /projects, destFile: models [odo] I1012 16:12:59.630965 20850 copy.go:171] Corrected destinations: base: /projects file: models [odo] ✓ Syncing files into the container [465ms] [odo] I1012 16:12:59.722638 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:12:59.722688 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:12:59.883925 20850 exec.go:96] cat: /opt/odo/.odo_cmd_run.pid: No such file or directory [odo] • Building your application in container (command: build) ... [odo] I1012 16:12:59.887408 20850 innerloop.go:131] running=false, execRequired=true [odo] I1012 16:12:59.887483 20850 exec.go:37] Executing command [/bin/sh -c cd ${PROJECT_SOURCE} && (export GOPATH="${PROJECT_SOURCE}/.go" GOCACHE="${PROJECT_SOURCE}/.cache" && go build main.go) 1>>/proc/1/fd/1 2>>/proc/1/fd/2] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] ✓ Building your application in container (command: build) [2s] [odo] I1012 16:13:01.981538 20850 kubeexec.go:54] StartProcessForCommand for "run" [odo] • Executing the application (command: run) ... [odo] I1012 16:13:01.981632 20850 retry.go:48] waiting for 5 second(s) before trying task "process for command \"run\"" [odo] I1012 16:13:01.981680 20850 exec.go:37] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd ${PROJECT_SOURCE} && (./main) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:06.982485 20850 execute_run.go:90] checking if process for command "run" is running [odo] I1012 16:13:06.982593 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:13:06.982643 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:07.155518 20850 exec.go:96] 161 [odo] I1012 16:13:07.157535 20850 exec.go:37] Executing command [/bin/sh -c kill -0 161; echo $?] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:07.336032 20850 exec.go:96] 0 [odo] I1012 16:13:07.337132 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:13:07.337245 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:07.539295 20850 exec.go:96] 161 [odo] I1012 16:13:07.541450 20850 exec.go:37] Executing command [/bin/sh -c kill -0 161; echo $?] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:07.735635 20850 exec.go:96] 0 [odo] • Waiting for the application to be ready ... [odo] I1012 16:13:08.739964 20850 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:09.016759 20850 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 16:13:09.016778 20850 exec.go:96] 0: 532E11AC:A388 F1B5FA8E:01BB 06 00000000:00000000 03:000014B0 00000000 0 0 0 3 0000000000000000 [odo] I1012 16:13:09.016784 20850 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 16:13:09.016791 20850 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 16:13:09.016796 20850 exec.go:96] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1006740000 0 1324479792 1 0000000000000000 100 0 0 10 0 [odo] I1012 16:13:09.016800 20850 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] I1012 16:13:09.018406 20850 port.go:346] ports not listening: map[] [odo] - Forwarding from 127.0.0.1:43071 -> 8080 [odo] [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/448009919 [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] I1012 16:13:09.123824 20850 status.go:34] setting inner loop State "Ready" [odo] I1012 16:13:09.123903 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87601224, generation: 2, pods: 1 [odo] I1012 16:13:09.225618 20850 watch.go:348] filesystem watch event: CREATE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:13:09.225899 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:13:09.226060 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.json" [odo] I1012 16:13:09.226326 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.20850.json" [odo] I1012 16:13:09.226556 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/devfile.yaml" [odo] I1012 16:13:09.226928 20850 file_watcher.go:64] adding watch on path /tmp/448009919/devfile.yaml [odo] I1012 16:13:09.226951 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/devfile.yaml" [odo] I1012 16:13:09.227148 20850 file_watcher.go:64] adding watch on path /tmp/448009919/devfile.yaml [odo] I1012 16:13:09.227160 20850 watch.go:348] filesystem watch event: REMOVE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:13:09.227177 20850 watch.go:383] error removing watch for /tmp/448009919/.odo/odo-file-index.json: can't remove non-existent watcher: /tmp/448009919/.odo/odo-file-index.json [odo] I1012 16:13:09.227183 20850 watch.go:348] filesystem watch event: CREATE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:13:09.227329 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/odo-file-index.json" [odo] I1012 16:13:09.227481 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.json" [odo] I1012 16:13:09.227622 20850 watch.go:348] filesystem watch event: WRITE "/tmp/448009919/.odo/devstate.20850.json" [odo] I1012 16:13:09.227856 20850 status.go:34] setting inner loop State "SyncOutdated" [odo] Pushing files... [odo] [odo] [odo] File /tmp/448009919/devfile.yaml changed [odo] I1012 16:13:09.227912 20850 watch.go:423] Copying files [/tmp/448009919/devfile.yaml] to pod [odo] I1012 16:13:09.228456 20850 context.go:172] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:13:09.228495 20850 context.go:113] absolute devfile path: '/tmp/448009919/devfile.yaml' [odo] I1012 16:13:09.229142 20850 content.go:48] converted devfile YAML to JSON [odo] I1012 16:13:09.229749 20850 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1012 16:13:09.229810 20850 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1012 16:13:09.236870 20850 schema.go:61] validated devfile schema [odo] I1012 16:13:09.237196 20850 validate.go:45] Successfully validated devfile sections [odo] I1012 16:13:09.237258 20850 components.go:69] component state: "SyncOutdated" [odo] • Waiting for Kubernetes resources ... [odo] I1012 16:13:09.248216 20850 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I1012 16:13:09.276498 20850 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1012 16:13:09.300085 20850 components.go:346] Creating deployment hjeusw-app [odo] I1012 16:13:09.300102 20850 components.go:347] The component name is hjeusw [odo] I1012 16:13:09.300109 20850 components.go:350] The component already exists, attempting to update it [odo] I1012 16:13:09.300120 20850 components.go:352] Applying deployment [odo] I1012 16:13:09.325955 20850 components.go:361] Successfully updated component hjeusw [odo] I1012 16:13:09.344724 20850 components.go:748] Successfully update Service for component hjeusw [odo] I1012 16:13:09.369246 20850 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:13:09.369381 20850 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:13:09.441486 20850 all.go:67] all goroutines have returned in 72.246381ms [odo] I1012 16:13:09.441513 20850 all.go:76] query result: objects=8 [odo] I1012 16:13:09.442182 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1012 16:13:09.442840 20850 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1012 16:13:09.443182 20850 service.go:75] Getting list of services [odo] I1012 16:13:09.443195 20850 operators.go:30] Fetching list of operators installed in cluster [odo] I1012 16:13:09.472121 20850 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.6 [odo] I1012 16:13:09.472189 20850 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1012 16:13:09.479844 20850 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1012 16:13:09.488972 20850 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1012 16:13:09.496899 20850 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1012 16:13:09.502553 20850 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I1012 16:13:09.502617 20850 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1012 16:13:09.504313 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:13:09.506021 20850 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1012 16:13:09.507553 20850 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1012 16:13:09.542219 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:13:09.548434 20850 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1012 16:13:09.554878 20850 kclient.go:237] Checking if "servicebindings" resource is supported [odo] • Syncing files into the container ... [odo] I1012 16:13:09.903308 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919, destBase: ., destFile: . [odo] I1012 16:13:09.903395 20850 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I1012 16:13:09.904641 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.gitignore, destBase: ., destFile: .gitignore [odo] I1012 16:13:09.904698 20850 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I1012 16:13:09.905140 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/.odo, destBase: ., destFile: .odo [odo] I1012 16:13:09.905193 20850 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I1012 16:13:09.905563 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/cluster.yaml, destBase: ., destFile: cluster.yaml [odo] I1012 16:13:09.905616 20850 file_indexer.go:406] Corrected destinations: base: . file: cluster.yaml [odo] I1012 16:13:09.905975 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I1012 16:13:09.906023 20850 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I1012 16:13:09.906341 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.mod, destBase: ., destFile: go.mod [odo] I1012 16:13:09.906387 20850 file_indexer.go:406] Corrected destinations: base: . file: go.mod [odo] I1012 16:13:09.906751 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/go.sum, destBase: ., destFile: go.sum [odo] I1012 16:13:09.906810 20850 file_indexer.go:406] Corrected destinations: base: . file: go.sum [odo] I1012 16:13:09.908406 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/main.go, destBase: ., destFile: main.go [odo] I1012 16:13:09.908500 20850 file_indexer.go:406] Corrected destinations: base: . file: main.go [odo] I1012 16:13:09.909077 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware, destBase: ., destFile: middleware [odo] I1012 16:13:09.909120 20850 file_indexer.go:406] Corrected destinations: base: . file: middleware [odo] I1012 16:13:09.910430 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/middleware/handlers.go, destBase: ., destFile: middleware/handlers.go [odo] I1012 16:13:09.910489 20850 file_indexer.go:406] Corrected destinations: base: . file: middleware/handlers.go [odo] I1012 16:13:09.911091 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models, destBase: ., destFile: models [odo] I1012 16:13:09.911147 20850 file_indexer.go:406] Corrected destinations: base: . file: models [odo] I1012 16:13:09.915196 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/models/models.go, destBase: ., destFile: models/models.go [odo] I1012 16:13:09.915256 20850 file_indexer.go:406] Corrected destinations: base: . file: models/models.go [odo] I1012 16:13:09.915755 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router, destBase: ., destFile: router [odo] I1012 16:13:09.915797 20850 file_indexer.go:406] Corrected destinations: base: . file: router [odo] I1012 16:13:09.920941 20850 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 448009919/router/router.go, destBase: ., destFile: router/router.go [odo] I1012 16:13:09.921013 20850 file_indexer.go:406] Corrected destinations: base: . file: router/router.go [odo] I1012 16:13:09.922690 20850 sync.go:138] List of files to be deleted: +[] [odo] I1012 16:13:09.922793 20850 sync.go:140] List of files changed: +[] [odo] ✓ Syncing files into the container [21ms] [odo] I1012 16:13:09.925026 20850 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1012 16:13:09.925091 20850 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:10.146554 20850 exec.go:96] 161 [odo] I1012 16:13:10.149834 20850 exec.go:37] Executing command [/bin/sh -c kill -0 161; echo $?] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:10.362432 20850 exec.go:96] 0 [odo] I1012 16:13:10.364705 20850 innerloop.go:131] running=true, execRequired=false [odo] • Waiting for the application to be ready ... [odo] I1012 16:13:11.365938 20850 exec.go:37] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: hjeusw-app-fb96d4bbc-zfwrr in container: runtime [odo] I1012 16:13:11.554390 20850 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 16:13:11.554416 20850 exec.go:96] 0: 532E11AC:A388 F1B5FA8E:01BB 06 00000000:00000000 03:000013B3 00000000 0 0 0 3 0000000000000000 [odo] I1012 16:13:11.554537 20850 exec.go:96] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 16:13:11.554861 20850 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I1012 16:13:11.554869 20850 exec.go:96] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1006740000 0 1324479792 1 0000000000000000 100 0 0 10 0 [odo] ✓ Waiting for the application to be ready [1s] [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/448009919 [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] I1012 16:13:11.555410 20850 exec.go:96] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I1012 16:13:11.555473 20850 port.go:346] ports not listening: map[] [odo] I1012 16:13:11.555530 20850 status.go:34] setting inner loop State "Ready" [odo] I1012 16:13:11.555557 20850 watch.go:271] deployment watcher Event: Type: MODIFIED, name: hjeusw-app, rv: 87601312, generation: 2, pods: 1 Running oc with args [oc get pods --namespace e2e-test461fou --selector=component=hjeusw -o jsonpath={.items[*].metadata.name}] and odo env: [] [oc] hjeusw-app-fb96d4bbc-zfwrrRunning oc with args [oc exec hjeusw-app-fb96d4bbc-zfwrr --namespace e2e-test461fou -c runtime -- curl http://127.0.0.1:8080/ping] and odo env: [] [oc] % Total % Received % Xferd Average Speed Time Time Time Current [oc] Dload Upload Total Spent Left Speed [oc] 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 4 100 4 0 0 4000 0 --:--:-- --:--:-- --:--:-- 4000 [oc] pong [FAILED] in [It] - /go/odo_1/tests/e2escenarios/e2e_test.go:430 @ 10/12/23 16:13:15.671 Running oc with args [oc get project e2e-test461fou -o jsonpath={.metadata.name}] and odo env: [] [oc] e2e-test461fouDeleting project: e2e-test461fou Running oc with args [oc delete project e2e-test461fou --wait=false] and odo env: [] [oc] project.project.openshift.io "e2e-test461fou" deleted Setting current dir to: /go/odo_1/tests/e2escenarios Deleting dir: /tmp/448009919 Deleting dir: /tmp/3637726993 << Timeline [FAILED] Expected <*url.Error | 0xc0000fa720>: Post "http://127.0.0.1:43071/api/newuser": EOF { Op: "Post", URL: "http://127.0.0.1:43071/api/newuser", Err: <*errors.errorString | 0xc0001100f0>{s: "EOF"}, } to be nil In [It] at: /go/odo_1/tests/e2escenarios/e2e_test.go:430 @ 10/12/23 16:13:15.671 ------------------------------ Progress Report for Ginkgo Process #2 Automatically polling progress: E2E Test starting with non-empty Directory should verify developer workflow from non-empty Directory (Spec Runtime: 2m3.733s) /go/odo_1/tests/e2escenarios/e2e_test.go:199 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/e2escenarios/e2e_test.go:199 Begin Captured GinkgoWriter Output >> ... [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/4131721236 [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] I1012 16:13:55.021101 20775 watch.go:271] deployment watcher Event: Type: MODIFIED, name: bepxsy-app, rv: 87601559, generation: 1, pods: 1 [odo] I1012 16:13:55.122979 20775 watch.go:348] filesystem watch event: WRITE "/tmp/4131721236/.odo/odo-file-index.json" [odo] I1012 16:13:55.125512 20775 watch.go:348] filesystem watch event: WRITE "/tmp/4131721236/.odo/odo-file-index.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 28 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00041d9d0, {0x2f3fe50?, 0xc00070cc30}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00041d9d0, {0x2f3fe50, 0xc00070cc30}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2b61f03, 0x8}, 0xc000580000?, 0xc0000b0600?, 0xc00070c660) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.(*DevSession).WaitSync(0xc000519d28) /go/odo_1/tests/helper/helper_dev.go:279 > github.com/redhat-developer/odo/tests/e2escenarios.glob..func1.6.2() /go/odo_1/tests/e2escenarios/e2e_test.go:246 | // "changes are made to the applications" | helper.ReplaceString(filepath.Join(commonVar.Context, "src", "main", "java", "com", "example", "demo", "DemoApplication.java"), "Hello updated World!", "Hello from an updated World!") > err = devSession.WaitSync() | Expect(err).ToNot(HaveOccurred()) | // "should deploy new changes" 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 ------------------------------ Progress Report for Ginkgo Process #5 Automatically polling progress: E2E Test starting with non-empty Directory test debugging should verify developer workflow from non-empty Directory (Spec Runtime: 2m3.777s) /go/odo_1/tests/e2escenarios/e2e_test.go:329 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/e2escenarios/e2e_test.go:329 Begin Captured GinkgoWriter Output >> ... [odo] I1012 16:13:45.546597 20787 execute_run.go:36] error while running background command: unable to exec command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_debug.pid && cd ${PROJECT_SOURCE} && (npm run debug) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_debug.pid]: error while streaming command: context canceled [odo] ✗ Finished executing the application (command: debug) [32s] [odo] I1012 16:13:45.563181 20787 implem.go:114] The path for preference file is /tmp/1704957674/preference.yaml [odo] I1012 16:13:45.564045 20787 segment.go:268] Checking telemetry enable status [odo] I1012 16:13:45.564070 20787 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I1012 16:13:45.682020 20787 all.go:46] starting to concurrently query 200 APIs [odo] I1012 16:13:45.685438 20787 all.go:62] fired up all goroutines to query APIs [odo] I1012 16:13:46.358167 20787 all.go:67] all goroutines have returned in 676.157338ms [odo] I1012 16:13:46.358238 20787 all.go:76] query result: objects=609 << End Captured GinkgoWriter Output Spec Goroutine goroutine 13 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0006ba380, {0x2f3fd30?, 0xc000a109a8}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0006ba380, {0x2f3fd30, 0xc000a109a8}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc000dfde28?, {0xc000a1a6b0?, 0xc000dfdd98?, 0x3?}) /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:263 > github.com/redhat-developer/odo/tests/e2escenarios.glob..func1.7.2() /go/odo_1/tests/e2escenarios/e2e_test.go:408 | // "exit dev mode" | devSession.Stop() > devSession.WaitEnd() | | // all resources should be deleted from the namespace github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x103b705, 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 ------------------------------ Progress Report for Ginkgo Process #6 Automatically polling progress: E2E Test starting with empty Directory should verify developer workflow from empty Directory (Spec Runtime: 2m4.076s) /go/odo_1/tests/e2escenarios/e2e_test.go:63 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/e2escenarios/e2e_test.go:63 Begin Captured GinkgoWriter Output >> ... [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] I1012 16:13:51.447278 21045 watch.go:271] deployment watcher Event: Type: MODIFIED, name: kpuhaj-app, rv: 87601682, generation: 1, pods: 1 [odo] I1012 16:13:51.559146 21045 watch.go:348] filesystem watch event: REMOVE "/tmp/2848381608/.odo/odo-file-index.json" [odo] I1012 16:13:51.559408 21045 watch.go:383] error removing watch for /tmp/2848381608/.odo/odo-file-index.json: can't remove non-existent watcher: /tmp/2848381608/.odo/odo-file-index.json [odo] I1012 16:13:51.559459 21045 watch.go:348] filesystem watch event: CREATE "/tmp/2848381608/.odo/odo-file-index.json" [odo] I1012 16:13:51.565733 21045 watch.go:348] filesystem watch event: WRITE "/tmp/2848381608/.odo/odo-file-index.json" [odo] I1012 16:13:51.569778 21045 watch.go:348] filesystem watch event: WRITE "/tmp/2848381608/.odo/devstate.json" [odo] I1012 16:13:51.573536 21045 watch.go:348] filesystem watch event: WRITE "/tmp/2848381608/.odo/devstate.21045.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 83 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000388c0, {0x2f3fe50?, 0xc000b3fef0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000388c0, {0x2f3fe50, 0xc000b3fef0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2b6ff84, 0xf}, 0x0?, 0x3?, 0xc000b3fe90) /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:192 > github.com/redhat-developer/odo/tests/e2escenarios.glob..func1.5.2() /go/odo_1/tests/e2escenarios/e2e_test.go:155 | | // start dev mode again > devSession, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | 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 ------------------------------ • [149.360 seconds] E2E Test starting with empty Directory should verify developer workflow from empty Directory /go/odo_1/tests/e2escenarios/e2e_test.go:63 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo init' from /tmp/2848381608 Spawning '/go/bin/odo delete component' from /tmp/2848381608 << Captured StdOut/StdErr Output ------------------------------ • [150.581 seconds] E2E Test starting with non-empty Directory test debugging should verify developer workflow from non-empty Directory /go/odo_1/tests/e2escenarios/e2e_test.go:329 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo init' from /tmp/2520251045 << Captured StdOut/StdErr Output ------------------------------ Progress Report for Ginkgo Process #2 Automatically polling progress: E2E Test starting with non-empty Directory should verify developer workflow from non-empty Directory (Spec Runtime: 4m3.739s) /go/odo_1/tests/e2escenarios/e2e_test.go:199 In [It] (Node Runtime: 4m0.006s) /go/odo_1/tests/e2escenarios/e2e_test.go:199 Begin Captured GinkgoWriter Output >> ... [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/4131721236 [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] I1012 16:15:50.234200 21304 watch.go:271] deployment watcher Event: Type: MODIFIED, name: bepxsy-app, rv: 87602855, generation: 1, pods: 1 [odo] I1012 16:15:50.348648 21304 watch.go:348] filesystem watch event: WRITE "/tmp/4131721236/.odo/odo-file-index.json" [odo] I1012 16:15:50.350446 21304 watch.go:348] filesystem watch event: WRITE "/tmp/4131721236/.odo/odo-file-index.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 28 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00003e000, {0x2f3fe50?, 0xc00076e000}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:538 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00003e000, {0x2f3fe50, 0xc00076e000}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2b61f03, 0x8}, 0xc0004c6d80?, 0xc0000b0600?, 0xc00076f350) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.(*DevSession).WaitSync(0xc000abfd28) /go/odo_1/tests/helper/helper_dev.go:279 > github.com/redhat-developer/odo/tests/e2escenarios.glob..func1.6.2() /go/odo_1/tests/e2escenarios/e2e_test.go:287 | // making changes to the project again | helper.ReplaceString(filepath.Join(commonVar.Context, "src", "main", "java", "com", "example", "demo", "DemoApplication.java"), "Hello from an updated World!", "Hello from an updated v2 World!") > err = devSession.WaitSync() | Expect(err).ToNot(HaveOccurred()) | 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 ------------------------------ • [252.971 seconds] E2E Test starting with non-empty Directory should verify developer workflow from non-empty Directory /go/odo_1/tests/e2escenarios/e2e_test.go:199 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo init' from /tmp/4131721236 Spawning '/go/bin/odo delete component' from /tmp/4131721236 << Captured StdOut/StdErr Output ------------------------------ Summarizing 1 Failure: [FAIL] E2E Test starting with non-empty Directory add Binding [It] should verify developer workflow of using binding as env in innerloop /go/odo_1/tests/e2escenarios/e2e_test.go:430 Ran 4 of 4 Specs in 252.984 seconds FAIL! -- 3 Passed | 1 Failed | 0 Pending | 0 Skipped Ginkgo ran 1 suite in 4m30.984011772s Test Suite Failed exit status 1 make: *** [Makefile:236: test-e2e] Error 1