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=2a0e55454" ./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: 1685983485 - will randomize all specs Will run 499 of 932 specs Running in parallel across 16 processes SSSSSSSS•SSSSSSSSSS•••••S•SS• ------------------------------ • [7.675 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/3630337683 << Captured StdOut/StdErr Output ------------------------------ S ------------------------------ • [26.452 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/2946248429 << Captured StdOut/StdErr Output ------------------------------ S ------------------------------ • [27.808 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/4190461773 << Captured StdOut/StdErr Output ------------------------------ ••• ------------------------------ • [34.598 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/978462892 << Captured StdOut/StdErr Output ------------------------------ S•S•SS•S•••••SS•••••SSS•SS•S•••S•SSS•••SSSS•SSSS•SS•SSSS•S•••SSS••S••••••SS•S•••••SS•SS•••••S•S•S•SSS•S••••SS•S•SS••••S•SSS•SSS•••S••S•S••S•SS•SS•SSS•SSSSS•SSSSSSS•SS•••••SS•SSS•S•SS• ------------------------------ • [10.434 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/950064832 << Captured StdOut/StdErr Output ------------------------------ ••SSS• ------------------------------ • [13.018 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/2696346754 << Captured StdOut/StdErr Output ------------------------------ S•••SSS•••S•S••SSS•••••••S•S•SS•S•S•S•SS••SS•SS••••••SSS•S•••S• ------------------------------ • [12.245 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/2419346165 << Captured StdOut/StdErr Output ------------------------------ S•S••S••S•S••S•SS• ------------------------------ • [18.323 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/1584829511 << Captured StdOut/StdErr Output ------------------------------ •SSSSSS•SS••SS•••S••S••SS••••S••• ------------------------------ • [13.672 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/3966130598 << Captured StdOut/StdErr Output ------------------------------ •••••SSS••SSS•S•••S•SS•SSS•SS•S•SSS•S•SS ------------------------------ • [8.897 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/3573575396 << Captured StdOut/StdErr Output ------------------------------ SSS•••••S•••S ------------------------------ • [25.368 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/2487962682 << Captured StdOut/StdErr Output ------------------------------ •••••SS• ------------------------------ Progress Report for Ginkgo Process #7 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.453s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:294 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:294 At [By Step] cleaning up the resources on ending the session (Step Runtime: 26.363s) /go/odo_1/tests/integration/cmd_dev_debug_test.go:332 Begin Captured GinkgoWriter Output >> ... [odo] ✗ Finished executing the application (command: start-debug) [1m] [odo] I0605 16:56:11.770045 23843 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] I0605 16:56:11.770910 23843 implem.go:114] The path for preference file is /tmp/2365614052/preference.yaml [odo] I0605 16:56:11.771447 23843 segment.go:264] Checking telemetry enable status [odo] I0605 16:56:11.771459 23843 segment.go:282] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0605 16:56:11.844544 23843 all.go:46] starting to concurrently query 200 APIs [odo] I0605 16:56:11.844869 23843 all.go:62] fired up all goroutines to query APIs [odo] I0605 16:56:13.797620 23843 all.go:67] all goroutines have returned in 1.9531932s [odo] I0605 16:56:13.797787 23843 all.go:76] query result: objects=585 << End Captured GinkgoWriter Output Spec Goroutine goroutine 295 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc000290000, {0x30b1748?, 0xc000cfce88}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc000290000, {0x30b1748, 0xc000cfce88}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc0004386c0?, {0xc001418180?, 0x91797ad1d5?, 0x42ec240?}) /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(0xc000132000, {0x2d0d4e6, 0x2f}, {0xc00109be60, 0x1, 0x0?}) /go/odo_1/vendor/github.com/onsi/ginkgo/v2/internal/suite.go:301 github.com/onsi/ginkgo/v2.By({0x2d0d4e6?, 0x0?}, {0xc00109be60?, 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({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 ------------------------------ •SSS•SS••S•••S ------------------------------ • [12.187 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/1820668454 << Captured StdOut/StdErr Output ------------------------------ S•SS• ------------------------------ • [10.545 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/2331906435 << Captured StdOut/StdErr Output ------------------------------ SS•• ------------------------------ • [20.435 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/3003155104 << Captured StdOut/StdErr Output ------------------------------ ••S•••••S• ------------------------------ • [10.338 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/1378096053 << Captured StdOut/StdErr Output ------------------------------ ••SS•SS•S••SSSSS••••SSSSS•S•••S•SS•S••S•S••SS•SS•••S••••S•S•••SSS•S• ------------------------------ • [3.321 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/1068746959 << Captured StdOut/StdErr Output ------------------------------ S•SSSSS ------------------------------ • [3.094 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/3640414693 << Captured StdOut/StdErr Output ------------------------------ S•••S•S•SSSS• ------------------------------ • [3.408 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/1778235225 << Captured StdOut/StdErr Output ------------------------------ ••S•SSSS•• ------------------------------ Progress Report for Ginkgo Process #6 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.523s) /go/odo_1/tests/integration/cmd_dev_test.go:927 In [BeforeEach] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:900 Begin Captured GinkgoWriter Output >> ... [odo] I0605 16:59:25.039603 32171 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 16:59:25.045307 32171 port.go:319] port 8080 not listening in container "runtime" [odo] I0605 16:59:32.789547 32171 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: dckyte-app-59f4cff7f8-b22bf in container: runtime [odo] I0605 16:59:33.066398 32171 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 16:59:33.066425 32171 exec.go:82] 0: D77811AC:E796 231A1068:01BB 06 00000000:00000000 03:00000C0D 00000000 0 0 0 3 0000000000000000 [odo] I0605 16:59:33.066436 32171 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 16:59:33.066443 32171 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 16:59:33.066451 32171 exec.go:82] 0: 00000000000000000000000000000000:0BB8 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1009390000 0 590872708 1 0000000000000000 100 0 0 10 0 [odo] I0605 16:59:33.066462 32171 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 16:59:33.069645 32171 port.go:319] port 8080 not listening in container "runtime" << End Captured GinkgoWriter Output Spec Goroutine goroutine 593 [select, 2 minutes] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000f2150, {0x30b1988?, 0xc000d0b710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000f2150, {0x30b1988, 0xc000d0b710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cae6c1, 0x8}, 0xc0000a4d00?, 0xc0000a4ce0?, 0xc0012402a0) /go/odo_1/tests/helper/helper_run.go:54 github.com/redhat-developer/odo/tests/helper.DevSession.WaitSync({0xc0012402a0, 0x0, 0xc000215080, {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:919 | 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({0x266a273, 0xc000d60600}) /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•S•S•SS ------------------------------ • [9.815 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/1784214956 << Captured StdOut/StdErr Output ------------------------------ •••••••S ------------------------------ • [9.432 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/219709914 << Captured StdOut/StdErr Output ------------------------------ SSSS•••S•SSS•S•S•S•SS•••••S•S ------------------------------ • [135.817 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:3489 Captured StdOut/StdErr Output >> PID: 3719 << Captured StdOut/StdErr Output ------------------------------ Progress Report for Ginkgo Process #3 Automatically polling progress: odo logs command tests when component is created and odo logs is executed when running in Dev mode when --follow flag is specified should successfully follow logs of running component (Spec Runtime: 2m1.543s) /go/odo_1/tests/integration/cmd_logs_test.go:172 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_logs_test.go:124 Begin Captured GinkgoWriter Output >> ... [odo] I0605 17:02:02.910502 4008 exec.go:82] 6: F37811AC:C888 231A1068:01BB 06 00000000:00000000 03:00000341 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:02.910505 4008 exec.go:82] 7: F37811AC:E56A 231A1068:01BB 06 00000000:00000000 03:00000205 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:02.910509 4008 exec.go:82] 8: F37811AC:E548 231A1068:01BB 06 00000000:00000000 03:00000205 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:02.910511 4008 exec.go:82] 9: F37811AC:E568 231A1068:01BB 06 00000000:00000000 03:00000203 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:02.910514 4008 exec.go:82] 10: F37811AC:E55A 231A1068:01BB 06 00000000:00000000 03:00000206 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:02.910518 4008 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:02:02.910522 4008 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:02:02.910526 4008 exec.go:82] 0: 00000000000000000000000000000000:1F90 00000000000000000000000000000000:0000 0A 00000000:00000000 00:00000000 00000000 1010120000 0 590920889 1 0000000000000000 100 0 0 10 0 [odo] I0605 17:02:02.910529 4008 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:02:02.910843 4008 port.go:319] port 3000 not listening in container "runtime" << End Captured GinkgoWriter Output Spec Goroutine goroutine 770 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0004c8310, {0x30b1988?, 0xc000f8cfc0}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0004c8310, {0x30b1988, 0xc000f8cfc0}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc000f8cf90) /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..func14.7.2.1() /go/odo_1/tests/integration/cmd_logs_test.go:125 | | BeforeEach(func() { > devSession, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{ | RunOnPodman: podman, | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc000101a40}) /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 #13 Automatically polling progress: odo dev command tests when running odo dev and multiple env variables are set - without metadata.name should be able to exec command (Spec Runtime: 2m1.228s) /go/odo_1/tests/integration/cmd_dev_test.go:1579 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:1579 Begin Captured GinkgoWriter Output >> ... [odo] I0605 17:02:06.619693 4068 exec.go:82] 8: DF7811AC:C4E8 231A1068:01BB 06 00000000:00000000 03:00000327 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:06.619698 4068 exec.go:82] 9: DF7811AC:B902 231A1068:01BB 06 00000000:00000000 03:00000066 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:06.619702 4068 exec.go:82] 10: DF7811AC:C4BE 231A1068:01BB 06 00000000:00000000 03:00000327 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:06.619707 4068 exec.go:82] 11: DF7811AC:C4CA 231A1068:01BB 06 00000000:00000000 03:00000327 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:06.619713 4068 exec.go:82] 12: DF7811AC:C4AA 231A1068:01BB 06 00000000:00000000 03:00000327 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:06.619718 4068 exec.go:82] 13: DF7811AC:C4F4 231A1068:01BB 06 00000000:00000000 03:00000327 00000000 0 0 0 3 0000000000000000 [odo] I0605 17:02:06.619723 4068 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:02:06.620024 4068 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:02:06.620653 4068 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:02:06.633029 4068 port.go:319] port 3000 not listening in container "runtime" << End Captured GinkgoWriter Output Spec Goroutine goroutine 35005 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc001566150, {0x30b1988?, 0xc0015fe060}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc001566150, {0x30b1988, 0xc0015fe060}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc0015fe030) /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/helper.RunDevMode({{0x0, 0x0, 0x0}, {0x0, 0x0, 0x0}, 0x0, 0x0, 0x0, 0x0, ...}, ...) /go/odo_1/tests/helper/helper_dev.go:270 > github.com/redhat-developer/odo/tests/integration.glob..func7.12.2() /go/odo_1/tests/integration/cmd_dev_test.go:1580 | | It("should be able to exec command", func() { > err := helper.RunDevMode(helper.DevSessionOpts{ | RunOnPodman: podman, | }, func(session *gexec.Session, out, err []byte, ports map[string]string) { github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x1075f3e, 0xc001853e00}) /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 #8 Automatically polling progress: odo devfile deploy command tests deploying devfile with long-running exec when Automount volumes are present in the namespace should mount the volumes (Spec Runtime: 2m2.552s) /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:690 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:690 Begin Captured GinkgoWriter Output >> ... [oc] }, [oc] "status": { [oc] "active": 1, [oc] "ready": 0, [oc] "startTime": "2023-06-05T17:00:32Z", [oc] "uncountedTerminatedPods": {} [oc] } [oc] } [odo] [odo] Tip: Run `odo logs --deploy --follow` to get the logs of the command output. << End Captured GinkgoWriter Output Spec Goroutine goroutine 1052 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0017b2b60, {0x30b1748?, 0xc001674240}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0017b2b60, {0x30b1748, 0xc001674240}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc0016e8a80?, {0x0?, 0x291fd00?, 0xc000c561f0?}) /go/odo_1/vendor/github.com/onsi/gomega/gexec/session.go:144 github.com/redhat-developer/odo/tests/helper.(*CmdWrapper).Should(0xc0016e8a80, 0xc000097f50) /go/odo_1/tests/helper/helper_cmd_wrapper.go:120 > github.com/redhat-developer/odo/tests/integration.glob..func9.15.3.2() /go/odo_1/tests/integration/cmd_devfile_deploy_test.go:691 | | It("should mount the volumes", func() { > helper.Cmd("odo", "deploy").Should(func(session *gexec.Session) { | component := helper.NewComponent(cmpName, "app", labels.ComponentDeployMode, commonVar.Project, commonVar.CliRunner) | jobDef := component.GetJobDef() github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00136d1a0}) /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 ------------------------------ •SSS•SSSS••••SS••••SS••S•S•SSSSSS•SSSS ------------------------------ • [77.624 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/154250122 << Captured StdOut/StdErr Output ------------------------------ S••SS•SSS•SSS•••••S••••S•••SSS•S•SS••SS•••••S••••S ------------------------------ • [13.007 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/254474283 << Captured StdOut/StdErr Output ------------------------------ ••S••SSSSSS••SSSSS•••S••S•••S•••S•••••S••S•S•SSS•S••SS•••SS•S••SSS•SSS•SS•SSSS•S ------------------------------ Progress Report for Ginkgo Process #13 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.396s) /go/odo_1/tests/integration/cmd_dev_test.go:4018 In [It] (Node Runtime: 2m0s) /go/odo_1/tests/integration/cmd_dev_test.go:4018 Begin Captured GinkgoWriter Output >> ... [odo] stderr: [] [odo] I0605 17:06:42.641420 10631 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] I0605 17:06:42.658385 10631 implem.go:114] The path for preference file is /tmp/3852406978/preference.yaml [odo] I0605 17:06:42.659150 10631 segment.go:264] Checking telemetry enable status [odo] I0605 17:06:42.659164 10631 segment.go:282] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I0605 17:06:42.708436 10631 all.go:46] starting to concurrently query 200 APIs [odo] I0605 17:06:42.708526 10631 all.go:62] fired up all goroutines to query APIs [odo] I0605 17:06:43.303049 10631 all.go:67] all goroutines have returned in 594.610348ms [odo] I0605 17:06:43.303087 10631 all.go:76] query result: objects=548 << End Captured GinkgoWriter Output Spec Goroutine goroutine 35162 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc00033c850, {0x30b1748?, 0xc000e30060}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc00033c850, {0x30b1748, 0xc000e30060}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/onsi/gomega/gexec.(*Session).Wait(0xc00147de10?, {0xc000321f90?, 0x6?, 0x2ca80ba?}) /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:4019 | }) | 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({0x1075f3e, 0xc001852ae0}) /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 ------------------------------ ••SSS••S•SS•SSSS•••••• ------------------------------ • [10.996 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/2667705187 << Captured StdOut/StdErr Output ------------------------------ S ------------------------------ Progress Report for Ginkgo Process #10 Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 2m1.18s) /go/odo_1/tests/integration/cmd_dev_test.go:2135 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:2110 Begin Captured GinkgoWriter Output >> ... [odo] I0605 17:05:49.683621 11488 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0605 17:05:49.686150 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:49.688682 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:49.691709 11488 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0605 17:05:49.717863 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:49.727498 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:49.759078 11488 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I0605 17:05:49.759115 11488 status.go:34] setting inner loop State "WaitDeployment" [odo] I0605 17:05:49.759143 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43160816, generation: 1, pods: 0 [odo] ⚠ 0/3 nodes are available: 3 pod has unbound immediate PersistentVolumeClaims. preemption: 0/3 nodes are available: 3 Preemption is not helpful for scheduling. << End Captured GinkgoWriter Output Spec Goroutine goroutine 914 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000d4850, {0x30b1988?, 0xc00166d710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000d4850, {0x30b1988, 0xc00166d710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc00166d6e0) /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:2124 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00149f320}) /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 ------------------------------ •• ------------------------------ • [6.760 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/237459643 << Captured StdOut/StdErr Output ------------------------------ S•S••SS•S•• ------------------------------ • [9.560 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/2498912592 << Captured StdOut/StdErr Output ------------------------------ •S•S•S•SSSSSSS••SS•••••S•SS•S ------------------------------ • [10.663 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/4244459318 << Captured StdOut/StdErr Output ------------------------------ •SSS••S••S••SS••S••••SS•SS••S•SSSS•SS••S• ------------------------------ Progress Report for Ginkgo Process #10 Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 4m1.187s) /go/odo_1/tests/integration/cmd_dev_test.go:2135 In [BeforeEach] (Node Runtime: 4m0.008s) /go/odo_1/tests/integration/cmd_dev_test.go:2110 Begin Captured GinkgoWriter Output >> ... [odo] I0605 17:05:49.688682 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:49.691709 11488 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0605 17:05:49.717863 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:49.727498 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:49.759078 11488 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I0605 17:05:49.759115 11488 status.go:34] setting inner loop State "WaitDeployment" [odo] I0605 17:05:49.759143 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43160816, generation: 1, pods: 0 [odo] ⚠ 0/3 nodes are available: 3 pod has unbound immediate PersistentVolumeClaims. preemption: 0/3 nodes are available: 3 Preemption is not helpful for scheduling. [odo] ⚠ AttachVolume.Attach failed for volume "pvc-4d9064e4-cecd-46e1-8ea6-534b00655ffe" : timed out waiting for external-attacher of vpc.block.csi.ibm.io CSI driver to attach volume r010-d1835666-ba7e-4aba-8057-60be60bff6f3 [odo] ⚠ Unable to attach or mount volumes: unmounted volumes=[secondvol-uekmgf-app-vol], unattached volumes=[secondvol-uekmgf-app-vol kube-api-access-msv69 odo-projects odo-shared-data firstvol-uekmgf-app-vol]: timed out waiting for the condition << End Captured GinkgoWriter Output Spec Goroutine goroutine 914 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000d4850, {0x30b1988?, 0xc00166d710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000d4850, {0x30b1988, 0xc00166d710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc00166d6e0) /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:2124 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00149f320}) /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 ------------------------------ •SSS ------------------------------ • [78.777 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/3294441945 << Captured StdOut/StdErr Output ------------------------------ •••SSSS•S• ------------------------------ • [77.520 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/1312766573 << Captured StdOut/StdErr Output ------------------------------ •S•••S•SSS••SS•SSS•••••••••••• ------------------------------ Progress Report for Ginkgo Process #10 Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 6m1.189s) /go/odo_1/tests/integration/cmd_dev_test.go:2135 In [BeforeEach] (Node Runtime: 6m0.009s) /go/odo_1/tests/integration/cmd_dev_test.go:2110 Begin Captured GinkgoWriter Output >> ... [odo] I0605 17:11:43.886022 11488 retry.go:48] waiting for 5 second(s) before trying task "process for command \"devrun\"" [odo] I0605 17:11:43.886059 11488 exec.go:33] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_devrun.pid && cd /data && (cat myfile.log) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_devrun.pid] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:44.071860 11488 kubeexec.go:40] GetProcessInfoForCommand for "devrun" [odo] I0605 17:11:44.072068 11488 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_devrun.pid || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:44.230794 11488 exec.go:82] 14 [odo] I0605 17:11:44.230842 11488 exec.go:82] 0 [odo] I0605 17:11:44.233199 11488 exec.go:33] Executing command [/bin/sh -c kill -0 14; echo $?] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:44.426922 11488 exec.go:82] 1 [odo] I0605 17:11:44.427085 11488 exec.go:82] /bin/sh: line 0: kill: (14) - No such process [odo] ✓ Finished executing the application (command: devrun) [543ms] << End Captured GinkgoWriter Output Spec Goroutine goroutine 914 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000d4850, {0x30b1988?, 0xc00166d710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000d4850, {0x30b1988, 0xc00166d710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc00166d6e0) /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:2124 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00149f320}) /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 ------------------------------ ••• ------------------------------ • [FAILED] [421.681 seconds] odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name [BeforeEach] should successfully use the volume components in container components [BeforeEach] /go/odo_1/tests/integration/cmd_dev_test.go:2110 [It] /go/odo_1/tests/integration/cmd_dev_test.go:2135 Timeline >> Created dir: /tmp/557709087 Created dir: /tmp/4240148699 Setting KUBECONFIG=/tmp/4240148699/config Creating a new project: cmd-dev-test2135nve Running oc with args [oc new-project cmd-dev-test2135nve] and odo env: [] [oc] Now using project "cmd-dev-test2135nve" 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 cmd-dev-test2135nve] and odo env: [] [oc] configmap/config-map-for-cleanup created Current working dir: /go/odo_1/tests/integration Running odo with args [odo preference remove registry DefaultDevfileRegistry -f] and odo env: [] [odo] I0605 17:05:48.021219 11473 implem.go:114] The path for preference file is /tmp/4240148699/preference.yaml [odo] Successfully removed registry [odo] I0605 17:05:48.022959 11473 implem.go:114] The path for preference file is /tmp/4240148699/preference.yaml [odo] I0605 17:05:48.023289 11473 segment.go:264] Checking telemetry enable status [odo] I0605 17:05:48.023298 11473 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] I0605 17:05:48.190385 11481 implem.go:114] The path for preference file is /tmp/4240148699/preference.yaml [odo] New registry successfully added [odo] I0605 17:05:48.191922 11481 implem.go:114] The path for preference file is /tmp/4240148699/preference.yaml [odo] I0605 17:05:48.192579 11481 segment.go:264] Checking telemetry enable status [odo] I0605 17:05:48.192587 11481 segment.go:282] Sending telemetry disabled by env variable Setting current dir to: /tmp/557709087 Running odo with args [odo dev --random-ports] and odo env: [ODO_LOG_LEVEL=4 ODO_TRACKING_CONSENT=no] [odo] I0605 17:05:48.387367 11488 version.go:41] executing [podman version --format json] [odo] I0605 17:05:48.387998 11488 clientset.go:198] no Podman client initialized: exec: "podman": executable file not found in $PATH [odo] I0605 17:05:48.388042 11488 implem.go:114] The path for preference file is /tmp/4240148699/preference.yaml [odo] I0605 17:05:48.389149 11488 context.go:172] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:05:48.389298 11488 context.go:113] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:05:48.390637 11488 content.go:48] converted devfile YAML to JSON [odo] I0605 17:05:48.390719 11488 apiVersion.go:60] devfile schemaVersion: '2.0.0' [odo] I0605 17:05:48.390886 11488 helper.go:56] devfile apiVersion '2.0.0' is supported [odo] I0605 17:05:48.400328 11488 schema.go:61] validated devfile schema [odo] I0605 17:05:48.403343 11488 validate.go:45] Successfully validated devfile sections [odo] I0605 17:05:48.403368 11488 validate.go:45] Successfully validated devfile sections [odo] I0605 17:05:48.403731 11488 component.go:87] name of component is "uekmgf", and sanitized name is "uekmgf" [odo] I0605 17:05:48.403777 11488 kclient.go:224] Checking if "projects" resource is supported [odo] __ [odo] / \__ Developing using the "uekmgf" Devfile [odo] \__/ \ Namespace: cmd-dev-test2135nve [odo] / \__/ odo version: v3.11.0 [odo] \__/ [odo] [odo] ↪ Running on the cluster in Dev mode [odo] I0605 17:05:48.558399 11488 kubedev.go:86] Creating new adapter [odo] I0605 17:05:48.558434 11488 kubedev.go:94] Creating inner-loop resources for the component [odo] I0605 17:05:48.558458 11488 watch.go:101] starting WatchAndPush, path: /tmp/557709087, component: uekmgf, ignores [.git .odo .odo/odo-file-index.json] [odo] I0605 17:05:48.559893 11488 file_watcher.go:126] adding watch on path /tmp/557709087 [odo] I0605 17:05:48.559935 11488 file_watcher.go:126] adding watch on path /tmp/557709087/.odo [odo] I0605 17:05:48.559956 11488 file_watcher.go:126] adding watch on path /tmp/557709087/test [odo] I0605 17:05:48.572367 11488 watch.go:414] Copying files [] to pod [odo] I0605 17:05:48.572445 11488 context.go:172] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:05:48.572461 11488 context.go:113] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:05:48.573177 11488 content.go:48] converted devfile YAML to JSON [odo] I0605 17:05:48.573253 11488 apiVersion.go:60] devfile schemaVersion: '2.0.0' [odo] I0605 17:05:48.573281 11488 helper.go:56] devfile apiVersion '2.0.0' is supported [odo] I0605 17:05:48.578819 11488 schema.go:61] validated devfile schema [odo] I0605 17:05:48.579082 11488 validate.go:45] Successfully validated devfile sections [odo] I0605 17:05:48.579131 11488 components.go:69] component state: "" [odo] • Waiting for Kubernetes resources ... [odo] I0605 17:05:48.593868 11488 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Not available] [odo] I0605 17:05:48.602832 11488 utils.go:149] No entrypoint defined for the component, setting container runtime entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0605 17:05:48.602844 11488 utils.go:149] No entrypoint defined for the component, setting container runtime2 entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0605 17:05:48.621738 11488 kubernetes.go:60] Creating a PVC with name firstvol-uekmgf-app and labels map[app:app app.kubernetes.io/instance:uekmgf app.kubernetes.io/managed-by:odo app.kubernetes.io/managed-by-version:v3.11.0 app.kubernetes.io/part-of:app app.kubernetes.io/storage-name:firstvol component:uekmgf odo.dev/mode:Dev storage-name:firstvol] [odo] ✓ Added storage firstvol to component [odo] I0605 17:05:48.638312 11488 kubernetes.go:60] Creating a PVC with name secondvol-uekmgf-app and labels map[app:app app.kubernetes.io/instance:uekmgf app.kubernetes.io/managed-by:odo app.kubernetes.io/managed-by-version:v3.11.0 app.kubernetes.io/part-of:app app.kubernetes.io/storage-name:secondvol component:uekmgf odo.dev/mode:Dev storage-name:secondvol] [odo] ✓ Added storage secondvol to component [odo] I0605 17:05:48.667397 11488 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0605 17:05:48.667425 11488 utils.go:77] Updating container runtime2 with mandatory volume mounts [odo] I0605 17:05:48.711282 11488 components.go:346] Creating deployment uekmgf-app [odo] I0605 17:05:48.711300 11488 components.go:347] The component name is uekmgf [odo] I0605 17:05:48.713578 11488 kclient.go:273] Kubernetes version is "v1.25.8+37a9a08" [odo] I0605 17:05:48.713594 11488 kclient.go:285] Cluster has support for SSA: true [odo] Warning: would violate PodSecurity "restricted:v1.24": allowPrivilegeEscalation != false (containers "runtime", "runtime2" must set securityContext.allowPrivilegeEscalation=false), unrestricted capabilities (containers "runtime", "runtime2" must set securityContext.capabilities.drop=["ALL"]), runAsNonRoot != true (pod or containers "runtime", "runtime2" must set securityContext.runAsNonRoot=true), seccompProfile (pod or containers "runtime", "runtime2" must set securityContext.seccompProfile.type to "RuntimeDefault" or "Localhost") [odo] I0605 17:05:48.764026 11488 components.go:378] Successfully created component uekmgf [odo] I0605 17:05:48.794115 11488 components.go:390] Successfully created Service for component uekmgf [odo] I0605 17:05:48.815591 11488 all.go:46] starting to concurrently query 200 APIs [odo] I0605 17:05:48.822864 11488 all.go:62] fired up all goroutines to query APIs [odo] I0605 17:05:48.873882 11488 all.go:67] all goroutines have returned in 58.278757ms [odo] I0605 17:05:48.873915 11488 all.go:76] query result: objects=7 [odo] I0605 17:05:48.873966 11488 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0605 17:05:48.876687 11488 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0605 17:05:48.879443 11488 service.go:75] Getting list of services [odo] I0605 17:05:48.879459 11488 operators.go:30] Fetching list of operators installed in cluster [odo] I0605 17:05:48.899864 11488 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0605 17:05:48.899899 11488 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0605 17:05:48.902745 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:48.905286 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:48.907684 11488 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0605 17:05:48.917483 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:48.925907 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:48.964920 11488 components.go:134] Deployment has been updated to generation 1. Waiting new event... [odo] I0605 17:05:48.964950 11488 status.go:34] setting inner loop State "WaitDeployment" [odo] I0605 17:05:48.968195 11488 watch.go:263] deployment watcher Event: Type: ADDED, name: uekmgf-app, rv: 43160743, generation: 1, pods: 0 [odo] ⚠ 0/3 nodes are available: 3 pod has unbound immediate PersistentVolumeClaims. preemption: 0/3 nodes are available: 3 Preemption is not helpful for scheduling. [odo] I0605 17:05:48.977903 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43160748, generation: 1, pods: 0 [odo] I0605 17:05:48.978916 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43160752, generation: 1, pods: 0 [odo] I0605 17:05:48.979721 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43160759, generation: 1, pods: 0 [odo] ⚠ Pod is Pending [odo] I0605 17:05:49.268973 11488 watch.go:414] Copying files [] to pod [odo] I0605 17:05:49.269650 11488 context.go:172] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:05:49.269673 11488 context.go:113] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:05:49.271245 11488 content.go:48] converted devfile YAML to JSON [odo] I0605 17:05:49.271532 11488 apiVersion.go:60] devfile schemaVersion: '2.0.0' [odo] I0605 17:05:49.271552 11488 helper.go:56] devfile apiVersion '2.0.0' is supported [odo] I0605 17:05:49.285531 11488 schema.go:61] validated devfile schema [odo] I0605 17:05:49.285940 11488 validate.go:45] Successfully validated devfile sections [odo] I0605 17:05:49.286013 11488 components.go:69] component state: "WaitDeployment" [odo] I0605 17:05:49.300274 11488 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Not available] [odo] I0605 17:05:49.313503 11488 utils.go:149] No entrypoint defined for the component, setting container runtime entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0605 17:05:49.313518 11488 utils.go:149] No entrypoint defined for the component, setting container runtime2 entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0605 17:05:49.367509 11488 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0605 17:05:49.367526 11488 utils.go:77] Updating container runtime2 with mandatory volume mounts [odo] I0605 17:05:49.408121 11488 components.go:346] Creating deployment uekmgf-app [odo] I0605 17:05:49.408160 11488 components.go:347] The component name is uekmgf [odo] I0605 17:05:49.408174 11488 components.go:350] The component already exists, attempting to update it [odo] I0605 17:05:49.408189 11488 components.go:352] Applying deployment [odo] I0605 17:05:49.442341 11488 components.go:361] Successfully updated component uekmgf [odo] I0605 17:05:49.471146 11488 components.go:748] Successfully update Service for component uekmgf [odo] I0605 17:05:49.491717 11488 all.go:46] starting to concurrently query 200 APIs [odo] I0605 17:05:49.491891 11488 all.go:62] fired up all goroutines to query APIs [odo] I0605 17:05:49.598650 11488 all.go:67] all goroutines have returned in 106.933366ms [odo] I0605 17:05:49.598717 11488 all.go:76] query result: objects=8 [odo] I0605 17:05:49.598799 11488 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0605 17:05:49.598852 11488 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0605 17:05:49.598878 11488 service.go:75] Getting list of services [odo] I0605 17:05:49.598889 11488 operators.go:30] Fetching list of operators installed in cluster [odo] I0605 17:05:49.629793 11488 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0605 17:05:49.629841 11488 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0605 17:05:49.652091 11488 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0605 17:05:49.665379 11488 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0605 17:05:49.675917 11488 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0605 17:05:49.683586 11488 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0605 17:05:49.683621 11488 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0605 17:05:49.686150 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:49.688682 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:05:49.691709 11488 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0605 17:05:49.717863 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:49.727498 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:05:49.759078 11488 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I0605 17:05:49.759115 11488 status.go:34] setting inner loop State "WaitDeployment" [odo] I0605 17:05:49.759143 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43160816, generation: 1, pods: 0 [odo] ⚠ 0/3 nodes are available: 3 pod has unbound immediate PersistentVolumeClaims. preemption: 0/3 nodes are available: 3 Preemption is not helpful for scheduling. Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 2m1.18s) /go/odo_1/tests/integration/cmd_dev_test.go:2135 In [BeforeEach] (Node Runtime: 2m0.001s) /go/odo_1/tests/integration/cmd_dev_test.go:2110 Spec Goroutine goroutine 914 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000d4850, {0x30b1988?, 0xc00166d710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000d4850, {0x30b1988, 0xc00166d710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc00166d6e0) /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:2124 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00149f320}) /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 [odo] ⚠ AttachVolume.Attach failed for volume "pvc-4d9064e4-cecd-46e1-8ea6-534b00655ffe" : timed out waiting for external-attacher of vpc.block.csi.ibm.io CSI driver to attach volume r010-d1835666-ba7e-4aba-8057-60be60bff6f3 [odo] ⚠ Unable to attach or mount volumes: unmounted volumes=[secondvol-uekmgf-app-vol], unattached volumes=[secondvol-uekmgf-app-vol kube-api-access-msv69 odo-projects odo-shared-data firstvol-uekmgf-app-vol]: timed out waiting for the condition Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 4m1.187s) /go/odo_1/tests/integration/cmd_dev_test.go:2135 In [BeforeEach] (Node Runtime: 4m0.008s) /go/odo_1/tests/integration/cmd_dev_test.go:2110 Spec Goroutine goroutine 914 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000d4850, {0x30b1988?, 0xc00166d710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000d4850, {0x30b1988, 0xc00166d710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc00166d6e0) /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:2124 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00149f320}) /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 [odo] ⚠ AttachVolume.Attach failed for volume "pvc-4d9064e4-cecd-46e1-8ea6-534b00655ffe" : timed out waiting for external-attacher of vpc.block.csi.ibm.io CSI driver to attach volume r010-d1835666-ba7e-4aba-8057-60be60bff6f3 [odo] ⚠ Unable to attach or mount volumes: unmounted volumes=[secondvol-uekmgf-app-vol], unattached volumes=[odo-shared-data firstvol-uekmgf-app-vol secondvol-uekmgf-app-vol kube-api-access-msv69 odo-projects]: timed out waiting for the condition [odo] ✓ Pod is Running [odo] I0605 17:11:42.398267 11488 watch.go:263] deployment watcher Event: Type: MODIFIED, name: uekmgf-app, rv: 43181766, generation: 1, pods: 1 [odo] I0605 17:11:42.699182 11488 watch.go:414] Copying files [] to pod [odo] I0605 17:11:42.700437 11488 context.go:172] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:11:42.700569 11488 context.go:113] absolute devfile path: '/tmp/557709087/devfile.yaml' [odo] I0605 17:11:42.702538 11488 content.go:48] converted devfile YAML to JSON [odo] I0605 17:11:42.702828 11488 apiVersion.go:60] devfile schemaVersion: '2.0.0' [odo] I0605 17:11:42.702973 11488 helper.go:56] devfile apiVersion '2.0.0' is supported [odo] I0605 17:11:42.713696 11488 schema.go:61] validated devfile schema [odo] I0605 17:11:42.714092 11488 validate.go:45] Successfully validated devfile sections [odo] I0605 17:11:42.714195 11488 components.go:69] component state: "WaitDeployment" [odo] I0605 17:11:42.724761 11488 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Not available] [odo] I0605 17:11:42.734168 11488 utils.go:149] No entrypoint defined for the component, setting container runtime entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0605 17:11:42.734213 11488 utils.go:149] No entrypoint defined for the component, setting container runtime2 entrypoint to 'tail -f /dev/null'. You can set a 'command' and/or 'args' for the component to override this default entrypoint. [odo] I0605 17:11:42.769063 11488 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I0605 17:11:42.769222 11488 utils.go:77] Updating container runtime2 with mandatory volume mounts [odo] I0605 17:11:42.793513 11488 components.go:346] Creating deployment uekmgf-app [odo] I0605 17:11:42.793558 11488 components.go:347] The component name is uekmgf [odo] I0605 17:11:42.793624 11488 components.go:350] The component already exists, attempting to update it [odo] I0605 17:11:42.793702 11488 components.go:352] Applying deployment [odo] I0605 17:11:42.819846 11488 components.go:361] Successfully updated component uekmgf [odo] I0605 17:11:42.844691 11488 components.go:748] Successfully update Service for component uekmgf [odo] I0605 17:11:42.883460 11488 all.go:46] starting to concurrently query 200 APIs [odo] I0605 17:11:42.883639 11488 all.go:62] fired up all goroutines to query APIs [odo] I0605 17:11:42.924092 11488 all.go:67] all goroutines have returned in 40.622329ms [odo] I0605 17:11:42.924157 11488 all.go:76] query result: objects=8 [odo] I0605 17:11:42.924215 11488 kclient.go:224] Checking if "servicebindings" resource is supported [odo] I0605 17:11:42.924274 11488 kclient.go:224] Checking if "clusterserviceversions" resource is supported [odo] I0605 17:11:42.924329 11488 service.go:75] Getting list of services [odo] I0605 17:11:42.924348 11488 operators.go:30] Fetching list of operators installed in cluster [odo] I0605 17:11:42.948954 11488 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.3 [odo] I0605 17:11:42.948982 11488 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I0605 17:11:42.964722 11488 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I0605 17:11:43.004952 11488 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I0605 17:11:43.012573 11488 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I0605 17:11:43.020822 11488 service.go:93] Getting services started from operator: service-binding-operator.v1.3.3 [odo] I0605 17:11:43.020905 11488 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I0605 17:11:43.023593 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:11:43.026202 11488 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I0605 17:11:43.028952 11488 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I0605 17:11:43.036015 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I0605 17:11:43.043067 11488 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] • Syncing files into the container ... [odo] I0605 17:11:43.091830 11488 libdevfile.go:207] Build command: devbuild [odo] I0605 17:11:43.091928 11488 libdevfile.go:219] Run command: devrun [odo] I0605 17:11:43.092393 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087, destBase: ., destFile: . [odo] I0605 17:11:43.092463 11488 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I0605 17:11:43.093656 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/.gitignore, destBase: ., destFile: .gitignore [odo] I0605 17:11:43.093739 11488 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I0605 17:11:43.094386 11488 file_indexer.go:453] file added: /tmp/557709087/.gitignore [odo] I0605 17:11:43.094463 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/.odo, destBase: ., destFile: .odo [odo] I0605 17:11:43.094492 11488 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I0605 17:11:43.095065 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I0605 17:11:43.095132 11488 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I0605 17:11:43.095572 11488 file_indexer.go:453] file added: /tmp/557709087/devfile.yaml [odo] I0605 17:11:43.095636 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/package.json, destBase: ., destFile: package.json [odo] I0605 17:11:43.095688 11488 file_indexer.go:406] Corrected destinations: base: . file: package.json [odo] I0605 17:11:43.096126 11488 file_indexer.go:453] file added: /tmp/557709087/package.json [odo] I0605 17:11:43.096186 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/server.js, destBase: ., destFile: server.js [odo] I0605 17:11:43.096213 11488 file_indexer.go:406] Corrected destinations: base: . file: server.js [odo] I0605 17:11:43.096584 11488 file_indexer.go:453] file added: /tmp/557709087/server.js [odo] I0605 17:11:43.096649 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/test, destBase: ., destFile: test [odo] I0605 17:11:43.096678 11488 file_indexer.go:406] Corrected destinations: base: . file: test [odo] I0605 17:11:43.097032 11488 file_indexer.go:453] file added: /tmp/557709087/test [odo] I0605 17:11:43.097636 11488 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/test/test.js, destBase: ., destFile: test/test.js [odo] I0605 17:11:43.097737 11488 file_indexer.go:406] Corrected destinations: base: . file: test/test.js [odo] I0605 17:11:43.098162 11488 file_indexer.go:453] file added: /tmp/557709087/test/test.js [odo] I0605 17:11:43.098589 11488 sync.go:138] List of files to be deleted: +[] [odo] I0605 17:11:43.098642 11488 sync.go:140] List of files changed: +[/tmp/557709087/package.json /tmp/557709087/server.js /tmp/557709087/test /tmp/557709087/test/test.js /tmp/557709087/.gitignore /tmp/557709087/devfile.yaml] [odo] I0605 17:11:43.098677 11488 sync.go:193] Push: componentName: uekmgf, path: /tmp/557709087, files: [/tmp/557709087/package.json /tmp/557709087/server.js /tmp/557709087/test /tmp/557709087/test/test.js /tmp/557709087/.gitignore /tmp/557709087/devfile.yaml], delFiles: [*], isForcePush: true [odo] I0605 17:11:43.099111 11488 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I0605 17:11:43.099204 11488 exec.go:33] Executing command [rm -rf /projects/*] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:11:43.295641 11488 sync.go:233] Copying files /tmp/557709087/package.json /tmp/557709087/server.js /tmp/557709087/test /tmp/557709087/test/test.js /tmp/557709087/.gitignore /tmp/557709087/devfile.yaml to pod [odo] I0605 17:11:43.295694 11488 copy.go:36] CopyFile arguments: localPath /tmp/557709087, dest /projects/557709087, targetPath /projects, copyFiles [/tmp/557709087/package.json /tmp/557709087/server.js /tmp/557709087/test /tmp/557709087/test/test.js /tmp/557709087/.gitignore /tmp/557709087/devfile.yaml], globalExps [.git .odo .odo/odo-file-index.json] [odo] I0605 17:11:43.295737 11488 copy.go:64] Executing command tar xf - -C /projects --no-same-owner [odo] I0605 17:11:43.295945 11488 copy.go:97] makeTar arguments: srcPath: /tmp/557709087, destPath: /projects/557709087, files: [/tmp/557709087/package.json /tmp/557709087/server.js /tmp/557709087/test /tmp/557709087/test/test.js /tmp/557709087/.gitignore /tmp/557709087/devfile.yaml] [odo] I0605 17:11:43.296735 11488 copy.go:128] Got abs path: /tmp/557709087/package.json [odo] I0605 17:11:43.296795 11488 copy.go:129] Making /tmp/557709087 relative to /tmp/557709087/package.json [odo] I0605 17:11:43.296817 11488 copy.go:145] makeTar srcFile: 557709087/package.json [odo] I0605 17:11:43.296834 11488 copy.go:146] makeTar destFile: package.json [odo] I0605 17:11:43.296848 11488 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/package.json, destBase: /projects, destFile: package.json [odo] I0605 17:11:43.296875 11488 copy.go:172] Corrected destinations: base: /projects file: package.json [odo] I0605 17:11:43.393116 11488 copy.go:128] Got abs path: /tmp/557709087/server.js [odo] I0605 17:11:43.393156 11488 copy.go:129] Making /tmp/557709087 relative to /tmp/557709087/server.js [odo] I0605 17:11:43.393174 11488 copy.go:145] makeTar srcFile: 557709087/server.js [odo] I0605 17:11:43.393183 11488 copy.go:146] makeTar destFile: server.js [odo] I0605 17:11:43.393191 11488 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/server.js, destBase: /projects, destFile: server.js [odo] I0605 17:11:43.393225 11488 copy.go:172] Corrected destinations: base: /projects file: server.js [odo] I0605 17:11:43.394065 11488 copy.go:128] Got abs path: /tmp/557709087/test [odo] I0605 17:11:43.394132 11488 copy.go:129] Making /tmp/557709087 relative to /tmp/557709087/test [odo] I0605 17:11:43.394152 11488 copy.go:145] makeTar srcFile: 557709087/test [odo] I0605 17:11:43.394166 11488 copy.go:146] makeTar destFile: test [odo] I0605 17:11:43.394313 11488 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/test, destBase: /projects, destFile: test [odo] I0605 17:11:43.394338 11488 copy.go:172] Corrected destinations: base: /projects file: test [odo] I0605 17:11:43.395012 11488 copy.go:128] Got abs path: /tmp/557709087/test/test.js [odo] I0605 17:11:43.395041 11488 copy.go:129] Making /tmp/557709087 relative to /tmp/557709087/test/test.js [odo] I0605 17:11:43.395060 11488 copy.go:145] makeTar srcFile: 557709087/test/test.js [odo] I0605 17:11:43.395073 11488 copy.go:146] makeTar destFile: test/test.js [odo] I0605 17:11:43.395123 11488 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/test/test.js, destBase: /projects, destFile: test/test.js [odo] I0605 17:11:43.395135 11488 copy.go:172] Corrected destinations: base: /projects file: test/test.js [odo] I0605 17:11:43.396109 11488 copy.go:128] Got abs path: /tmp/557709087/.gitignore [odo] I0605 17:11:43.396139 11488 copy.go:129] Making /tmp/557709087 relative to /tmp/557709087/.gitignore [odo] I0605 17:11:43.396159 11488 copy.go:145] makeTar srcFile: 557709087/.gitignore [odo] I0605 17:11:43.396172 11488 copy.go:146] makeTar destFile: .gitignore [odo] I0605 17:11:43.396223 11488 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/.gitignore, destBase: /projects, destFile: .gitignore [odo] I0605 17:11:43.396234 11488 copy.go:172] Corrected destinations: base: /projects file: .gitignore [odo] I0605 17:11:43.397191 11488 copy.go:128] Got abs path: /tmp/557709087/devfile.yaml [odo] I0605 17:11:43.397220 11488 copy.go:129] Making /tmp/557709087 relative to /tmp/557709087/devfile.yaml [odo] I0605 17:11:43.397238 11488 copy.go:145] makeTar srcFile: 557709087/devfile.yaml [odo] I0605 17:11:43.397252 11488 copy.go:146] makeTar destFile: devfile.yaml [odo] I0605 17:11:43.397264 11488 copy.go:166] recursiveTar arguments: srcBase: /tmp, srcFile: 557709087/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I0605 17:11:43.397284 11488 copy.go:172] Corrected destinations: base: /projects file: devfile.yaml [odo] ✓ Syncing files into the container [408ms] [odo] I0605 17:11:43.502387 11488 kubeexec.go:40] GetProcessInfoForCommand for "devrun" [odo] I0605 17:11:43.502473 11488 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_devrun.pid || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:43.706512 11488 exec.go:82] cat: /opt/odo/.odo_cmd_devrun.pid: No such file or directory [odo] I0605 17:11:43.709175 11488 innerloop.go:157] running=false, execRequired=true [odo] • Building your application in container (command: devbuild) ... [odo] I0605 17:11:43.709393 11488 exec.go:33] Executing command [/bin/sh -c cd /data && (echo hello >> myfile.log) 1>>/proc/1/fd/1 2>>/proc/1/fd/2] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] ✓ Building your application in container (command: devbuild) [176ms] [odo] I0605 17:11:43.885897 11488 kubeexec.go:54] StartProcessForCommand for "devrun" [odo] • Executing the application (command: devrun) ... [odo] I0605 17:11:43.886022 11488 retry.go:48] waiting for 5 second(s) before trying task "process for command \"devrun\"" [odo] I0605 17:11:43.886059 11488 exec.go:33] Executing command [/bin/sh -c echo $$ > /opt/odo/.odo_cmd_devrun.pid && cd /data && (cat myfile.log) 1>>/proc/1/fd/1 2>>/proc/1/fd/2; echo $? >> /opt/odo/.odo_cmd_devrun.pid] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:44.071860 11488 kubeexec.go:40] GetProcessInfoForCommand for "devrun" [odo] I0605 17:11:44.072068 11488 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_devrun.pid || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:44.230794 11488 exec.go:82] 14 [odo] I0605 17:11:44.230842 11488 exec.go:82] 0 [odo] I0605 17:11:44.233199 11488 exec.go:33] Executing command [/bin/sh -c kill -0 14; echo $?] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:44.426922 11488 exec.go:82] 1 [odo] I0605 17:11:44.427085 11488 exec.go:82] /bin/sh: line 0: kill: (14) - No such process [odo] ✓ Finished executing the application (command: devrun) [543ms] Automatically polling progress: odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name should successfully use the volume components in container components (Spec Runtime: 6m1.189s) /go/odo_1/tests/integration/cmd_dev_test.go:2135 In [BeforeEach] (Node Runtime: 6m0.009s) /go/odo_1/tests/integration/cmd_dev_test.go:2110 Spec Goroutine goroutine 914 [select] github.com/onsi/gomega/internal.(*AsyncAssertion).match(0xc0000d4850, {0x30b1988?, 0xc00166d710}, 0x1, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:530 github.com/onsi/gomega/internal.(*AsyncAssertion).Should(0xc0000d4850, {0x30b1988, 0xc00166d710}, {0x0, 0x0, 0x0}) /go/odo_1/vendor/github.com/onsi/gomega/internal/async_assertion.go:145 github.com/redhat-developer/odo/tests/helper.WaitForOutputToContain({0x2cbd12b, 0xf}, 0x0?, 0x2?, 0xc00166d6e0) /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:2124 | } | var err error > session, _, _, _, err = helper.StartDevMode(helper.DevSessionOpts{}) | Expect(err).ToNot(HaveOccurred()) | }) github.com/onsi/ginkgo/v2/internal.extractBodyFunction.func3({0x266a273, 0xc00149f320}) /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 [odo] I0605 17:11:48.886908 11488 execute_run.go:90] checking if process for command "devrun" is running [odo] I0605 17:11:48.886970 11488 kubeexec.go:40] GetProcessInfoForCommand for "devrun" [odo] I0605 17:11:48.887007 11488 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_devrun.pid || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:49.055113 11488 exec.go:82] 14 [odo] I0605 17:11:49.055145 11488 exec.go:82] 0 [odo] I0605 17:11:49.057543 11488 exec.go:33] Executing command [/bin/sh -c kill -0 14; echo $?] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:49.265937 11488 exec.go:82] 1 [odo] I0605 17:11:49.266021 11488 exec.go:82] /bin/sh: line 0: kill: (14) - No such process [odo] I0605 17:11:49.268084 11488 kubeexec.go:40] GetProcessInfoForCommand for "devrun" [odo] I0605 17:11:49.268146 11488 exec.go:33] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_devrun.pid || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:49.426414 11488 exec.go:82] 14 [odo] I0605 17:11:49.426458 11488 exec.go:82] 0 [odo] I0605 17:11:49.429129 11488 exec.go:33] Executing command [/bin/sh -c kill -0 14; echo $?] for pod: uekmgf-app-86768589dc-p92cr in container: runtime2 [odo] I0605 17:11:49.595042 11488 exec.go:82] 1 [odo] I0605 17:11:49.595120 11488 exec.go:82] /bin/sh: line 0: kill: (14) - No such process [odo] • Waiting for the application to be ready ... [odo] I0605 17:11:50.599731 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:11:50.804107 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:11:50.805981 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:11:50.806012 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:11:50.806082 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:11:50.811206 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:11:52.600281 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:11:52.756708 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:11:52.756794 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:11:52.756956 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:11:52.758172 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:11:52.764219 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:11:56.601128 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:11:56.776037 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:11:56.776213 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:11:56.776806 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:11:56.776903 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:11:56.780424 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:12:04.602045 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:12:04.760467 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:04.760590 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:04.760718 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:04.763321 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:04.763372 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:12:14.602213 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:12:14.784555 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:14.785520 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:14.786081 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:14.786101 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:14.791162 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:12:24.603055 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:12:24.764172 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:24.764226 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:24.764246 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:24.764286 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:24.767446 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:12:34.604017 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:12:34.821431 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:34.821488 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:34.821506 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:34.821519 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:34.824522 11488 port.go:319] port 3000 not listening in container "runtime" [odo] I0605 17:12:44.604413 11488 exec.go:33] Executing command [/bin/sh -c cat /proc/net/tcp /proc/net/udp /proc/net/tcp6 /proc/net/udp6 || true] for pod: uekmgf-app-86768589dc-p92cr in container: runtime [odo] I0605 17:12:44.819237 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:44.819287 11488 exec.go:82] sl local_address rem_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:44.819313 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode [odo] I0605 17:12:44.820516 11488 exec.go:82] sl local_address remote_address st tx_queue rx_queue tr tm->when retrnsmt uid timeout inode ref pointer drops [odo] I0605 17:12:44.823669 11488 port.go:319] port 3000 not listening in container "runtime" [FAILED] in [BeforeEach] - /go/odo_1/tests/helper/helper_run.go:54 @ 06/05/23 17:12:48.223 Deleting project: cmd-dev-test2135nve Running oc with args [oc delete project cmd-dev-test2135nve --wait=false] and odo env: [] [oc] project.project.openshift.io "cmd-dev-test2135nve" deleted Setting current dir to: /go/odo_1/tests/integration Deleting dir: /tmp/557709087 Deleting dir: /tmp/4240148699 << Timeline [FAILED] Timed out after 420.001s. Expected : __ / \__ Developing using the "uekmgf" Devfile \__/ \ Namespace: cmd-dev-test2135nve / \__/ odo version: v3.11.0 \__/ ↪ Running on the cluster in Dev mode • Waiting for Kubernetes resources ... ✓ Added storage firstvol to component ✓ Added storage secondvol to component ⚠ 0/3 nodes are available: 3 pod has unbound immediate PersistentVolumeClaims. preemption: 0/3 nodes are available: 3 Preemption is not helpful for scheduling. ⚠ Pod is Pending ⚠ 0/3 nodes are available: 3 pod has unbound immediate PersistentVolumeClaims. preemption: 0/3 nodes are available: 3 Preemption is not helpful for scheduling. ⚠ AttachVolume.Attach failed for volume "pvc-4d9064e4-cecd-46e1-8ea6-534b00655ffe" : timed out waiting for external-attacher of vpc.block.csi.ibm.io CSI driver to attach volume r010-d1835666-ba7e-4aba-8057-60be60bff6f3 ⚠ Unable to attach or mount volumes: unmounted volumes=[secondvol-uekmgf-app-vol], unattached volumes=[secondvol-uekmgf-app-vol kube-api-access-msv69 odo-projects odo-shared-data firstvol-uekmgf-app-vol]: timed out waiting for the condition ⚠ AttachVolume.Attach failed for volume "pvc-4d9064e4-cecd-46e1-8ea6-534b00655ffe" : timed out waiting for external-attacher of vpc.block.csi.ibm.io CSI driver to attach volume r010-d1835666-ba7e-4aba-8057-60be60bff6f3 ⚠ Unable to attach or mount volumes: unmounted volumes=[secondvol-uekmgf-app-vol], unattached volumes=[odo-shared-data firstvol-uekmgf-app-vol secondvol-uekmgf-app-vol kube-api-access-msv69 odo-projects]: timed out waiting for the condition ✓ Pod is Running • Syncing files into the container ... ✓ Syncing files into the container [408ms] • Building your application in container (command: devbuild) ... ✓ Building your application in container (command: devbuild) [176ms] • Executing the application (command: devrun) ... ✓ Finished executing the application (command: devrun) [543ms] • Waiting for the application to be ready ... to contain substring : [Ctrl+c] - Exit In [BeforeEach] at: /go/odo_1/tests/helper/helper_run.go:54 @ 06/05/23 17:12:48.223 ------------------------------ Summarizing 1 Failure: [FAIL] odo dev command tests when running odo dev with devfile containing volume-component - with metadata.name [BeforeEach] should successfully use the volume components in container components /go/odo_1/tests/helper/helper_run.go:54 Ran 489 of 932 Specs in 1621.057 seconds FAIL! -- 488 Passed | 1 Failed | 0 Pending | 443 Skipped Ginkgo ran 1 suite in 28m6.153043824s Test Suite Failed exit status 1 make: *** [Makefile:207: test-integration-cluster] Error 1