Error from server (NotFound): namespaces "devfile-proxy" not found Using Devfile proxy: go install -mod=vendor -ldflags="-X github.com/redhat-developer/odo/pkg/version.GITCOMMIT=38cac04c4" ./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: 1684950190 - will randomize all specs Will run 497 of 894 specs Running in parallel across 16 processes SSSSSSSSSSSSSSSSSS••S•• ------------------------------ • [10.697 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/882991116 << Captured StdOut/StdErr Output ------------------------------ S ------------------------------ • [22.305 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (naming strategy: "lowercase") /go/odo_1/tests/integration/interactive_add_binding_test.go:378 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/1006079512 << Captured StdOut/StdErr Output ------------------------------ SSSS ------------------------------ • [22.193 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile (Bind as Files) /go/odo_1/tests/integration/interactive_add_binding_test.go:101 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/3539306535 << Captured StdOut/StdErr Output ------------------------------ •S•S••••SSS••S•S••S•S•••••••S•S••••S•S••S•SS•••SSS•••S•SSSS••SS••S•••••••SSSS•• ------------------------------ • [65.904 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:3471 Captured StdOut/StdErr Output >> PID: 14809 << Captured StdOut/StdErr Output ------------------------------ •S•SSSS•SS•••SSSS•S•••••••SS•SS••SSSSSSS•SSS ------------------------------ • [11.382 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (custom naming strategy: "") /go/odo_1/tests/integration/interactive_add_binding_test.go:463 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/3185148795 << Captured StdOut/StdErr Output ------------------------------ S ------------------------------ Progress Report for Ginkgo Process #3 Automatically polling progress: odo describe/list binding command tests when creating a component with a binding as files (service in namespace "binding-ysh") when Starting a Pg service when running dev session should list the binding (Spec Runtime: 2m12.023s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:742 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:690 Begin Captured GinkgoWriter Output >> ... [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] I0524 17:47:56.350632 15719 status.go:34] setting inner loop State "Ready" [odo] I0524 17:47:56.350699 15719 watch.go:263] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 30424153, generation: 3, pods: 1 [odo] I0524 17:47:56.451098 15719 watch.go:339] filesystem watch event: CREATE "/tmp/2556393984/.odo/odo-file-index.json" [odo] I0524 17:47:56.451448 15719 watch.go:339] filesystem watch event: WRITE "/tmp/2556393984/.odo/odo-file-index.json" [odo] I0524 17:47:56.451598 15719 watch.go:339] filesystem watch event: WRITE "/tmp/2556393984/.odo/devstate.json" [odo] I0524 17:47:56.451845 15719 watch.go:339] filesystem watch event: WRITE "/tmp/2556393984/.odo/devstate.15719.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 262 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0004251f0, {0x30aabc8?, 0xc0005c3ad0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0004251f0, {0x30aabc8, 0xc0005c3ad0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cb6bfa, 0xf}, 0x0?, 0x2?, 0xc0005c3a40) /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:163 > github.com/redhat-developer/odo/tests/integration.glob..func5.28.2.3.1() /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:692 | BeforeEach(func() { | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2669033, 0xc00006b500}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ ••S••S•SS•••SSS•SS•SS•• ------------------------------ • [79.535 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/1712765648 << Captured StdOut/StdErr Output ------------------------------ •S••SSS•S••••••S ------------------------------ • [31.073 seconds] odo add binding interactive command tests when the component is bootstrapped when binding to a service in a different namespace should successfully add binding to the devfile /go/odo_1/tests/integration/interactive_add_binding_test.go:257 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/870337461 << Captured StdOut/StdErr Output ------------------------------ • [18.630 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile (custom naming strategy: "") /go/odo_1/tests/integration/interactive_add_binding_test.go:180 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/408159791 << Captured StdOut/StdErr Output ------------------------------ ••S•• ------------------------------ • [20.060 seconds] odo add binding interactive command tests when running a deployment when binding to a service in a different namespace should successfully add binding without devfile /go/odo_1/tests/integration/interactive_add_binding_test.go:591 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/2232583391 << Captured StdOut/StdErr Output ------------------------------ SS••SS•••••••SSS•• ------------------------------ • [12.748 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile ("lowercase" as naming strategy) /go/odo_1/tests/integration/interactive_add_binding_test.go:131 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/643526282 << Captured StdOut/StdErr Output ------------------------------ S••S• ------------------------------ • [16.815 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (custom naming strategy: "{ .name | upper }") /go/odo_1/tests/integration/interactive_add_binding_test.go:463 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/1774059482 << Captured StdOut/StdErr Output ------------------------------ S•S••S•S•S••• ------------------------------ • [13.134 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (default naming strategy) /go/odo_1/tests/integration/interactive_add_binding_test.go:311 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/2941045022 << Captured StdOut/StdErr Output ------------------------------ •S•••••S••S•••S••SS•••S•S••S•S•S••S•SSS••••S•SSS•SSSS•SS•S•• ------------------------------ • [11.404 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile ("none" as naming strategy) /go/odo_1/tests/integration/interactive_add_binding_test.go:131 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/403664973 << Captured StdOut/StdErr Output ------------------------------ •SSS ------------------------------ • [11.165 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (custom naming strategy: "any string") /go/odo_1/tests/integration/interactive_add_binding_test.go:463 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/1805657054 << Captured StdOut/StdErr Output ------------------------------ SS••••SS•SS•SS•S•SS ------------------------------ Progress Report for Ginkgo Process #9 Automatically polling progress: odo describe/list binding command tests when creating a component with a spec binding (service in namespace "binding-lpk") when Starting a Pg service when running dev session should list the binding (Spec Runtime: 2m9.952s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:742 In [BeforeEach] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:690 Begin Captured GinkgoWriter Output >> ... [odo] Watching for changes in the current directory /tmp/550317093 [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] I0524 17:53:13.217745 24950 watch.go:263] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 30445803, generation: 3, pods: 1 [odo] I0524 17:53:13.339891 24950 watch.go:339] filesystem watch event: CREATE "/tmp/550317093/.odo/odo-file-index.json" [odo] I0524 17:53:13.340493 24950 watch.go:339] filesystem watch event: WRITE "/tmp/550317093/.odo/odo-file-index.json" [odo] I0524 17:53:13.340985 24950 watch.go:339] filesystem watch event: WRITE "/tmp/550317093/.odo/devstate.json" [odo] I0524 17:53:13.341512 24950 watch.go:339] filesystem watch event: WRITE "/tmp/550317093/.odo/devstate.24950.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 27531 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000291490, {0x30aabc8?, 0xc001728f90}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000291490, {0x30aabc8, 0xc001728f90}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cb6bfa, 0xf}, 0x0?, 0x2?, 0xc001728f60) /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:163 > github.com/redhat-developer/odo/tests/integration.glob..func5.28.2.3.1() /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:692 | BeforeEach(func() { | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2669033, 0xc001148d20}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ ••SS•SSSS••SSS•S••SS••S•SSS•S••SS••••SS••S•S••••SS•S•S ------------------------------ • [10.231 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile ("uppercase" as naming strategy) /go/odo_1/tests/integration/interactive_add_binding_test.go:131 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/2617217868 << Captured StdOut/StdErr Output ------------------------------ •SS•• ------------------------------ • [80.647 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/3329420815 << Captured StdOut/StdErr Output ------------------------------ S•S••SSSSS• ------------------------------ • [18.700 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:206 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo dev --random-ports --no-watch' from /tmp/1916610420 << Captured StdOut/StdErr Output ------------------------------ • ------------------------------ Progress Report for Ginkgo Process #12 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: 3m31.649s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:294 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:294 At [By Step] cleaning up the resources on ending the session (Step Runtime: 16.826s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:332 Begin Captured GinkgoWriter Output >> ... [odo] stderr: [] [odo] I0524 17:55:45.970906 26613 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] I0524 17:55:45.987359 26613 implem.go:114] The path for preference file is /tmp/3884199814/preference.yaml [odo] Cleaning resources, please wait [odo] I0524 17:55:45.988329 26613 segment.go:264] Checking telemetry enable status [odo] I0524 17:55:45.988354 26613 segment.go:282] Sending telemetry disabled by env variable [odo] I0524 17:55:46.131381 26613 all.go:46] starting to concurrently query 200 APIs [odo] I0524 17:55:46.135143 26613 all.go:62] fired up all goroutines to query APIs [odo] I0524 17:55:46.997605 26613 all.go:67] all goroutines have returned in 866.200234ms [odo] I0524 17:55:46.997731 26613 all.go:76] query result: objects=581 << End Captured GinkgoWriter Output Spec Goroutine goroutine 576 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00028eb60, {0x30aa988?, 0xc0009ba480}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00028eb60, {0x30aa988, 0xc0009ba480}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc00081a180?, {0xc000182e90?, 0xa1ac2fb31f?, 0x42e3200?}) /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:224 > github.com/redhat-developer/odo/tests/integration.glob..func6.7.2.8() /go/odo_1/tests/integration/cmd_dev_debug_test.go:334 | By("cleaning up the resources on ending the session", func() { | session.Stop() > session.WaitEnd() | out := commonVar.CliRunner.Run("get", "deployments").Out.Contents() | helper.DontMatchAllInOutput(string(out), deploymentNames) github.com/onsi/ginkgo/v2/internal.(*Suite).By(0xc0000c4000, {0x2d06c67, 0x2f}, {0xc000fc3e60, 0x1, 0x0?}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:301 github.com/onsi/ginkgo/v2.By({0x2d06c67?, 0x0?}, {0xc000fc3e60?, 0x0?, 0x0?}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/core_dsl.go:547 > github.com/redhat-developer/odo/tests/integration.glob..func6.7.2() /go/odo_1/tests/integration/cmd_dev_debug_test.go:332 | }) | > By("cleaning up the resources on ending the session", func() { | session.Stop() | session.WaitEnd() github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2669033, 0xc0014146c0}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ •S••••S•S••SS••SS•S•S•SSSS•••S••S••SS•SS•••S••SSSSSSS•S•SSSS•S••S•S••S ------------------------------ • [3.229 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/3134163909 << Captured StdOut/StdErr Output ------------------------------ •S• ------------------------------ • [9.450 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/1429280806 << Captured StdOut/StdErr Output ------------------------------ S•SS•••••SS•S ------------------------------ • [2.352 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/2891244764 << Captured StdOut/StdErr Output ------------------------------ SSSS ------------------------------ Progress Report for Ginkgo Process #1 Automatically polling progress: odo describe/list binding command tests when creating a component with a spec binding (service in namespace "") when Starting a Pg service when running dev session when changing the current namespace should list the binding with --namespace flag (Spec Runtime: 2m7.06s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:787 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:690 Begin Captured GinkgoWriter Output >> ... [odo] Watching for changes in the current directory /tmp/4030091239 [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] I0524 17:58:52.068758 31520 watch.go:263] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 30465367, generation: 3, pods: 1 [odo] I0524 17:58:52.204566 31520 watch.go:339] filesystem watch event: CREATE "/tmp/4030091239/.odo/odo-file-index.json" [odo] I0524 17:58:52.205154 31520 watch.go:339] filesystem watch event: WRITE "/tmp/4030091239/.odo/odo-file-index.json" [odo] I0524 17:58:52.205453 31520 watch.go:339] filesystem watch event: WRITE "/tmp/4030091239/.odo/devstate.json" [odo] I0524 17:58:52.205871 31520 watch.go:339] filesystem watch event: WRITE "/tmp/4030091239/.odo/devstate.31520.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 675 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0001993b0, {0x30aabc8?, 0xc000d7f350}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0001993b0, {0x30aabc8, 0xc000d7f350}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cb6bfa, 0xf}, 0x0?, 0x2?, 0xc000d7f320) /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:163 > github.com/redhat-developer/odo/tests/integration.glob..func5.28.2.3.1() /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:692 | BeforeEach(func() { | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2669033, 0xc0007d1140}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ •• ------------------------------ • [79.522 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/3356004273 << Captured StdOut/StdErr Output ------------------------------ ••••S••• ------------------------------ Progress Report for Ginkgo Process #12 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: 2m1.389s) /go/odo_1/tests/integration/cmd_dev_test.go:3980 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:3980 Begin Captured GinkgoWriter Output >> ... [odo] ✗ Finished executing the application (command: run) [1m] [odo] I0524 17:58:52.586460 32268 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] I0524 17:58:52.591298 32268 implem.go:114] The path for preference file is /tmp/2519204684/preference.yaml [odo] I0524 17:58:52.592274 32268 segment.go:264] Checking telemetry enable status [odo] I0524 17:58:52.592303 32268 segment.go:282] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0524 17:58:52.679137 32268 all.go:46] starting to concurrently query 200 APIs [odo] I0524 17:58:52.679266 32268 all.go:62] fired up all goroutines to query APIs [odo] I0524 17:58:53.468079 32268 all.go:67] all goroutines have returned in 788.506634ms [odo] I0524 17:58:53.468145 32268 all.go:76] query result: objects=544 << End Captured GinkgoWriter Output Spec Goroutine goroutine 651 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0006fdab0, {0x30aa988?, 0xc0009bb338}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0006fdab0, {0x30aa988, 0xc0009bb338}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc000c9de10?, {0xc0008459c0?, 0x6?, 0x2ca1bf1?}) /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:224 github.com/redhat-developer/odo/tests/helper.RunDevMode.func1() /go/odo_1/tests/helper/helper_dev.go:276 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:279 > github.com/redhat-developer/odo/tests/integration.glob..func7.64.2() /go/odo_1/tests/integration/cmd_dev_test.go:3981 | }) | 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, _ []byte, _ 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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ •S•• ------------------------------ Progress Report for Ginkgo Process #15 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: 2m1.628s) /go/odo_1/tests/integration/cmd_dev_test.go:922 In [BeforeEach] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:895 Begin Captured GinkgoWriter Output >> ... [odo] I0524 17:59:37.651259 347 exec.go:82] /bin/sh: line 0: kill: (49) - No such process [odo] I0524 17:59:37.657676 347 retry.go:48] waiting for 2 second(s) before trying task "status for remote process 49" [odo] I0524 17:59:39.658419 347 exec.go:33] Executing command [/bin/sh -c kill -0 49; echo $?] for pod: tkkmdv-app-6968f58cd8-pnq2d in container: runtime [odo] I0524 17:59:40.531364 347 exec.go:82] 1 [odo] I0524 17:59:40.531425 347 exec.go:82] /bin/sh: line 0: kill: (49) - No such process [odo] I0524 17:59:40.531999 347 exec.go:33] Executing command [/bin/sh -c rm -f /opt/odo/.odo_cmd_start.pid] for pod: tkkmdv-app-6968f58cd8-pnq2d in container: runtime [odo] I0524 17:59:40.847003 347 kubeexec.go:54] StartProcessForCommand for "start" [odo] • Executing the application (command: start) ... [odo] I0524 17:59:40.847095 347 retry.go:48] waiting for 5 second(s) before trying task "process for command \"start\"" [odo] I0524 17:59:40.847119 347 exec.go:33] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_start.pid && cd /projects && (npm start) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_start.pid] for pod: tkkmdv-app-6968f58cd8-pnq2d in container: runtime << End Captured GinkgoWriter Output Spec Goroutine goroutine 63691 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000498000, {0x30aabc8?, 0xc001380000}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000498000, {0x30aabc8, 0xc001380000}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2ca81e9, 0x8}, 0xc000461880?, 0xc000461860?, 0xc001380210) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.DevSession.WaitSync({0xc001380210, 0x0, 0xc0010da3c0, {0x0, 0x0}}) /go/odo_1/tests/helper/helper_dev.go:233 > github.com/redhat-developer/odo/tests/integration.glob..func7.5.1.1() /go/odo_1/tests/integration/cmd_dev_test.go:914 | 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({0x2669033, 0xc001bac8a0}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ •SS• ------------------------------ • [2.802 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/2428688426 << Captured StdOut/StdErr Output ------------------------------ •S•SS• ------------------------------ • [10.241 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile (custom naming strategy: "{ .name | upper }") /go/odo_1/tests/integration/interactive_add_binding_test.go:180 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/1187608443 << Captured StdOut/StdErr Output ------------------------------ •••••S•S ------------------------------ • [19.702 seconds] odo add binding interactive command tests when the component is bootstrapped when binding to a service in a different namespace should error out if service is not found in the namespace selected /go/odo_1/tests/integration/interactive_add_binding_test.go:242 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/4144332435 << Captured StdOut/StdErr Output ------------------------------ SSS•••SS••••••• ------------------------------ • [15.044 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (naming strategy: "none") /go/odo_1/tests/integration/interactive_add_binding_test.go:378 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/580976731 << Captured StdOut/StdErr Output ------------------------------ ••S•SSS•S••S ------------------------------ Progress Report for Ginkgo Process #5 Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - without metadata.name should successfully use the volume components in container components (Spec Runtime: 2m1.455s) /go/odo_1/tests/integration/cmd_dev_test.go:2124 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:2099 Begin Captured GinkgoWriter Output >> ... [odo] Watching for changes in the current directory /tmp/673406735 [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] I0524 18:00:29.984422 988 watch.go:263] deployment watcher Event: Type: MODIFIED, name: wtrwdy-app, rv: 30470440, generation: 1, pods: 1 [odo] I0524 18:00:30.085179 988 watch.go:339] filesystem watch event: CREATE "/tmp/673406735/.odo/odo-file-index.json" [odo] I0524 18:00:30.085456 988 watch.go:339] filesystem watch event: WRITE "/tmp/673406735/.odo/odo-file-index.json" [odo] I0524 18:00:30.085602 988 watch.go:339] filesystem watch event: WRITE "/tmp/673406735/.odo/devstate.json" [odo] I0524 18:00:30.085808 988 watch.go:339] filesystem watch event: WRITE "/tmp/673406735/.odo/devstate.988.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 65764 [select, 2 minutes] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0005ecee0, {0x30aabc8?, 0xc0012dbad0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0005ecee0, {0x30aabc8, 0xc0012dbad0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cb6bfa, 0xf}, 0x0?, 0x2?, 0xc0012dbaa0) /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:163 > github.com/redhat-developer/odo/tests/integration.glob..func7.23.1() /go/odo_1/tests/integration/cmd_dev_test.go:2113 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2669033, 0xc0011923c0}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ •••••••S•S•SSSSSS••SS•••SSSS•••SS•••S•S••S•S••S•SS•SS•SS••••S•••••••SSS•SS•S•••••••••SSSS•S•••S••S•SS•S••••SS•••S•SSSS•S•SSSS•S•SSS•••SSS••SS•S•••SS•••S••••S•SS•S•SS•S•••SSSSS••SS•SSS•S•••••SSSS•SSS••SSSSSS•SSS•S•••S••••S•••S• ------------------------------ • [13.524 seconds] odo add binding interactive command tests when running a deployment should successfully add binding without devfile (naming strategy: "uppercase") /go/odo_1/tests/integration/interactive_add_binding_test.go:378 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/3148070874 << Captured StdOut/StdErr Output ------------------------------ S••••S•SS•SSS••SS• ------------------------------ Progress Report for Ginkgo Process #16 Automatically polling progress: odo describe/list binding command tests when creating a component with a binding as files (service in namespace "binding-qpq") when Starting a Pg service when running dev session when changing the current namespace should list the binding with --namespace flag (Spec Runtime: 2m11.413s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:787 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:690 Begin Captured GinkgoWriter Output >> ... [odo] Watching for changes in the current directory /tmp/2468966641 [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] I0524 18:07:48.298657 12070 watch.go:263] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 30498603, generation: 3, pods: 1 [odo] I0524 18:07:48.400038 12070 watch.go:339] filesystem watch event: CREATE "/tmp/2468966641/.odo/odo-file-index.json" [odo] I0524 18:07:48.400763 12070 watch.go:339] filesystem watch event: WRITE "/tmp/2468966641/.odo/odo-file-index.json" [odo] I0524 18:07:48.401070 12070 watch.go:339] filesystem watch event: WRITE "/tmp/2468966641/.odo/devstate.json" [odo] I0524 18:07:48.401532 12070 watch.go:339] filesystem watch event: WRITE "/tmp/2468966641/.odo/devstate.12070.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 6634 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000377b90, {0x30aabc8?, 0xc000e22120}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000377b90, {0x30aabc8, 0xc000e22120}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cb6bfa, 0xf}, 0x0?, 0x2?, 0xc000e220f0) /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:163 > github.com/redhat-developer/odo/tests/integration.glob..func5.28.2.3.1() /go/odo_1/tests/integration/cmd_describe_list_binding_test.go:692 | BeforeEach(func() { | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x2669033, 0xc001312de0}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ •• ------------------------------ • [14.413 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile (Bind as Environment Variables) /go/odo_1/tests/integration/interactive_add_binding_test.go:73 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/704114638 << Captured StdOut/StdErr Output ------------------------------ •S•••S••••SS••SS•SSS•SS•••SSSS•S•S•S• ------------------------------ • [9.161 seconds] odo add binding interactive command tests when the component is bootstrapped should successfully add binding to the devfile (custom naming strategy: "any string") /go/odo_1/tests/integration/interactive_add_binding_test.go:180 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/501304730 << Captured StdOut/StdErr Output ------------------------------ •SS•S••SS•SS•S••••SS•S•S•SS ------------------------------ • [19.993 seconds] odo add binding interactive command tests when running a deployment when binding to a service in a different namespace should error out if service is not found in the namespace selected /go/odo_1/tests/integration/interactive_add_binding_test.go:577 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo add binding' from /tmp/3904761324 << Captured StdOut/StdErr Output ------------------------------ •S•S•••••••••••••••• Ran 487 of 894 Specs in 1775.036 seconds SUCCESS! -- 487 Passed | 0 Failed | 0 Pending | 407 Skipped Ginkgo ran 1 suite in 30m38.476259461s 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: 1684952035 - will randomize all specs Will run 4 of 4 specs Running in parallel across 16 processes ------------------------------ • [FAILED] [82.277 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:462 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo init' from /tmp/1874439376 << Captured StdOut/StdErr Output Timeline >> Created dir: /tmp/1874439376 Created dir: /tmp/3309117965 Setting KUBECONFIG=/tmp/3309117965/config Creating a new project: e2e-test462cwi Running oc with args [oc new-project e2e-test462cwi] and odo env: [] [oc] Now using project "e2e-test462cwi" on server "https://c100-e.eu-de.containers.cloud.ibm.com:31049". [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 create configmap config-map-for-cleanup --from-literal type=testing --from-literal team=odo -n e2e-test462cwi] 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] I0524 18:14:13.661059 20502 implem.go:114] The path for preference file is /tmp/3309117965/preference.yaml [odo] Successfully removed registry [odo] I0524 18:14:13.662418 20502 implem.go:114] The path for preference file is /tmp/3309117965/preference.yaml [odo] I0524 18:14:13.663195 20502 segment.go:264] Checking telemetry enable status [odo] I0524 18:14:13.663202 20502 segment.go:282] Sending telemetry disabled by env variable Running odo with args [odo preference add registry DefaultDevfileRegistry https://devfile-registry-ci-devfile-registry.odo-test-kubernetes-clust-49529fc6e6a4a9fe7ebba9a3db5b55c4-0000.eu-de.containers.appdomain.cloud/] and odo env: [] [odo] I0524 18:14:13.912188 20543 implem.go:114] The path for preference file is /tmp/3309117965/preference.yaml [odo] I0524 18:14:13.913808 20543 implem.go:114] The path for preference file is /tmp/3309117965/preference.yaml [odo] New registry successfully added [odo] I0524 18:14:13.914456 20543 segment.go:264] Checking telemetry enable status [odo] I0524 18:14:13.914466 20543 segment.go:282] 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.3 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.3 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/1874439376 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] and odo env: [ODO_LOG_LEVEL=4 ODO_TRACKING_CONSENT=no] [odo] I0524 18:14:19.186349 20668 version.go:41] executing [podman version --format json] [odo] I0524 18:14:19.186870 20668 clientset.go:198] no Podman client initialized: exec: "podman": executable file not found in $PATH [odo] I0524 18:14:19.186895 20668 implem.go:114] The path for preference file is /tmp/3309117965/preference.yaml [odo] I0524 18:14:19.187911 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:19.188034 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:19.188720 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:19.188795 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:19.188990 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:19.191854 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:19.194464 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:19.194506 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:19.194770 20668 component.go:87] name of component is "diseiw", and sanitized name is "diseiw" [odo] I0524 18:14:19.194830 20668 kclient.go:224] Checking if "projects" resource is supported [odo] __ [odo] / \__ Developing using the "diseiw" Devfile [odo] \__/ \ Namespace: e2e-test462cwi [odo] / \__/ odo version: v3.10.0 [odo] \__/ [odo] [odo] ↪ Running on the cluster in Dev mode [odo] I0524 18:14:19.375263 20668 kubedev.go:86] Creating new adapter [odo] I0524 18:14:19.375291 20668 kubedev.go:94] Creating inner-loop resources for the component [odo] I0524 18:14:19.375353 20668 watch.go:101] starting WatchAndPush, path: /tmp/1874439376, component: diseiw, ignores [.git .odo .odo/odo-file-index.json] [odo] I0524 18:14:19.377918 20668 file_watcher.go:126] adding watch on path /tmp/1874439376 [odo] I0524 18:14:19.377970 20668 file_watcher.go:126] adding watch on path /tmp/1874439376/.odo [odo] I0524 18:14:19.378052 20668 file_watcher.go:126] adding watch on path /tmp/1874439376/middleware [odo] I0524 18:14:19.378120 20668 file_watcher.go:126] adding watch on path /tmp/1874439376/models [odo] I0524 18:14:19.378149 20668 file_watcher.go:126] adding watch on path /tmp/1874439376/router [odo] I0524 18:14:19.419367 20668 watch.go:414] Copying files [] to pod [odo] I0524 18:14:19.419722 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:19.419750 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:19.420896 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:19.421100 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:19.421123 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:19.433470 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:19.434485 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:19.434596 20668 components.go:69] component state: "" [odo] • Waiting for Kubernetes resources ... [odo] I0524 18:14:19.454994 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:14:19.499693 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:14:19.533370 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:14:19.533382 20668 components.go:346] The component name is diseiw [odo] I0524 18:14:19.540484 20668 kclient.go:273] Kubernetes version is "v1.25.8+27e744f" [odo] I0524 18:14:19.540504 20668 kclient.go:285] 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] I0524 18:14:19.674975 20668 components.go:377] Successfully created component diseiw [odo] I0524 18:14:19.749503 20668 components.go:389] Successfully created Service for component diseiw [odo] I0524 18:14:19.778417 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:14:19.779073 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:14:19.835999 20668 all.go:67] all goroutines have returned in 57.563372ms [odo] I0524 18:14:19.836095 20668 all.go:76] query result: objects=6 [odo] I0524 18:14:19.836178 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:19.839436 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:14:19.843337 20668 service.go:75] Getting list of services [odo] I0524 18:14:19.843414 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:14:19.889557 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:14:19.889594 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:19.907263 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:19.928418 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:19.959596 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:19.977596 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:14:19.977653 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:14:19.981727 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:19.986607 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:19.990396 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:14:20.011468 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:20.025711 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:20.046608 20668 components.go:134] Deployment has been updated to generation 1. Waiting new event... [odo] I0524 18:14:20.046681 20668 status.go:34] setting inner loop State "WaitDeployment" [odo] ⚠ Pod is Pending [odo] I0524 18:14:20.065092 20668 watch.go:263] deployment watcher Event: Type: ADDED, name: diseiw-app, rv: 30518791, generation: 1, pods: 0 [odo] I0524 18:14:20.067296 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518795, generation: 1, pods: 0 [odo] I0524 18:14:20.069417 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518800, generation: 1, pods: 0 [odo] I0524 18:14:20.070840 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518806, generation: 1, pods: 0 [odo] I0524 18:14:20.367211 20668 watch.go:414] Copying files [] to pod [odo] I0524 18:14:20.368074 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:20.368108 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:20.369335 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:20.369534 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:20.369577 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:20.376970 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:20.377410 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:20.377464 20668 components.go:69] component state: "WaitDeployment" [odo] I0524 18:14:20.393805 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:14:20.449221 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:14:20.484982 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:14:20.485089 20668 components.go:346] The component name is diseiw [odo] I0524 18:14:20.485125 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:14:20.485180 20668 components.go:351] Applying deployment [odo] I0524 18:14:20.539426 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:14:20.575690 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:14:20.616819 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:14:20.619252 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:14:20.693800 20668 all.go:67] all goroutines have returned in 76.97643ms [odo] I0524 18:14:20.693954 20668 all.go:76] query result: objects=6 [odo] I0524 18:14:20.694114 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:20.694198 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:14:20.694232 20668 service.go:75] Getting list of services [odo] I0524 18:14:20.694282 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:14:20.737726 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:14:20.737769 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:20.746450 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:20.766138 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:20.781238 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:20.822986 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:14:20.823023 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:14:20.828007 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:20.832487 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:20.836831 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:14:20.853671 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:20.868688 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:20.889890 20668 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I0524 18:14:20.889924 20668 status.go:34] setting inner loop State "WaitDeployment" [odo] I0524 18:14:20.889964 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518813, generation: 1, pods: 0 [odo] ✓ Pod is Running [odo] I0524 18:14:22.032708 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518834, generation: 1, pods: 1 [odo] I0524 18:14:22.332848 20668 watch.go:414] Copying files [] to pod [odo] I0524 18:14:22.333924 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:22.333950 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:22.335356 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:22.335497 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:22.335520 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:22.345950 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:22.346371 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:22.346411 20668 components.go:69] component state: "WaitDeployment" [odo] I0524 18:14:22.364956 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:14:22.417403 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:14:22.453272 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:14:22.453379 20668 components.go:346] The component name is diseiw [odo] I0524 18:14:22.453418 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:14:22.453474 20668 components.go:351] Applying deployment [odo] I0524 18:14:22.500100 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:14:22.546909 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:14:22.580609 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:14:22.580805 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:14:22.640809 20668 all.go:67] all goroutines have returned in 60.195479ms [odo] I0524 18:14:22.640854 20668 all.go:76] query result: objects=6 [odo] I0524 18:14:22.640960 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:22.641044 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:14:22.641067 20668 service.go:75] Getting list of services [odo] I0524 18:14:22.641075 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:14:22.684512 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:14:22.684599 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:22.698120 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:22.715882 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:22.730775 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:22.744846 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:14:22.744938 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:14:22.749657 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:22.754642 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:22.760170 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:14:22.777535 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:22.803764 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] • Syncing files into the container ... [odo] I0524 18:14:22.892519 20668 libdevfile.go:187] Build command: build [odo] I0524 18:14:22.892593 20668 libdevfile.go:199] Run command: run [odo] I0524 18:14:22.893531 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376, destBase: ., destFile: . [odo] I0524 18:14:22.893575 20668 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I0524 18:14:22.895222 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.gitignore, destBase: ., destFile: .gitignore [odo] I0524 18:14:22.895302 20668 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I0524 18:14:22.895752 20668 file_indexer.go:453] file added: /tmp/1874439376/.gitignore [odo] I0524 18:14:22.895815 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.odo, destBase: ., destFile: .odo [odo] I0524 18:14:22.895843 20668 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I0524 18:14:22.896206 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/cluster.yaml, destBase: ., destFile: cluster.yaml [odo] I0524 18:14:22.896253 20668 file_indexer.go:406] Corrected destinations: base: . file: cluster.yaml [odo] I0524 18:14:22.896598 20668 file_indexer.go:453] file added: /tmp/1874439376/cluster.yaml [odo] I0524 18:14:22.896654 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I0524 18:14:22.896680 20668 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I0524 18:14:22.897037 20668 file_indexer.go:453] file added: /tmp/1874439376/devfile.yaml [odo] I0524 18:14:22.897092 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.mod, destBase: ., destFile: go.mod [odo] I0524 18:14:22.897117 20668 file_indexer.go:406] Corrected destinations: base: . file: go.mod [odo] I0524 18:14:22.897459 20668 file_indexer.go:453] file added: /tmp/1874439376/go.mod [odo] I0524 18:14:22.897512 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.sum, destBase: ., destFile: go.sum [odo] I0524 18:14:22.897643 20668 file_indexer.go:406] Corrected destinations: base: . file: go.sum [odo] I0524 18:14:22.898042 20668 file_indexer.go:453] file added: /tmp/1874439376/go.sum [odo] I0524 18:14:22.898116 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/main.go, destBase: ., destFile: main.go [odo] I0524 18:14:22.898180 20668 file_indexer.go:406] Corrected destinations: base: . file: main.go [odo] I0524 18:14:22.898553 20668 file_indexer.go:453] file added: /tmp/1874439376/main.go [odo] I0524 18:14:22.898617 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware, destBase: ., destFile: middleware [odo] I0524 18:14:22.898649 20668 file_indexer.go:406] Corrected destinations: base: . file: middleware [odo] I0524 18:14:22.898994 20668 file_indexer.go:453] file added: /tmp/1874439376/middleware [odo] I0524 18:14:22.899687 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware/handlers.go, destBase: ., destFile: middleware/handlers.go [odo] I0524 18:14:22.899737 20668 file_indexer.go:406] Corrected destinations: base: . file: middleware/handlers.go [odo] I0524 18:14:22.900242 20668 file_indexer.go:453] file added: /tmp/1874439376/middleware/handlers.go [odo] I0524 18:14:22.900316 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models, destBase: ., destFile: models [odo] I0524 18:14:22.900343 20668 file_indexer.go:406] Corrected destinations: base: . file: models [odo] I0524 18:14:22.900723 20668 file_indexer.go:453] file added: /tmp/1874439376/models [odo] I0524 18:14:22.901210 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models/models.go, destBase: ., destFile: models/models.go [odo] I0524 18:14:22.901255 20668 file_indexer.go:406] Corrected destinations: base: . file: models/models.go [odo] I0524 18:14:22.901614 20668 file_indexer.go:453] file added: /tmp/1874439376/models/models.go [odo] I0524 18:14:22.901678 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router, destBase: ., destFile: router [odo] I0524 18:14:22.901704 20668 file_indexer.go:406] Corrected destinations: base: . file: router [odo] I0524 18:14:22.902095 20668 file_indexer.go:453] file added: /tmp/1874439376/router [odo] I0524 18:14:22.902567 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router/router.go, destBase: ., destFile: router/router.go [odo] I0524 18:14:22.902613 20668 file_indexer.go:406] Corrected destinations: base: . file: router/router.go [odo] I0524 18:14:22.902965 20668 file_indexer.go:453] file added: /tmp/1874439376/router/router.go [odo] I0524 18:14:22.903398 20668 sync.go:138] List of files to be deleted: +[] [odo] I0524 18:14:22.903451 20668 sync.go:140] List of files changed: +[/tmp/1874439376/models /tmp/1874439376/go.mod /tmp/1874439376/middleware /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router /tmp/1874439376/router/router.go /tmp/1874439376/main.go /tmp/1874439376/.gitignore /tmp/1874439376/devfile.yaml /tmp/1874439376/cluster.yaml /tmp/1874439376/go.sum] [odo] I0524 18:14:22.903491 20668 sync.go:193] Push: componentName: diseiw, path: /tmp/1874439376, files: [/tmp/1874439376/models /tmp/1874439376/go.mod /tmp/1874439376/middleware /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router /tmp/1874439376/router/router.go /tmp/1874439376/main.go /tmp/1874439376/.gitignore /tmp/1874439376/devfile.yaml /tmp/1874439376/cluster.yaml /tmp/1874439376/go.sum], delFiles: [*], isForcePush: true [odo] I0524 18:14:22.904131 20668 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I0524 18:14:22.904192 20668 exec.go:33] Executing command [rm -rf /projects/*] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:23.284347 20668 sync.go:233] Copying files /tmp/1874439376/models /tmp/1874439376/go.mod /tmp/1874439376/middleware /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router /tmp/1874439376/router/router.go /tmp/1874439376/main.go /tmp/1874439376/.gitignore /tmp/1874439376/devfile.yaml /tmp/1874439376/cluster.yaml /tmp/1874439376/go.sum to pod [odo] I0524 18:14:23.284484 20668 copy.go:36] CopyFile arguments: localPath /tmp/1874439376, dest /projects/1874439376, targetPath /projects, copyFiles [/tmp/1874439376/models /tmp/1874439376/go.mod /tmp/1874439376/middleware /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router /tmp/1874439376/router/router.go /tmp/1874439376/main.go /tmp/1874439376/.gitignore /tmp/1874439376/devfile.yaml /tmp/1874439376/cluster.yaml /tmp/1874439376/go.sum], globalExps [.git .odo .odo/odo-file-index.json] [odo] I0524 18:14:23.284589 20668 copy.go:64] Executing command tar xf - -C /projects --no-same-owner [odo] I0524 18:14:23.284619 20668 copy.go:97] makeTar arguments: srcPath: /tmp/1874439376, destPath: /projects/1874439376, files: [/tmp/1874439376/models /tmp/1874439376/go.mod /tmp/1874439376/middleware /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router /tmp/1874439376/router/router.go /tmp/1874439376/main.go /tmp/1874439376/.gitignore /tmp/1874439376/devfile.yaml /tmp/1874439376/cluster.yaml /tmp/1874439376/go.sum] [odo] I0524 18:14:23.285087 20668 copy.go:128] Got abs path: /tmp/1874439376/models [odo] I0524 18:14:23.285107 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/models [odo] I0524 18:14:23.285126 20668 copy.go:145] makeTar srcFile: 1874439376/models [odo] I0524 18:14:23.285136 20668 copy.go:146] makeTar destFile: models [odo] I0524 18:14:23.285144 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models, destBase: /projects, destFile: models [odo] I0524 18:14:23.285153 20668 copy.go:172] Corrected destinations: base: /projects file: models [odo] I0524 18:14:23.285817 20668 copy.go:128] Got abs path: /tmp/1874439376/go.mod [odo] I0524 18:14:23.285837 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/go.mod [odo] I0524 18:14:23.285849 20668 copy.go:145] makeTar srcFile: 1874439376/go.mod [odo] I0524 18:14:23.285859 20668 copy.go:146] makeTar destFile: go.mod [odo] I0524 18:14:23.285867 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.mod, destBase: /projects, destFile: go.mod [odo] I0524 18:14:23.285874 20668 copy.go:172] Corrected destinations: base: /projects file: go.mod [odo] I0524 18:14:23.642784 20668 copy.go:128] Got abs path: /tmp/1874439376/middleware [odo] I0524 18:14:23.642901 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/middleware [odo] I0524 18:14:23.642983 20668 copy.go:145] makeTar srcFile: 1874439376/middleware [odo] I0524 18:14:23.643018 20668 copy.go:146] makeTar destFile: middleware [odo] I0524 18:14:23.643073 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware, destBase: /projects, destFile: middleware [odo] I0524 18:14:23.643106 20668 copy.go:172] Corrected destinations: base: /projects file: middleware [odo] I0524 18:14:23.643802 20668 copy.go:128] Got abs path: /tmp/1874439376/middleware/handlers.go [odo] I0524 18:14:23.643870 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/middleware/handlers.go [odo] I0524 18:14:23.643931 20668 copy.go:145] makeTar srcFile: 1874439376/middleware/handlers.go [odo] I0524 18:14:23.643964 20668 copy.go:146] makeTar destFile: middleware/handlers.go [odo] I0524 18:14:23.644024 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware/handlers.go, destBase: /projects, destFile: middleware/handlers.go [odo] I0524 18:14:23.644057 20668 copy.go:172] Corrected destinations: base: /projects file: middleware/handlers.go [odo] I0524 18:14:23.644899 20668 copy.go:128] Got abs path: /tmp/1874439376/models/models.go [odo] I0524 18:14:23.644928 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/models/models.go [odo] I0524 18:14:23.644946 20668 copy.go:145] makeTar srcFile: 1874439376/models/models.go [odo] I0524 18:14:23.644956 20668 copy.go:146] makeTar destFile: models/models.go [odo] I0524 18:14:23.644964 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models/models.go, destBase: /projects, destFile: models/models.go [odo] I0524 18:14:23.644972 20668 copy.go:172] Corrected destinations: base: /projects file: models/models.go [odo] I0524 18:14:23.645616 20668 copy.go:128] Got abs path: /tmp/1874439376/router [odo] I0524 18:14:23.645662 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/router [odo] I0524 18:14:23.645684 20668 copy.go:145] makeTar srcFile: 1874439376/router [odo] I0524 18:14:23.645693 20668 copy.go:146] makeTar destFile: router [odo] I0524 18:14:23.645701 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router, destBase: /projects, destFile: router [odo] I0524 18:14:23.645715 20668 copy.go:172] Corrected destinations: base: /projects file: router [odo] I0524 18:14:23.646155 20668 copy.go:128] Got abs path: /tmp/1874439376/router/router.go [odo] I0524 18:14:23.646184 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/router/router.go [odo] I0524 18:14:23.646197 20668 copy.go:145] makeTar srcFile: 1874439376/router/router.go [odo] I0524 18:14:23.646205 20668 copy.go:146] makeTar destFile: router/router.go [odo] I0524 18:14:23.646212 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router/router.go, destBase: /projects, destFile: router/router.go [odo] I0524 18:14:23.646222 20668 copy.go:172] Corrected destinations: base: /projects file: router/router.go [odo] I0524 18:14:23.646807 20668 copy.go:128] Got abs path: /tmp/1874439376/main.go [odo] I0524 18:14:23.646862 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/main.go [odo] I0524 18:14:23.646924 20668 copy.go:145] makeTar srcFile: 1874439376/main.go [odo] I0524 18:14:23.646963 20668 copy.go:146] makeTar destFile: main.go [odo] I0524 18:14:23.646989 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/main.go, destBase: /projects, destFile: main.go [odo] I0524 18:14:23.647034 20668 copy.go:172] Corrected destinations: base: /projects file: main.go [odo] I0524 18:14:23.647819 20668 copy.go:128] Got abs path: /tmp/1874439376/.gitignore [odo] I0524 18:14:23.647844 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/.gitignore [odo] I0524 18:14:23.647856 20668 copy.go:145] makeTar srcFile: 1874439376/.gitignore [odo] I0524 18:14:23.647865 20668 copy.go:146] makeTar destFile: .gitignore [odo] I0524 18:14:23.647874 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.gitignore, destBase: /projects, destFile: .gitignore [odo] I0524 18:14:23.647896 20668 copy.go:172] Corrected destinations: base: /projects file: .gitignore [odo] I0524 18:14:23.648591 20668 copy.go:128] Got abs path: /tmp/1874439376/devfile.yaml [odo] I0524 18:14:23.648656 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/devfile.yaml [odo] I0524 18:14:23.648689 20668 copy.go:145] makeTar srcFile: 1874439376/devfile.yaml [odo] I0524 18:14:23.648736 20668 copy.go:146] makeTar destFile: devfile.yaml [odo] I0524 18:14:23.648772 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I0524 18:14:23.648800 20668 copy.go:172] Corrected destinations: base: /projects file: devfile.yaml [odo] I0524 18:14:23.649511 20668 copy.go:128] Got abs path: /tmp/1874439376/cluster.yaml [odo] I0524 18:14:23.649585 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/cluster.yaml [odo] I0524 18:14:23.649661 20668 copy.go:145] makeTar srcFile: 1874439376/cluster.yaml [odo] I0524 18:14:23.649721 20668 copy.go:146] makeTar destFile: cluster.yaml [odo] I0524 18:14:23.649749 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/cluster.yaml, destBase: /projects, destFile: cluster.yaml [odo] I0524 18:14:23.649792 20668 copy.go:172] Corrected destinations: base: /projects file: cluster.yaml [odo] I0524 18:14:23.650503 20668 copy.go:128] Got abs path: /tmp/1874439376/go.sum [odo] I0524 18:14:23.650564 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/go.sum [odo] I0524 18:14:23.650595 20668 copy.go:145] makeTar srcFile: 1874439376/go.sum [odo] I0524 18:14:23.650699 20668 copy.go:146] makeTar destFile: go.sum [odo] I0524 18:14:23.650737 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.sum, destBase: /projects, destFile: go.sum [odo] I0524 18:14:23.650770 20668 copy.go:172] Corrected destinations: base: /projects file: go.sum [odo] ✓ Syncing files into the container [844ms] [odo] I0524 18:14:23.736704 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:14:23.736752 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:23.952010 20668 exec.go:82] cat: /opt/odo/.odo_cmd_run.pid: No such file or directory [odo] I0524 18:14:23.955263 20668 innerloop.go:138] running=false, execRequired=true [odo] • Building your application in container (command: build) ... [odo] I0524 18:14:23.955556 20668 exec.go:33] 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: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:26.792113 20668 kubeexec.go:54] StartProcessForCommand for "run" [odo] I0524 18:14:26.792174 20668 retry.go:48] waiting for 5 second(s) before trying task "process for command \"run\"" [odo] I0524 18:14:26.792197 20668 exec.go:33] 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: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] ✓ Building your application in container (command: build) [3s] [odo] • Executing the application (command: run) ... [odo] I0524 18:14:31.792883 20668 execute_run.go:90] checking if process for command "run" is running [odo] I0524 18:14:31.792919 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:14:31.792948 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:32.033381 20668 exec.go:82] 150 [odo] I0524 18:14:32.047381 20668 exec.go:33] Executing command [/bin/sh -c kill -0 150; echo $?] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:32.239071 20668 exec.go:82] 0 [odo] I0524 18:14:32.239921 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:14:32.240020 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:32.422356 20668 exec.go:82] 150 [odo] I0524 18:14:32.424096 20668 exec.go:33] Executing command [/bin/sh -c kill -0 150; echo $?] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:32.629896 20668 exec.go:82] 0 [odo] • Waiting for the application to be ready ... [odo] I0524 18:14:33.633808 20668 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:14:33.849933 20668 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0524 18:14:33.849976 20668 exec.go:82] 0: C97811AC:D0B2 91D43AD8:01BB 06 00000000:00000000 03:000014AD 00000000 0 0 0 3 0000000000000000 [odo] I0524 18:14:33.849991 20668 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0524 18:14:33.850144 20668 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0524 18:14:33.850180 20668 exec.go:82] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1051910000 0 398138737 1 0000000000000000 100 0 0 10 0 [odo] I0524 18:14:33.850386 20668 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0524 18:14:33.853207 20668 port.go:346] ports not listening: map[] [odo] ✓ Waiting for the application to be ready [1s] [odo] - Forwarding from 127.0.0.1:40535 -> 8080 [odo] [odo] I0524 18:14:33.935830 20668 status.go:34] setting inner loop State "Ready" [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/1874439376 [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] I0524 18:14:33.951325 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518840, generation: 1, pods: 1 [odo] I0524 18:14:34.052375 20668 watch.go:339] filesystem watch event: CREATE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:14:34.053214 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:14:34.053616 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.json" [odo] I0524 18:14:34.054167 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.20668.json" Running odo with args [odo add binding --name zylemn --service cluster-example-initdb --bind-as-files=false] and odo env: [] [odo] I0524 18:14:40.278674 20714 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:40.279368 20714 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:40.279913 20714 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:40.279991 20714 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:40.280324 20714 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:40.283973 20714 schema.go:61] validated devfile schema [odo] I0524 18:14:40.285837 20714 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:40.285880 20714 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:40.286243 20714 component.go:87] name of component is "diseiw", and sanitized name is "diseiw" [odo] I0524 18:14:40.286312 20714 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:40.402655 20714 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:40.402706 20714 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:40.403889 20714 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:40.404028 20714 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:40.404054 20714 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:40.409302 20714 schema.go:61] validated devfile schema [odo] I0524 18:14:40.409594 20714 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:40.528439 20714 kclient.go:224] Checking if "deployments" resource is supported [odo] I0524 18:14:40.536287 20714 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] I0524 18:14:40.536506 20714 implem.go:114] The path for preference file is /tmp/3309117965/preference.yaml [odo] I0524 18:14:40.537103 20714 segment.go:264] Checking telemetry enable status [odo] I0524 18:14:40.537175 20714 segment.go:282] Sending telemetry disabled by env variable [odo] I0524 18:14:40.637484 20668 watch.go:339] filesystem watch event: CREATE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:14:40.638321 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:14:40.638639 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.json" [odo] I0524 18:14:40.639079 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.20668.json" [odo] I0524 18:14:40.639524 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/devfile.yaml" [odo] I0524 18:14:40.639967 20668 file_watcher.go:60] adding watch on path /tmp/1874439376/devfile.yaml [odo] I0524 18:14:40.640072 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/devfile.yaml" [odo] I0524 18:14:40.640449 20668 file_watcher.go:60] adding watch on path /tmp/1874439376/devfile.yaml [odo] Pushing files... [odo] [odo] I0524 18:14:40.640490 20668 status.go:34] setting inner loop State "SyncOutdated" [odo] [odo] File /tmp/1874439376/devfile.yaml changed [odo] I0524 18:14:40.640669 20668 watch.go:414] Copying files [/tmp/1874439376/devfile.yaml] to pod [odo] I0524 18:14:40.640967 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:40.641006 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:40.642447 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:40.642684 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:40.642772 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:40.648520 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:40.649221 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:40.649332 20668 components.go:69] component state: "SyncOutdated" [odo] • Waiting for Kubernetes resources ... [odo] I0524 18:14:40.679303 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:14:40.745390 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:14:40.780442 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:14:40.780634 20668 components.go:346] The component name is diseiw [odo] I0524 18:14:40.780857 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:14:40.780925 20668 components.go:351] Applying deployment [odo] I0524 18:14:40.870007 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:14:40.911989 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:14:40.945835 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:14:40.948360 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:14:41.087753 20668 all.go:67] all goroutines have returned in 141.925487ms [odo] I0524 18:14:41.087947 20668 all.go:76] query result: objects=6 [odo] I0524 18:14:41.089367 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:41.199273 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:14:41.199702 20668 service.go:75] Getting list of services [odo] I0524 18:14:41.199727 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:14:41.269650 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:14:41.269792 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:41.278955 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:41.296667 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:41.307541 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:41.320370 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:14:41.320403 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:14:41.326964 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:41.332563 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:41.337034 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:14:41.358587 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:41.372981 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:41.387457 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] • Creating resource ServiceBinding/zylemn ... [odo] ✓ Creating resource ServiceBinding/zylemn [odo] I0524 18:14:41.583054 20668 components.go:152] Waiting for all service bindings to be injected... [odo] I0524 18:14:41.583157 20668 watch.go:428] 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/1874439376 [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] Updating Component... [odo] [odo] I0524 18:14:41.583467 20668 watch.go:414] Copying files [] to pod [odo] I0524 18:14:41.584542 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:41.584604 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:41.587229 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:41.587649 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:41.587727 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:41.600316 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:41.600653 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:41.600707 20668 components.go:69] component state: "SyncOutdated" [odo] • Waiting for Kubernetes resources ... [odo] I0524 18:14:41.613595 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:14:41.663247 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:14:41.702234 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:14:41.702268 20668 components.go:346] The component name is diseiw [odo] I0524 18:14:41.702284 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:14:41.702299 20668 components.go:351] Applying deployment [odo] I0524 18:14:41.764313 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:14:41.802554 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:14:41.828697 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:14:41.828859 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:14:41.925160 20668 all.go:67] all goroutines have returned in 96.460188ms [odo] I0524 18:14:41.925195 20668 all.go:76] query result: objects=7 [odo] I0524 18:14:41.926126 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:41.926997 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:14:41.927030 20668 service.go:75] Getting list of services [odo] I0524 18:14:41.927070 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:14:41.965724 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:14:41.965774 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:41.973401 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:42.016153 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:42.032868 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:42.125439 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:14:42.125504 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:14:42.129858 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:42.134256 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:42.139396 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:14:42.158319 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:42.175050 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:42.189163 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] Error occurred on Push - watch command was unable to push component: unable to get pod for component diseiw: pod not found for the selector: component=diseiw [odo] [odo] I0524 18:14:42.398056 20668 watch.go:428] Error from Push: watch command was unable to push component: unable to get pod for component diseiw: pod not found for the selector: component=diseiw [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/1874439376 [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] I0524 18:14:42.399230 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30518993, generation: 1, pods: 1 [odo] ⚠ Pod is Terminating [odo] I0524 18:14:42.399631 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519000, generation: 1, pods: 1 [odo] I0524 18:14:42.400771 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519002, generation: 2, pods: 1 [odo] I0524 18:14:42.401805 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519006, generation: 2, pods: 1 [odo] I0524 18:14:42.402736 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519014, generation: 2, pods: 0 [odo] I0524 18:14:42.703588 20668 watch.go:414] Copying files [] to pod [odo] I0524 18:14:42.704656 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:42.704714 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:14:42.706137 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:14:42.706361 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:14:42.706409 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:14:42.714378 20668 schema.go:61] validated devfile schema [odo] I0524 18:14:42.714917 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:14:42.714989 20668 components.go:69] component state: "SyncOutdated" [odo] • Waiting for Kubernetes resources ... [odo] I0524 18:14:42.743467 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:14:42.808121 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:14:42.848357 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:14:42.848391 20668 components.go:346] The component name is diseiw [odo] I0524 18:14:42.848407 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:14:42.848423 20668 components.go:351] Applying deployment [odo] I0524 18:14:42.894020 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:14:42.984216 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:14:43.017580 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:14:43.017771 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:14:43.092652 20668 all.go:67] all goroutines have returned in 75.042214ms [odo] I0524 18:14:43.092786 20668 all.go:76] query result: objects=7 [odo] I0524 18:14:43.094236 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:43.095500 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:14:43.095614 20668 service.go:75] Getting list of services [odo] I0524 18:14:43.095648 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:14:43.132984 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:14:43.133026 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:43.141847 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:43.160108 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:43.199159 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:14:43.231586 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:14:43.231726 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:14:43.236174 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:43.243726 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:14:43.247321 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:14:43.268593 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:43.290558 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:14:43.314558 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:14:43.394156 20668 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I0524 18:14:43.394193 20668 status.go:34] setting inner loop State "WaitDeployment" [odo] I0524 18:14:43.394239 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519021, generation: 2, pods: 0 [odo] I0524 18:15:12.134291 20668 exec.go:49] 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] I0524 18:15:12.134342 20668 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] I0524 18:15:12.134353 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:15:12.134373 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-8759dbd59-xqd8t in container: runtime [odo] I0524 18:15:12.306938 20668 exec.go:49] 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] I0524 18:15:12.307027 20668 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] ✗ Finished executing the application (command: run) [46s] [odo] ⚠ No pod exists [odo] I0524 18:15:13.354970 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519369, generation: 2, pods: 0 [odo] ⚠ Pod is Pending [odo] I0524 18:15:13.467764 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519376, generation: 2, pods: 0 [odo] I0524 18:15:13.482944 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519380, generation: 2, pods: 0 [odo] ⚠ MountVolume.SetUp failed for volume "kube-api-access-x42cv" : failed to sync configmap cache: timed out waiting for the condition [odo] ✓ Pod is Running [odo] I0524 18:15:18.298991 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519455, generation: 2, pods: 1 [odo] I0524 18:15:18.599101 20668 watch.go:414] Copying files [] to pod [odo] I0524 18:15:18.600220 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:15:18.600248 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:15:18.601508 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:15:18.601685 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:15:18.601711 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:15:18.607270 20668 schema.go:61] validated devfile schema [odo] I0524 18:15:18.607704 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:15:18.607745 20668 components.go:69] component state: "WaitDeployment" [odo] I0524 18:15:18.627668 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] I0524 18:15:18.710873 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:15:18.747489 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:15:18.747586 20668 components.go:346] The component name is diseiw [odo] I0524 18:15:18.747621 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:15:18.747675 20668 components.go:351] Applying deployment [odo] I0524 18:15:18.788619 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:15:18.858913 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:15:18.898447 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:15:18.898831 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:15:18.990851 20668 all.go:67] all goroutines have returned in 92.840115ms [odo] I0524 18:15:18.990871 20668 all.go:76] query result: objects=8 [odo] I0524 18:15:18.991507 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:15:18.992149 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:15:18.992273 20668 service.go:75] Getting list of services [odo] I0524 18:15:18.992287 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:15:19.061117 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:15:19.061174 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:19.092693 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:19.178100 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:19.195420 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:19.238857 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:15:19.238875 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:15:19.252626 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:15:19.256294 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:15:19.259508 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:15:19.322165 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:15:19.344915 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:15:19.359405 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] • Syncing files into the container ... [odo] I0524 18:15:19.570558 20668 libdevfile.go:187] Build command: build [odo] I0524 18:15:19.570574 20668 libdevfile.go:199] Run command: run [odo] I0524 18:15:19.571063 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376, destBase: ., destFile: . [odo] I0524 18:15:19.571084 20668 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I0524 18:15:19.571931 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.gitignore, destBase: ., destFile: .gitignore [odo] I0524 18:15:19.571941 20668 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I0524 18:15:19.572233 20668 file_indexer.go:453] file added: /tmp/1874439376/.gitignore [odo] I0524 18:15:19.572254 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.odo, destBase: ., destFile: .odo [odo] I0524 18:15:19.572257 20668 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I0524 18:15:19.572394 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/cluster.yaml, destBase: ., destFile: cluster.yaml [odo] I0524 18:15:19.572397 20668 file_indexer.go:406] Corrected destinations: base: . file: cluster.yaml [odo] I0524 18:15:19.572517 20668 file_indexer.go:453] file added: /tmp/1874439376/cluster.yaml [odo] I0524 18:15:19.572525 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I0524 18:15:19.572529 20668 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I0524 18:15:19.572642 20668 file_indexer.go:453] file added: /tmp/1874439376/devfile.yaml [odo] I0524 18:15:19.572648 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.mod, destBase: ., destFile: go.mod [odo] I0524 18:15:19.572651 20668 file_indexer.go:406] Corrected destinations: base: . file: go.mod [odo] I0524 18:15:19.572801 20668 file_indexer.go:453] file added: /tmp/1874439376/go.mod [odo] I0524 18:15:19.572810 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.sum, destBase: ., destFile: go.sum [odo] I0524 18:15:19.572812 20668 file_indexer.go:406] Corrected destinations: base: . file: go.sum [odo] I0524 18:15:19.572925 20668 file_indexer.go:453] file added: /tmp/1874439376/go.sum [odo] I0524 18:15:19.572931 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/main.go, destBase: ., destFile: main.go [odo] I0524 18:15:19.572934 20668 file_indexer.go:406] Corrected destinations: base: . file: main.go [odo] I0524 18:15:19.573158 20668 file_indexer.go:453] file added: /tmp/1874439376/main.go [odo] I0524 18:15:19.573169 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware, destBase: ., destFile: middleware [odo] I0524 18:15:19.573171 20668 file_indexer.go:406] Corrected destinations: base: . file: middleware [odo] I0524 18:15:19.573408 20668 file_indexer.go:453] file added: /tmp/1874439376/middleware [odo] I0524 18:15:19.573776 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware/handlers.go, destBase: ., destFile: middleware/handlers.go [odo] I0524 18:15:19.573807 20668 file_indexer.go:406] Corrected destinations: base: . file: middleware/handlers.go [odo] I0524 18:15:19.574044 20668 file_indexer.go:453] file added: /tmp/1874439376/middleware/handlers.go [odo] I0524 18:15:19.574077 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models, destBase: ., destFile: models [odo] I0524 18:15:19.574088 20668 file_indexer.go:406] Corrected destinations: base: . file: models [odo] I0524 18:15:19.574297 20668 file_indexer.go:453] file added: /tmp/1874439376/models [odo] I0524 18:15:19.574610 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models/models.go, destBase: ., destFile: models/models.go [odo] I0524 18:15:19.574647 20668 file_indexer.go:406] Corrected destinations: base: . file: models/models.go [odo] I0524 18:15:19.574831 20668 file_indexer.go:453] file added: /tmp/1874439376/models/models.go [odo] I0524 18:15:19.574865 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router, destBase: ., destFile: router [odo] I0524 18:15:19.574879 20668 file_indexer.go:406] Corrected destinations: base: . file: router [odo] I0524 18:15:19.575054 20668 file_indexer.go:453] file added: /tmp/1874439376/router [odo] I0524 18:15:19.575370 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router/router.go, destBase: ., destFile: router/router.go [odo] I0524 18:15:19.575413 20668 file_indexer.go:406] Corrected destinations: base: . file: router/router.go [odo] I0524 18:15:19.575627 20668 file_indexer.go:453] file added: /tmp/1874439376/router/router.go [odo] I0524 18:15:19.575906 20668 sync.go:138] List of files to be deleted: +[] [odo] I0524 18:15:19.575936 20668 sync.go:140] List of files changed: +[/tmp/1874439376/models /tmp/1874439376/router /tmp/1874439376/devfile.yaml /tmp/1874439376/go.sum /tmp/1874439376/cluster.yaml /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router/router.go /tmp/1874439376/go.mod /tmp/1874439376/.gitignore /tmp/1874439376/main.go /tmp/1874439376/middleware] [odo] I0524 18:15:19.575960 20668 sync.go:193] Push: componentName: diseiw, path: /tmp/1874439376, files: [/tmp/1874439376/models /tmp/1874439376/router /tmp/1874439376/devfile.yaml /tmp/1874439376/go.sum /tmp/1874439376/cluster.yaml /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router/router.go /tmp/1874439376/go.mod /tmp/1874439376/.gitignore /tmp/1874439376/main.go /tmp/1874439376/middleware], delFiles: [*], isForcePush: true [odo] I0524 18:15:19.576323 20668 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I0524 18:15:19.576397 20668 exec.go:33] Executing command [rm -rf /projects/*] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:19.775223 20668 sync.go:233] Copying files /tmp/1874439376/models /tmp/1874439376/router /tmp/1874439376/devfile.yaml /tmp/1874439376/go.sum /tmp/1874439376/cluster.yaml /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router/router.go /tmp/1874439376/go.mod /tmp/1874439376/.gitignore /tmp/1874439376/main.go /tmp/1874439376/middleware to pod [odo] I0524 18:15:19.775259 20668 copy.go:36] CopyFile arguments: localPath /tmp/1874439376, dest /projects/1874439376, targetPath /projects, copyFiles [/tmp/1874439376/models /tmp/1874439376/router /tmp/1874439376/devfile.yaml /tmp/1874439376/go.sum /tmp/1874439376/cluster.yaml /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router/router.go /tmp/1874439376/go.mod /tmp/1874439376/.gitignore /tmp/1874439376/main.go /tmp/1874439376/middleware], globalExps [.git .odo .odo/odo-file-index.json] [odo] I0524 18:15:19.775317 20668 copy.go:64] Executing command tar xf - -C /projects --no-same-owner [odo] I0524 18:15:19.776035 20668 copy.go:97] makeTar arguments: srcPath: /tmp/1874439376, destPath: /projects/1874439376, files: [/tmp/1874439376/models /tmp/1874439376/router /tmp/1874439376/devfile.yaml /tmp/1874439376/go.sum /tmp/1874439376/cluster.yaml /tmp/1874439376/middleware/handlers.go /tmp/1874439376/models/models.go /tmp/1874439376/router/router.go /tmp/1874439376/go.mod /tmp/1874439376/.gitignore /tmp/1874439376/main.go /tmp/1874439376/middleware] [odo] I0524 18:15:19.776900 20668 copy.go:128] Got abs path: /tmp/1874439376/models [odo] I0524 18:15:19.776921 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/models [odo] I0524 18:15:19.776935 20668 copy.go:145] makeTar srcFile: 1874439376/models [odo] I0524 18:15:19.776944 20668 copy.go:146] makeTar destFile: models [odo] I0524 18:15:19.776952 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models, destBase: /projects, destFile: models [odo] I0524 18:15:19.776961 20668 copy.go:172] Corrected destinations: base: /projects file: models [odo] I0524 18:15:19.777585 20668 copy.go:128] Got abs path: /tmp/1874439376/router [odo] I0524 18:15:19.777603 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/router [odo] I0524 18:15:19.777615 20668 copy.go:145] makeTar srcFile: 1874439376/router [odo] I0524 18:15:19.777623 20668 copy.go:146] makeTar destFile: router [odo] I0524 18:15:19.777630 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router, destBase: /projects, destFile: router [odo] I0524 18:15:19.777637 20668 copy.go:172] Corrected destinations: base: /projects file: router [odo] I0524 18:15:19.778016 20668 copy.go:128] Got abs path: /tmp/1874439376/devfile.yaml [odo] I0524 18:15:19.778031 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/devfile.yaml [odo] I0524 18:15:19.778042 20668 copy.go:145] makeTar srcFile: 1874439376/devfile.yaml [odo] I0524 18:15:19.778050 20668 copy.go:146] makeTar destFile: devfile.yaml [odo] I0524 18:15:19.778057 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I0524 18:15:19.778064 20668 copy.go:172] Corrected destinations: base: /projects file: devfile.yaml [odo] I0524 18:15:19.956845 20668 copy.go:128] Got abs path: /tmp/1874439376/go.sum [odo] I0524 18:15:19.956877 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/go.sum [odo] I0524 18:15:19.956890 20668 copy.go:145] makeTar srcFile: 1874439376/go.sum [odo] I0524 18:15:19.956902 20668 copy.go:146] makeTar destFile: go.sum [odo] I0524 18:15:19.956909 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.sum, destBase: /projects, destFile: go.sum [odo] I0524 18:15:19.956915 20668 copy.go:172] Corrected destinations: base: /projects file: go.sum [odo] I0524 18:15:19.957660 20668 copy.go:128] Got abs path: /tmp/1874439376/cluster.yaml [odo] I0524 18:15:19.957685 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/cluster.yaml [odo] I0524 18:15:19.957705 20668 copy.go:145] makeTar srcFile: 1874439376/cluster.yaml [odo] I0524 18:15:19.957721 20668 copy.go:146] makeTar destFile: cluster.yaml [odo] I0524 18:15:19.957729 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/cluster.yaml, destBase: /projects, destFile: cluster.yaml [odo] I0524 18:15:19.957743 20668 copy.go:172] Corrected destinations: base: /projects file: cluster.yaml [odo] I0524 18:15:19.958369 20668 copy.go:128] Got abs path: /tmp/1874439376/middleware/handlers.go [odo] I0524 18:15:19.958388 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/middleware/handlers.go [odo] I0524 18:15:19.958407 20668 copy.go:145] makeTar srcFile: 1874439376/middleware/handlers.go [odo] I0524 18:15:19.958422 20668 copy.go:146] makeTar destFile: middleware/handlers.go [odo] I0524 18:15:19.958430 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware/handlers.go, destBase: /projects, destFile: middleware/handlers.go [odo] I0524 18:15:19.958444 20668 copy.go:172] Corrected destinations: base: /projects file: middleware/handlers.go [odo] I0524 18:15:19.959028 20668 copy.go:128] Got abs path: /tmp/1874439376/models/models.go [odo] I0524 18:15:19.959064 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/models/models.go [odo] I0524 18:15:19.959076 20668 copy.go:145] makeTar srcFile: 1874439376/models/models.go [odo] I0524 18:15:19.959084 20668 copy.go:146] makeTar destFile: models/models.go [odo] I0524 18:15:19.959091 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models/models.go, destBase: /projects, destFile: models/models.go [odo] I0524 18:15:19.959099 20668 copy.go:172] Corrected destinations: base: /projects file: models/models.go [odo] I0524 18:15:19.960314 20668 copy.go:128] Got abs path: /tmp/1874439376/router/router.go [odo] I0524 18:15:19.960350 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/router/router.go [odo] I0524 18:15:19.960372 20668 copy.go:145] makeTar srcFile: 1874439376/router/router.go [odo] I0524 18:15:19.960382 20668 copy.go:146] makeTar destFile: router/router.go [odo] I0524 18:15:19.960397 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router/router.go, destBase: /projects, destFile: router/router.go [odo] I0524 18:15:19.960406 20668 copy.go:172] Corrected destinations: base: /projects file: router/router.go [odo] I0524 18:15:19.961221 20668 copy.go:128] Got abs path: /tmp/1874439376/go.mod [odo] I0524 18:15:19.961280 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/go.mod [odo] I0524 18:15:19.961301 20668 copy.go:145] makeTar srcFile: 1874439376/go.mod [odo] I0524 18:15:19.961310 20668 copy.go:146] makeTar destFile: go.mod [odo] I0524 18:15:19.961324 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.mod, destBase: /projects, destFile: go.mod [odo] I0524 18:15:19.961377 20668 copy.go:172] Corrected destinations: base: /projects file: go.mod [odo] I0524 18:15:19.962225 20668 copy.go:128] Got abs path: /tmp/1874439376/.gitignore [odo] I0524 18:15:19.962297 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/.gitignore [odo] I0524 18:15:19.962316 20668 copy.go:145] makeTar srcFile: 1874439376/.gitignore [odo] I0524 18:15:19.962325 20668 copy.go:146] makeTar destFile: .gitignore [odo] I0524 18:15:19.962340 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.gitignore, destBase: /projects, destFile: .gitignore [odo] I0524 18:15:19.962379 20668 copy.go:172] Corrected destinations: base: /projects file: .gitignore [odo] I0524 18:15:19.962997 20668 copy.go:128] Got abs path: /tmp/1874439376/main.go [odo] I0524 18:15:19.963015 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/main.go [odo] I0524 18:15:19.963027 20668 copy.go:145] makeTar srcFile: 1874439376/main.go [odo] I0524 18:15:19.963035 20668 copy.go:146] makeTar destFile: main.go [odo] I0524 18:15:19.963045 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/main.go, destBase: /projects, destFile: main.go [odo] I0524 18:15:19.963052 20668 copy.go:172] Corrected destinations: base: /projects file: main.go [odo] I0524 18:15:19.963698 20668 copy.go:128] Got abs path: /tmp/1874439376/middleware [odo] I0524 18:15:19.963732 20668 copy.go:129] Making /tmp/1874439376 relative to /tmp/1874439376/middleware [odo] I0524 18:15:19.963750 20668 copy.go:145] makeTar srcFile: 1874439376/middleware [odo] I0524 18:15:19.963759 20668 copy.go:146] makeTar destFile: middleware [odo] I0524 18:15:19.963773 20668 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware, destBase: /projects, destFile: middleware [odo] I0524 18:15:19.963781 20668 copy.go:172] Corrected destinations: base: /projects file: middleware [odo] I0524 18:15:20.036661 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:15:20.036689 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] ✓ Syncing files into the container [466ms] [odo] I0524 18:15:20.305497 20668 exec.go:82] cat: /opt/odo/.odo_cmd_run.pid: No such file or directory [odo] I0524 18:15:20.306194 20668 innerloop.go:138] running=false, execRequired=true [odo] • Building your application in container (command: build) ... [odo] I0524 18:15:20.306302 20668 exec.go:33] 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: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] ✓ Building your application in container (command: build) [2s] [odo] • Executing the application (command: run) ... [odo] I0524 18:15:22.548279 20668 kubeexec.go:54] StartProcessForCommand for "run" [odo] I0524 18:15:22.548362 20668 retry.go:48] waiting for 5 second(s) before trying task "process for command \"run\"" [odo] I0524 18:15:22.548401 20668 exec.go:33] 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: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:27.549208 20668 execute_run.go:90] checking if process for command "run" is running [odo] I0524 18:15:27.549302 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:15:27.549346 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:27.764857 20668 exec.go:82] 154 [odo] I0524 18:15:27.769346 20668 exec.go:33] Executing command [/bin/sh -c kill -0 154; echo $?] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:27.946402 20668 exec.go:82] 0 [odo] I0524 18:15:27.949403 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:15:27.949443 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:28.144170 20668 exec.go:82] 154 [odo] I0524 18:15:28.146908 20668 exec.go:33] Executing command [/bin/sh -c kill -0 154; echo $?] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:28.335297 20668 exec.go:82] 0 [odo] • Waiting for the application to be ready ... [odo] I0524 18:15:29.338623 20668 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:29.545451 20668 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0524 18:15:29.545482 20668 exec.go:82] 0: D87811AC:E1C8 91D43AD8:01BB 06 00000000:00000000 03:000014B4 00000000 0 0 0 3 0000000000000000 [odo] I0524 18:15:29.545736 20668 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0524 18:15:29.545999 20668 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0524 18:15:29.546018 20668 exec.go:82] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1051910000 0 398157022 1 0000000000000000 100 0 0 10 0 [odo] I0524 18:15:29.546221 20668 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0524 18:15:29.554899 20668 port.go:346] ports not listening: map[] [odo] ✓ Waiting for the application to be ready [1s] [odo] - Forwarding from 127.0.0.1:36213 -> 8080 [odo] [odo] I0524 18:15:29.704330 20668 status.go:34] setting inner loop State "Ready" [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/1874439376 [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] I0524 18:15:29.734435 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519464, generation: 2, pods: 1 [odo] I0524 18:15:29.835935 20668 watch.go:339] filesystem watch event: CREATE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:15:29.836659 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:15:29.837020 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.json" [odo] I0524 18:15:29.837520 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.20668.json" [odo] I0524 18:15:29.837910 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/devfile.yaml" [odo] I0524 18:15:29.838292 20668 file_watcher.go:60] adding watch on path /tmp/1874439376/devfile.yaml [odo] I0524 18:15:29.838348 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/devfile.yaml" [odo] I0524 18:15:29.838630 20668 file_watcher.go:60] adding watch on path /tmp/1874439376/devfile.yaml [odo] I0524 18:15:29.838654 20668 watch.go:339] filesystem watch event: REMOVE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:15:29.838687 20668 watch.go:374] error removing watch for /tmp/1874439376/.odo/odo-file-index.json: can't remove non-existent watcher: /tmp/1874439376/.odo/odo-file-index.json [odo] I0524 18:15:29.838704 20668 watch.go:339] filesystem watch event: CREATE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:15:29.839058 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/odo-file-index.json" [odo] I0524 18:15:29.839396 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.json" [odo] I0524 18:15:29.839739 20668 watch.go:339] filesystem watch event: WRITE "/tmp/1874439376/.odo/devstate.20668.json" [odo] Pushing files... [odo] [odo] [odo] File /tmp/1874439376/devfile.yaml changed [odo] I0524 18:15:29.840038 20668 status.go:34] setting inner loop State "SyncOutdated" [odo] I0524 18:15:29.840134 20668 watch.go:414] Copying files [/tmp/1874439376/devfile.yaml] to pod [odo] I0524 18:15:29.840695 20668 context.go:172] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:15:29.840754 20668 context.go:113] absolute devfile path: '/tmp/1874439376/devfile.yaml' [odo] I0524 18:15:29.841905 20668 content.go:48] converted devfile YAML to JSON [odo] I0524 18:15:29.842116 20668 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I0524 18:15:29.842199 20668 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I0524 18:15:29.848316 20668 schema.go:61] validated devfile schema [odo] I0524 18:15:29.848883 20668 validate.go:45] Successfully validated devfile sections [odo] I0524 18:15:29.848991 20668 components.go:69] component state: "SyncOutdated" [odo] I0524 18:15:29.866225 20668 components.go:259] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Go] [odo] • Waiting for Kubernetes resources ... [odo] I0524 18:15:29.997469 20668 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0524 18:15:30.059396 20668 components.go:345] Creating deployment diseiw-app [odo] I0524 18:15:30.059498 20668 components.go:346] The component name is diseiw [odo] I0524 18:15:30.059543 20668 components.go:349] The component already exists, attempting to update it [odo] I0524 18:15:30.059588 20668 components.go:351] Applying deployment [odo] I0524 18:15:30.200451 20668 components.go:360] Successfully updated component diseiw [odo] I0524 18:15:30.252584 20668 components.go:747] Successfully update Service for component diseiw [odo] I0524 18:15:30.288442 20668 all.go:46] starting to concurrently query 200 APIs [odo] I0524 18:15:30.292169 20668 all.go:62] fired up all goroutines to query APIs [odo] I0524 18:15:30.342339 20668 all.go:67] all goroutines have returned in 53.90049ms [odo] I0524 18:15:30.342395 20668 all.go:76] query result: objects=8 [odo] I0524 18:15:30.343448 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0524 18:15:30.344396 20668 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0524 18:15:30.344585 20668 service.go:75] Getting list of services [odo] I0524 18:15:30.344601 20668 operators.go:30] Fetching list of operators installed in cluster [odo] I0524 18:15:30.408866 20668 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0524 18:15:30.408956 20668 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:30.430163 20668 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:30.453145 20668 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:30.465664 20668 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0524 18:15:30.478486 20668 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0524 18:15:30.478565 20668 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0524 18:15:30.481978 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:15:30.485587 20668 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0524 18:15:30.490467 20668 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0524 18:15:30.506591 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0524 18:15:30.532296 20668 service.go:124] Getting instances of: servicebindings.servicebinding.io Running oc with args [oc get pods --namespace e2e-test462cwi --selector=component=diseiw -o jsonpath={.items[*].metadata.name}] and odo env: [] [odo] I0524 18:15:30.569040 20668 kclient.go:224] Checking if "servicebindings" resource is supported [odo] • Syncing files into the container ... [odo] I0524 18:15:30.818083 20668 libdevfile.go:187] Build command: build [odo] I0524 18:15:30.818103 20668 libdevfile.go:199] Run command: run [odo] I0524 18:15:30.818469 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376, destBase: ., destFile: . [odo] I0524 18:15:30.818478 20668 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I0524 18:15:30.819115 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.gitignore, destBase: ., destFile: .gitignore [odo] I0524 18:15:30.819126 20668 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I0524 18:15:30.819313 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/.odo, destBase: ., destFile: .odo [odo] I0524 18:15:30.819320 20668 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I0524 18:15:30.819453 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/cluster.yaml, destBase: ., destFile: cluster.yaml [odo] I0524 18:15:30.819459 20668 file_indexer.go:406] Corrected destinations: base: . file: cluster.yaml [odo] I0524 18:15:30.819581 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I0524 18:15:30.819589 20668 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I0524 18:15:30.819714 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.mod, destBase: ., destFile: go.mod [odo] I0524 18:15:30.819720 20668 file_indexer.go:406] Corrected destinations: base: . file: go.mod [odo] I0524 18:15:30.819840 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/go.sum, destBase: ., destFile: go.sum [odo] I0524 18:15:30.819847 20668 file_indexer.go:406] Corrected destinations: base: . file: go.sum [odo] I0524 18:15:30.820027 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/main.go, destBase: ., destFile: main.go [odo] I0524 18:15:30.820035 20668 file_indexer.go:406] Corrected destinations: base: . file: main.go [odo] I0524 18:15:30.820181 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware, destBase: ., destFile: middleware [odo] I0524 18:15:30.820188 20668 file_indexer.go:406] Corrected destinations: base: . file: middleware [odo] I0524 18:15:30.820535 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/middleware/handlers.go, destBase: ., destFile: middleware/handlers.go [odo] I0524 18:15:30.820543 20668 file_indexer.go:406] Corrected destinations: base: . file: middleware/handlers.go [odo] I0524 18:15:30.820685 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models, destBase: ., destFile: models [odo] I0524 18:15:30.820693 20668 file_indexer.go:406] Corrected destinations: base: . file: models [odo] I0524 18:15:30.821001 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/models/models.go, destBase: ., destFile: models/models.go [odo] I0524 18:15:30.821014 20668 file_indexer.go:406] Corrected destinations: base: . file: models/models.go [odo] I0524 18:15:30.821171 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router, destBase: ., destFile: router [odo] I0524 18:15:30.821177 20668 file_indexer.go:406] Corrected destinations: base: . file: router [odo] I0524 18:15:30.821498 20668 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1874439376/router/router.go, destBase: ., destFile: router/router.go [odo] I0524 18:15:30.821506 20668 file_indexer.go:406] Corrected destinations: base: . file: router/router.go [odo] I0524 18:15:30.821757 20668 sync.go:138] List of files to be deleted: +[] [odo] I0524 18:15:30.821769 20668 sync.go:140] List of files changed: +[] [odo] ✓ Syncing files into the container [3ms] [odo] I0524 18:15:30.821797 20668 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:15:30.821812 20668 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [oc] diseiw-app-5f4bddcbdf-jndhrRunning oc with args [oc exec diseiw-app-5f4bddcbdf-jndhr --namespace e2e-test462cwi -c runtime -- curl http://127.0.0.1:8080/ping] and odo env: [] [odo] I0524 18:15:31.115687 20668 exec.go:82] 154 [odo] I0524 18:15:31.119742 20668 exec.go:33] Executing command [/bin/sh -c kill -0 154; echo $?] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:31.337154 20668 exec.go:82] 0 [odo] I0524 18:15:31.338879 20668 innerloop.go:138] running=true, execRequired=false [odo] • Waiting for the application to be ready ... [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 1000 0 --:--:-- --:--:-- --:--:-- 1000 [oc] pong[odo] I0524 18:15:32.339580 20668 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: diseiw-app-5f4bddcbdf-jndhr in container: runtime [odo] I0524 18:15:32.654468 20668 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0524 18:15:32.654650 20668 exec.go:82] 0: 0100007F:CEE8 0100007F:1F90 06 00000000:00000000 03:000016FC 00000000 0 0 0 3 0000000000000000 [odo] I0524 18:15:32.654699 20668 exec.go:82] 1: D87811AC:E1C8 91D43AD8:01BB 06 00000000:00000000 03:0000137D 00000000 0 0 0 3 0000000000000000 [odo] I0524 18:15:32.654727 20668 exec.go:82] 2: D87811AC:C20E 21C915AC:1538 02 00000001:00000000 01:00000000 00000000 1051910000 0 398157638 2 0000000000000000 100 0 0 10 -1 [odo] I0524 18:15:32.655087 20668 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0524 18:15:32.656386 20668 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0524 18:15:32.656459 20668 exec.go:82] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1051910000 0 398157022 1 0000000000000000 100 0 0 10 0 [odo] I0524 18:15:32.656533 20668 exec.go:82] 1: 00000000000000000000000001000000:1F90 00000000000000000000000001000000:8E48 01 00000000:00000000 02:0000056E 00000000 1051910000 0 398157636 2 0000000000000000 20 4 32 10 -1 [odo] I0524 18:15:32.656578 20668 exec.go:82] 2: 00000000000000000000000001000000:8E48 00000000000000000000000001000000:1F90 01 00000000:00000000 02:0000056E 00000000 0 0 398160002 2 0000000000000000 20 0 0 10 -1 [odo] I0524 18:15:32.656999 20668 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0524 18:15:32.658042 20668 port.go:346] ports not listening: map[] [odo] ✓ Waiting for the application to be ready [1s] [odo] I0524 18:15:32.658381 20668 status.go:34] setting inner loop State "Ready" [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/1874439376 [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] I0524 18:15:32.658562 20668 watch.go:263] deployment watcher Event: Type: MODIFIED, name: diseiw-app, rv: 30519662, generation: 2, pods: 1 [FAILED] in [It] - /go/odo_1/tests/e2escenarios/e2e_test.go:431 @ 05/24/23 18:15:33.665 Deleting project: e2e-test462cwi Running oc with args [oc delete project e2e-test462cwi --wait=false] and odo env: [] [oc] project.project.openshift.io "e2e-test462cwi" deleted Setting current dir to: /go/odo_1/tests/e2escenarios Deleting dir: /tmp/1874439376 Deleting dir: /tmp/3309117965 << Timeline [FAILED] Expected <*url.Error | 0xc0001614d0>: { Op: "Post", URL: "http://127.0.0.1:36213/api/newuser", Err: <*errors.errorString | 0xc000072130>{s: "EOF"}, } to be nil In [It] at: /go/odo_1/tests/e2escenarios/e2e_test.go:431 @ 05/24/23 18:15:33.665 ------------------------------ • [105.898 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/3299176275 << Captured StdOut/StdErr Output ------------------------------ Progress Report for Ginkgo Process #3 Automatically polling progress: E2E Test starting with non-empty Directory should verify developer workflow from non-empty Directory (Spec Runtime: 2m2.166s) /go/odo_1/tests/e2escenarios/e2e_test.go:197 In [It] (Node Runtime: 2m0.001s) /go/odo_1/tests/e2escenarios/e2e_test.go:197 Begin Captured GinkgoWriter Output >> ... [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/2340288260 [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] I0524 18:16:10.670511 20596 watch.go:263] deployment watcher Event: Type: MODIFIED, name: azwtbn-app, rv: 30519985, generation: 1, pods: 1 [odo] I0524 18:16:10.771648 20596 watch.go:339] filesystem watch event: WRITE "/tmp/2340288260/.odo/odo-file-index.json" [odo] I0524 18:16:10.772920 20596 watch.go:339] filesystem watch event: WRITE "/tmp/2340288260/.odo/odo-file-index.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 70 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000713500, {0x2f01730?, 0xc00069adb0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000713500, {0x2f01730, 0xc00069adb0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2b29481, 0x8}, 0xc00013ed80?, 0xc0001800c0?, 0xc000572bd0) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.DevSession.WaitSync({0xc000572bd0, 0x0, 0xc0007680c0, {0x0, 0x0}}) /go/odo_1/tests/helper/helper_dev.go:233 > 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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ Progress Report for Ginkgo Process #2 Automatically polling progress: E2E Test starting with empty Directory should verify developer workflow from empty Directory (Spec Runtime: 2m2.511s) /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] I0524 18:16:12.397909 20880 watch.go:263] deployment watcher Event: Type: MODIFIED, name: ikrvsl-app, rv: 30520166, generation: 1, pods: 1 [odo] I0524 18:16:12.503116 20880 watch.go:339] filesystem watch event: REMOVE "/tmp/1237407533/.odo/odo-file-index.json" [odo] I0524 18:16:12.503587 20880 watch.go:374] error removing watch for /tmp/1237407533/.odo/odo-file-index.json: can't remove non-existent watcher: /tmp/1237407533/.odo/odo-file-index.json [odo] I0524 18:16:12.503615 20880 watch.go:339] filesystem watch event: CREATE "/tmp/1237407533/.odo/odo-file-index.json" [odo] I0524 18:16:12.514204 20880 watch.go:339] filesystem watch event: WRITE "/tmp/1237407533/.odo/odo-file-index.json" [odo] I0524 18:16:12.524143 20880 watch.go:339] filesystem watch event: WRITE "/tmp/1237407533/.odo/devstate.json" [odo] I0524 18:16:12.528141 20880 watch.go:339] filesystem watch event: WRITE "/tmp/1237407533/.odo/devstate.20880.json" << End Captured GinkgoWriter Output Spec Goroutine goroutine 71 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00045d6c0, {0x2f01730?, 0xc00077b260}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00045d6c0, {0x2f01730, 0xc00077b260}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2b373e8, 0xf}, 0x0?, 0x2?, 0xc00077b230) /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:163 > github.com/redhat-developer/odo/tests/e2escenarios.glob..func1.5.2() /go/odo_1/tests/e2escenarios/e2e_test.go:153 | | // start dev mode again > devSession, _, _, ports, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x26014f3, 0xc000824060}) /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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ • [148.835 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/1237407533 Spawning '/go/bin/odo delete component' from /tmp/1237407533 << Captured StdOut/StdErr Output ------------------------------ Progress Report for Ginkgo Process #3 Automatically polling progress: E2E Test starting with non-empty Directory should verify developer workflow from non-empty Directory (Spec Runtime: 4m2.171s) /go/odo_1/tests/e2escenarios/e2e_test.go:197 In [It] (Node Runtime: 4m0.006s) /go/odo_1/tests/e2escenarios/e2e_test.go:197 Begin Captured GinkgoWriter Output >> ... [odo] I0524 18:18:08.372396 21032 retry.go:48] waiting for 5 second(s) before trying task "process for command \"run\"" [odo] I0524 18:18:08.372458 21032 exec.go:33] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_run.pid && cd ${PROJECT_SOURCE} && (mvn -Dmaven.repo.local=/home/user/.m2/repository spring-boot:run) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_run.pid] for pod: azwtbn-app-556cf76b6c-bz4gk in container: tools [odo] I0524 18:18:13.372918 21032 execute_run.go:90] checking if process for command "run" is running [odo] I0524 18:18:13.372974 21032 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:18:13.373007 21032 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: azwtbn-app-556cf76b6c-bz4gk in container: tools [odo] I0524 18:18:13.619857 21032 exec.go:82] 347 [odo] I0524 18:18:13.621163 21032 exec.go:33] Executing command [/bin/sh -c kill -0 347; echo $?] for pod: azwtbn-app-556cf76b6c-bz4gk in container: tools [odo] I0524 18:18:13.824971 21032 exec.go:82] 0 [odo] I0524 18:18:13.827797 21032 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I0524 18:18:13.828013 21032 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: azwtbn-app-556cf76b6c-bz4gk in container: tools << End Captured GinkgoWriter Output Spec Goroutine goroutine 70 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000020690, {0x2f01730?, 0xc0007d2d80}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000020690, {0x2f01730, 0xc0007d2d80}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2b29481, 0x8}, 0xc0008cc240?, 0xc0001800c0?, 0xc0007d2d20) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.DevSession.WaitSync({0xc0007d2d20, 0x0, 0xc000576fc0, {0x0, 0x0}}) /go/odo_1/tests/helper/helper_dev.go:233 > 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:854 github.com/onsi/ginkgo/v2/internal.(*Suite).runNode /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:841 ------------------------------ • [257.287 seconds] E2E Test starting with non-empty Directory should verify developer workflow from non-empty Directory /go/odo_1/tests/e2escenarios/e2e_test.go:197 Captured StdOut/StdErr Output >> Spawning '/go/bin/odo init' from /tmp/2340288260 Spawning '/go/bin/odo delete component' from /tmp/2340288260 << 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:431 Ran 4 of 4 Specs in 257.351 seconds FAIL! -- 3 Passed | 1 Failed | 0 Pending | 0 Skipped Ginkgo ran 1 suite in 4m40.186206418s Test Suite Failed exit status 1 make: *** [Makefile:229: test-e2e] Error 1