cp ododevapispec.yaml pkg/apiserver-impl/swagger-ui/swagger.yaml go install -mod=vendor -ldflags="-X github.com/redhat-developer/odo/pkg/version.GITCOMMIT=d34edd69a-dirty" ./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-doc-automation.xml" tests/documentation/... [1699239763] Doc Command Reference Automation Suite - 17/17 specs - 16 procs ------------------------------ • [4.229 seconds] doc command reference odo init Non Interactive Mode Fetch Devfile of a specific version [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:106 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [5.381 seconds] doc command reference odo init Non Interactive Mode Fetch Devfile of the latest version [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:117 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [7.345 seconds] doc command reference odo init Non Interactive Mode Fetch Devfile from a URL [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:128 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [7.090 seconds] doc command reference odo init Non Interactive Mode Fetch Devfile from a URL [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:221 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [8.897 seconds] doc command reference odo init Interactive Mode when the directory is not empty Directory with sources [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:76 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo init' from /tmp/200622713 << Captured StdOut/StdErr Output ------------------------------ • [4.992 seconds] doc command reference odo init Non Interactive Mode set application ports after fetching Devfile [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:232 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [10.354 seconds] doc command reference odo init Interactive Mode Empty directory [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:32 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo init' from /tmp/2844217098 << Captured StdOut/StdErr Output ------------------------------ • [14.420 seconds] doc command reference odo init Non Interactive Mode fetching devfile from a registry when setting up the registry Fetch Devfile from a specific registry of the list [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:174 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [13.072 seconds] doc command reference odo init Non Interactive Mode fetching devfile from a registry when setting up the registry Fetch Devfile from any registry of the list [nocluster] /go/odo_1/tests/documentation/command-reference/doc_command_reference_init_test.go:190 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [14.890 seconds] doc command reference odo list namespace To list all available namespaces Lists all project resources available in a openshift cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_listnamespace_test.go:51 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [14.516 seconds] doc command reference odo set namespace To set an active namespace resource Sets a project resource to be current active on a openshift cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_setnamespace_test.go:41 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [15.416 seconds] doc command reference odo set namespace To set an active namespace resource Sets a namespace resource to be current active on a kubernetes cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_setnamespace_test.go:31 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [16.227 seconds] doc command reference odo list namespace To list all available namespaces Lists all namespace resources available in a kubernetes cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_listnamespace_test.go:40 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [16.152 seconds] doc command reference odo delete namespace To delete a namespace resource Deletes a project resource for a openshift cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_deletenamespace_test.go:61 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo delete project doc-command-reference-deletenamespace-test61cbj' from /tmp/1631821738 << Captured StdOut/StdErr Output ------------------------------ • [16.676 seconds] doc command reference odo create namespace To create a namespace resource Creates a project resource for a kubernetes cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_createnamespace_test.go:50 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [18.012 seconds] doc command reference odo create namespace To create a namespace resource Creates a namespace resource for a kubernetes cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_createnamespace_test.go:39 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [17.567 seconds] doc command reference odo delete namespace To delete a namespace resource Deletes a namespace resource for a kubernetes cluster /go/odo_1/tests/documentation/command-reference/doc_command_reference_deletenamespace_test.go:43 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo delete namespace doc-command-reference-deletenamespace-test43jgu' from /tmp/3338211948 << Captured StdOut/StdErr Output ------------------------------ SUCCESS! 18.087525394s [1699239763] User Guides Doc Automation Suite - 5/5 specs - 16 procs ------------------------------ • [6.485 seconds] User guides: Quickstart test Create namespace/project should show correct output for namespace/project creation /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:39 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" << Captured StdOut/StdErr Output ------------------------------ • [FAILED] [49.626 seconds] User guides: Quickstart test nodejs [It] should test the complete nodejs quickstart output in order /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:66 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo init' from /tmp/1833339464 << Captured StdOut/StdErr Output Timeline >> Created dir: /tmp/1833339464 Created dir: /tmp/2400515420 Setting KUBECONFIG=/tmp/2400515420/config Running kubectl with args [kubectl get namespace doc-user-guides-quickstart-test66tmv -o jsonpath={.metadata.name}] and odo env: [] [kubectl] Error from server (NotFound): namespaces "doc-user-guides-quickstart-test66tmv" not found Creating a new project: doc-user-guides-quickstart-test66tmv Running kubectl with args [kubectl create namespace doc-user-guides-quickstart-test66tmv] and odo env: [] [kubectl] namespace/doc-user-guides-quickstart-test66tmv created Running kubectl with args [kubectl config set-context --current --namespace doc-user-guides-quickstart-test66tmv] and odo env: [] [kubectl] Context "odo-test-kubernetes-cluster-2/cia4jeff0jvb2lioor0g/admin" modified. Running kubectl with args [kubectl get ns] and odo env: [] [kubectl] NAME STATUS AGE [kubectl] asoro Active 41d [kubectl] cert-manager Active 27d [kubectl] ci-devfile-registry Active 136d [kubectl] default Active 136d [kubectl] doc-user-guides-quickstart-test116xty Active 1s [kubectl] doc-user-guides-quickstart-test166ycv Active 1s [kubectl] doc-user-guides-quickstart-test231nbx Active 1s [kubectl] doc-user-guides-quickstart-test39wut Active 1s [kubectl] doc-user-guides-quickstart-test66tmv Active 1s [kubectl] flsz-project Active 100d [kubectl] ibm-cert-store Active 136d [kubectl] ibm-operators Active 136d [kubectl] ibm-system Active 136d [kubectl] kube-node-lease Active 136d [kubectl] kube-public Active 136d [kubectl] kube-system Active 136d [kubectl] mcsu-namespace Active 108d [kubectl] odo-dev-pr-7097 Active 41d [kubectl] odo-dev-pr-7142 Active 39m [kubectl] olm Active 136d [kubectl] operators Active 136d [kubectl] registry-operator-system Active 27d Running kubectl with args [kubectl create configmap config-map-for-cleanup --from-literal type=testing --from-literal team=odo -n doc-user-guides-quickstart-test66tmv] and odo env: [] [kubectl] configmap/config-map-for-cleanup created Current working dir: /go/odo_1/tests/documentation/user-guides Setting current dir to: /tmp/1833339464 Running odo with args [odo preference remove registry DefaultDevfileRegistry -f] and odo env: [] [odo] I1106 03:04:26.522106 13389 implem.go:114] The path for preference file is /tmp/2400515420/preference.yaml [odo] Successfully removed registry [odo] I1106 03:04:26.524796 13389 implem.go:114] The path for preference file is /tmp/2400515420/preference.yaml [odo] I1106 03:04:26.525486 13389 segment.go:268] Checking telemetry enable status [odo] I1106 03:04:26.525496 13389 segment.go:286] Sending telemetry disabled by env variable Running odo with args [odo preference add registry DefaultDevfileRegistry https://registry.stage.devfile.io] and odo env: [] [odo] I1106 03:04:26.699234 13409 implem.go:114] The path for preference file is /tmp/2400515420/preference.yaml [odo] New registry successfully added [odo] I1106 03:04:26.701150 13409 implem.go:114] The path for preference file is /tmp/2400515420/preference.yaml [odo] I1106 03:04:26.701524 13409 segment.go:268] Checking telemetry enable status [odo] I1106 03:04:26.701536 13409 segment.go:286] Sending telemetry disabled by env variable Setting current dir to: /tmp/1833339464 STEP: running odo init @ 11/06/23 03:04:26.722 running command [odo init] with env [ODO_LOG_LEVEL=0] STEP: running odo dev @ 11/06/23 03:04:29.165 Running odo with args [odo dev --random-ports --api-server=false] and odo env: [ODO_LOG_LEVEL=4 ODO_TRACKING_CONSENT=no] [odo] I1106 03:04:29.340369 13540 version.go:37] executing [podman version --format json] [odo] I1106 03:04:29.340771 13540 clientset.go:243] no Podman client initialized: exec: "podman": executable file not found in $PATH [odo] I1106 03:04:29.340788 13540 implem.go:114] The path for preference file is /tmp/2400515420/preference.yaml [odo] I1106 03:04:29.341826 13540 context.go:172] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:29.341844 13540 context.go:113] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:29.342470 13540 content.go:48] converted devfile YAML to JSON [odo] I1106 03:04:29.342642 13540 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1106 03:04:29.342799 13540 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1106 03:04:29.345840 13540 schema.go:61] validated devfile schema [odo] I1106 03:04:29.351479 13540 validate.go:45] Successfully validated devfile sections [odo] I1106 03:04:29.351532 13540 validate.go:45] Successfully validated devfile sections [odo] I1106 03:04:29.351685 13540 component.go:87] name of component is "my-nodejs-app", and sanitized name is "my-nodejs-app" [odo] I1106 03:04:29.351734 13540 kclient.go:237] Checking if "projects" resource is supported [odo] I1106 03:04:29.391682 13540 oc_server.go:26] Trying to connect to server c110.eu-de.containers.cloud.ibm.com:32062 [odo] I1106 03:04:29.399993 13540 oc_server.go:33] Server https://c110.eu-de.containers.cloud.ibm.com:32062 is up [odo] I1106 03:04:29.411466 13540 oc_server.go:66] Unable to get OpenShift Version: the server could not find the requested resource [odo] __ [odo] / \__ Developing using the "my-nodejs-app" Devfile [odo] \__/ \ Namespace: doc-user-guides-quickstart-test66tmv [odo] / \__/ odo version: v3.15.0 (d34edd69a-dirty) [odo] \__/ [odo] [odo] ↪ Running on the cluster in Dev mode [odo] I1106 03:04:29.429433 13540 kubedev.go:79] Creating new adapter [odo] I1106 03:04:29.429519 13540 kubedev.go:87] Creating inner-loop resources for the component [odo] I1106 03:04:29.429560 13540 watch.go:105] starting WatchAndPush, path: /tmp/1833339464, component: my-nodejs-app, ignores [.git .odo .odo/odo-file-index.json] [odo] I1106 03:04:29.432669 13540 file_watcher.go:130] adding watch on path /tmp/1833339464 [odo] I1106 03:04:29.432796 13540 file_watcher.go:130] adding watch on path /tmp/1833339464/.odo [odo] I1106 03:04:29.432881 13540 file_watcher.go:130] adding watch on path /tmp/1833339464/test [odo] I1106 03:04:29.480305 13540 watch.go:423] Copying files [] to pod [odo] I1106 03:04:29.480405 13540 context.go:172] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:29.480430 13540 context.go:113] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:29.482939 13540 content.go:48] converted devfile YAML to JSON [odo] I1106 03:04:29.486448 13540 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1106 03:04:29.486489 13540 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1106 03:04:29.503562 13540 schema.go:61] validated devfile schema [odo] I1106 03:04:29.503804 13540 validate.go:45] Successfully validated devfile sections [odo] I1106 03:04:29.503845 13540 components.go:69] component state: "" [odo] • Waiting for Kubernetes resources ... [odo] I1106 03:04:29.522670 13540 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Node.js] [odo] I1106 03:04:29.614704 13540 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1106 03:04:29.688857 13540 components.go:346] Creating deployment my-nodejs-app-app [odo] I1106 03:04:29.688886 13540 components.go:347] The component name is my-nodejs-app [odo] I1106 03:04:29.700972 13540 kclient.go:286] Kubernetes version is "v1.25.14+IKS" [odo] I1106 03:04:29.701017 13540 kclient.go:298] Cluster has support for SSA: true [odo] I1106 03:04:29.780525 13540 components.go:378] Successfully created component my-nodejs-app [odo] I1106 03:04:29.842206 13540 components.go:390] Successfully created Service for component my-nodejs-app [odo] I1106 03:04:29.859421 13540 all.go:46] starting to concurrently query 98 APIs [odo] I1106 03:04:29.859615 13540 all.go:62] fired up all goroutines to query APIs [odo] I1106 03:04:30.006581 13540 all.go:67] all goroutines have returned in 147.144913ms [odo] I1106 03:04:30.006689 13540 all.go:76] query result: objects=6 [odo] I1106 03:04:30.006844 13540 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1106 03:04:30.012872 13540 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1106 03:04:30.027973 13540 service.go:75] Getting list of services [odo] I1106 03:04:30.028011 13540 operators.go:30] Fetching list of operators installed in cluster [odo] I1106 03:04:30.085372 13540 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.2 [odo] I1106 03:04:30.085476 13540 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:30.096984 13540 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:30.121750 13540 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:30.136834 13540 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:30.157159 13540 service.go:93] Getting services started from operator: service-binding-operator.v1.4.1 [odo] I1106 03:04:30.157352 13540 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1106 03:04:30.167429 13540 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1106 03:04:30.177013 13540 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1106 03:04:30.185332 13540 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1106 03:04:30.205695 13540 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1106 03:04:30.227731 13540 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1106 03:04:30.266984 13540 components.go:134] Deployment has been updated to generation 1. Waiting new event... [odo] I1106 03:04:30.267037 13540 status.go:34] setting inner loop State "WaitDeployment" [odo] I1106 03:04:30.270484 13540 watch.go:271] deployment watcher Event: Type: ADDED, name: my-nodejs-app-app, rv: 41242362, generation: 1, pods: 0 [odo] I1106 03:04:30.272892 13540 watch.go:271] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 41242365, generation: 1, pods: 0 [odo] I1106 03:04:30.272919 13540 watch.go:271] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 41242374, generation: 1, pods: 0 [odo] =================== [odo] ⚠ Pod is Pending [odo] =================== [odo] I1106 03:04:30.274133 13540 watch.go:271] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 41242378, generation: 1, pods: 0 [odo] I1106 03:04:30.570930 13540 watch.go:423] Copying files [] to pod [odo] I1106 03:04:30.571910 13540 context.go:172] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:30.571955 13540 context.go:113] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:30.573772 13540 content.go:48] converted devfile YAML to JSON [odo] I1106 03:04:30.574037 13540 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1106 03:04:30.574168 13540 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1106 03:04:30.582030 13540 schema.go:61] validated devfile schema [odo] I1106 03:04:30.583092 13540 validate.go:45] Successfully validated devfile sections [odo] I1106 03:04:30.583210 13540 components.go:69] component state: "WaitDeployment" [odo] I1106 03:04:30.608725 13540 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Node.js] [odo] I1106 03:04:30.694743 13540 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1106 03:04:30.758214 13540 components.go:346] Creating deployment my-nodejs-app-app [odo] I1106 03:04:30.758253 13540 components.go:347] The component name is my-nodejs-app [odo] I1106 03:04:30.758269 13540 components.go:350] The component already exists, attempting to update it [odo] I1106 03:04:30.758286 13540 components.go:352] Applying deployment [odo] I1106 03:04:30.811128 13540 components.go:361] Successfully updated component my-nodejs-app [odo] I1106 03:04:30.862980 13540 components.go:748] Successfully update Service for component my-nodejs-app [odo] I1106 03:04:30.878329 13540 all.go:46] starting to concurrently query 98 APIs [odo] I1106 03:04:30.878489 13540 all.go:62] fired up all goroutines to query APIs [odo] I1106 03:04:31.114352 13540 all.go:67] all goroutines have returned in 236.032213ms [odo] I1106 03:04:31.114391 13540 all.go:76] query result: objects=6 [odo] I1106 03:04:31.114461 13540 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1106 03:04:31.114511 13540 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1106 03:04:31.114616 13540 service.go:75] Getting list of services [odo] I1106 03:04:31.114634 13540 operators.go:30] Fetching list of operators installed in cluster [odo] I1106 03:04:31.203522 13540 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.2 [odo] I1106 03:04:31.203624 13540 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:31.220955 13540 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:31.246460 13540 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:31.267881 13540 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:31.293818 13540 service.go:93] Getting services started from operator: service-binding-operator.v1.4.1 [odo] I1106 03:04:31.293847 13540 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1106 03:04:31.303217 13540 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1106 03:04:31.318164 13540 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1106 03:04:31.332184 13540 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1106 03:04:31.359803 13540 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1106 03:04:31.381169 13540 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1106 03:04:31.452378 13540 components.go:141] Deployment has 0 ready replicas. Waiting new event... [odo] I1106 03:04:31.452430 13540 status.go:34] setting inner loop State "WaitDeployment" [odo] I1106 03:04:31.452469 13540 watch.go:271] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 41242423, generation: 1, pods: 0 [odo] ✓ Pod is Running [odo] I1106 03:04:32.446940 13540 watch.go:271] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 41242475, generation: 1, pods: 1 [odo] I1106 03:04:32.747064 13540 watch.go:423] Copying files [] to pod [odo] I1106 03:04:32.747859 13540 context.go:172] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:32.747893 13540 context.go:113] absolute devfile path: '/tmp/1833339464/devfile.yaml' [odo] I1106 03:04:32.749379 13540 content.go:48] converted devfile YAML to JSON [odo] I1106 03:04:32.749601 13540 apiVersion.go:60] devfile schemaVersion: '2.1.0' [odo] I1106 03:04:32.749632 13540 helper.go:56] devfile apiVersion '2.1.0' is supported [odo] I1106 03:04:32.761913 13540 schema.go:61] validated devfile schema [odo] I1106 03:04:32.762463 13540 validate.go:45] Successfully validated devfile sections [odo] I1106 03:04:32.762580 13540 components.go:69] component state: "WaitDeployment" [odo] I1106 03:04:32.785765 13540 components.go:260] We are deploying these annotations: map[alpha.image.policy.openshift.io/resolve-names:* odo.dev/project-type:Node.js] [odo] I1106 03:04:32.852713 13540 utils.go:77] Updating container runtime with mandatory volume mounts [odo] I1106 03:04:32.905062 13540 components.go:346] Creating deployment my-nodejs-app-app [odo] I1106 03:04:32.905096 13540 components.go:347] The component name is my-nodejs-app [odo] I1106 03:04:32.905166 13540 components.go:350] The component already exists, attempting to update it [odo] I1106 03:04:32.905184 13540 components.go:352] Applying deployment [odo] I1106 03:04:32.955004 13540 components.go:361] Successfully updated component my-nodejs-app [odo] I1106 03:04:32.987062 13540 components.go:748] Successfully update Service for component my-nodejs-app [odo] I1106 03:04:33.008937 13540 all.go:46] starting to concurrently query 98 APIs [odo] I1106 03:04:33.011940 13540 all.go:62] fired up all goroutines to query APIs [odo] I1106 03:04:33.215479 13540 all.go:67] all goroutines have returned in 206.535676ms [odo] I1106 03:04:33.215550 13540 all.go:76] query result: objects=6 [odo] I1106 03:04:33.215653 13540 kclient.go:237] Checking if "servicebindings" resource is supported [odo] I1106 03:04:33.215686 13540 kclient.go:237] Checking if "clusterserviceversions" resource is supported [odo] I1106 03:04:33.215730 13540 service.go:75] Getting list of services [odo] I1106 03:04:33.215740 13540 operators.go:30] Fetching list of operators installed in cluster [odo] I1106 03:04:33.287168 13540 service.go:93] Getting services started from operator: cloud-native-postgresql.v1.18.2 [odo] I1106 03:04:33.287297 13540 service.go:124] Getting instances of: backups.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:33.307464 13540 service.go:124] Getting instances of: clusters.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:33.329932 13540 service.go:124] Getting instances of: poolers.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:33.346975 13540 service.go:124] Getting instances of: scheduledbackups.postgresql.k8s.enterprisedb.io [odo] I1106 03:04:33.363130 13540 service.go:93] Getting services started from operator: service-binding-operator.v1.4.1 [odo] I1106 03:04:33.363161 13540 service.go:124] Getting instances of: bindablekinds.binding.operators.coreos.com [odo] I1106 03:04:33.370679 13540 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1106 03:04:33.381901 13540 service.go:124] Getting instances of: clusterworkloadresourcemappings.servicebinding.io [odo] I1106 03:04:33.394189 13540 service.go:124] Getting instances of: servicebindings.binding.operators.coreos.com [odo] I1106 03:04:33.411261 13540 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1106 03:04:33.436356 13540 service.go:124] Getting instances of: servicebindings.servicebinding.io [odo] I1106 03:04:33.508884 13540 libdevfile.go:326] not running in Debug mode, so ignored Debug port for container runtime:debug:5858 [odo] • Syncing files into the container ... [odo] I1106 03:04:33.533579 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464, destBase: ., destFile: . [odo] I1106 03:04:33.533612 13540 file_indexer.go:406] Corrected destinations: base: . file: . [odo] I1106 03:04:33.534938 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/.gitignore, destBase: ., destFile: .gitignore [odo] I1106 03:04:33.534965 13540 file_indexer.go:406] Corrected destinations: base: . file: .gitignore [odo] I1106 03:04:33.535448 13540 file_indexer.go:453] file added: /tmp/1833339464/.gitignore [odo] I1106 03:04:33.535486 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/.odo, destBase: ., destFile: .odo [odo] I1106 03:04:33.535531 13540 file_indexer.go:406] Corrected destinations: base: . file: .odo [odo] I1106 03:04:33.535906 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/devfile.yaml, destBase: ., destFile: devfile.yaml [odo] I1106 03:04:33.535928 13540 file_indexer.go:406] Corrected destinations: base: . file: devfile.yaml [odo] I1106 03:04:33.536302 13540 file_indexer.go:453] file added: /tmp/1833339464/devfile.yaml [odo] I1106 03:04:33.536337 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/package.json, destBase: ., destFile: package.json [odo] I1106 03:04:33.536345 13540 file_indexer.go:406] Corrected destinations: base: . file: package.json [odo] I1106 03:04:33.536681 13540 file_indexer.go:453] file added: /tmp/1833339464/package.json [odo] I1106 03:04:33.536714 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/server.js, destBase: ., destFile: server.js [odo] I1106 03:04:33.536742 13540 file_indexer.go:406] Corrected destinations: base: . file: server.js [odo] I1106 03:04:33.537068 13540 file_indexer.go:453] file added: /tmp/1833339464/server.js [odo] I1106 03:04:33.537156 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/test, destBase: ., destFile: test [odo] I1106 03:04:33.537180 13540 file_indexer.go:406] Corrected destinations: base: . file: test [odo] I1106 03:04:33.537499 13540 file_indexer.go:453] file added: /tmp/1833339464/test [odo] I1106 03:04:33.542896 13540 file_indexer.go:400] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/test/test.js, destBase: ., destFile: test/test.js [odo] I1106 03:04:33.542923 13540 file_indexer.go:406] Corrected destinations: base: . file: test/test.js [odo] I1106 03:04:33.543518 13540 file_indexer.go:453] file added: /tmp/1833339464/test/test.js [odo] I1106 03:04:33.543855 13540 sync.go:138] List of files to be deleted: +[] [odo] I1106 03:04:33.543868 13540 sync.go:140] List of files changed: +[/tmp/1833339464/test /tmp/1833339464/test/test.js /tmp/1833339464/.gitignore /tmp/1833339464/devfile.yaml /tmp/1833339464/package.json /tmp/1833339464/server.js] [odo] I1106 03:04:33.543882 13540 sync.go:193] Push: componentName: my-nodejs-app, path: /tmp/1833339464, files: [/tmp/1833339464/test /tmp/1833339464/test/test.js /tmp/1833339464/.gitignore /tmp/1833339464/devfile.yaml /tmp/1833339464/package.json /tmp/1833339464/server.js], delFiles: [*], isForcePush: true [odo] I1106 03:04:33.544364 13540 sync.go:310] remote files marked for deletion are [/projects/*] [odo] I1106 03:04:33.544468 13540 exec.go:37] Executing command [rm -rf /projects/*] for pod: my-nodejs-app-app-7d5b5c9545-phlm5 in container: runtime [odo] I1106 03:04:33.792606 13540 sync.go:233] Copying files /tmp/1833339464/test /tmp/1833339464/test/test.js /tmp/1833339464/.gitignore /tmp/1833339464/devfile.yaml /tmp/1833339464/package.json /tmp/1833339464/server.js to pod [odo] I1106 03:04:33.792638 13540 copy.go:36] CopyFile arguments: localPath /tmp/1833339464, dest /projects/1833339464, targetPath /projects, copyFiles [/tmp/1833339464/test /tmp/1833339464/test/test.js /tmp/1833339464/.gitignore /tmp/1833339464/devfile.yaml /tmp/1833339464/package.json /tmp/1833339464/server.js], globalExps [.git .odo .odo/odo-file-index.json] [odo] I1106 03:04:33.792667 13540 copy.go:63] Executing command tar xf - -C /projects --no-same-owner [odo] I1106 03:04:33.793276 13540 copy.go:96] makeTar arguments: srcPath: /tmp/1833339464, destPath: /projects/1833339464, files: [/tmp/1833339464/test /tmp/1833339464/test/test.js /tmp/1833339464/.gitignore /tmp/1833339464/devfile.yaml /tmp/1833339464/package.json /tmp/1833339464/server.js] [odo] I1106 03:04:33.793965 13540 copy.go:127] Got abs path: /tmp/1833339464/test [odo] I1106 03:04:33.793993 13540 copy.go:128] Making /tmp/1833339464 relative to /tmp/1833339464/test [odo] I1106 03:04:33.794009 13540 copy.go:144] makeTar srcFile: 1833339464/test [odo] I1106 03:04:33.794018 13540 copy.go:145] makeTar destFile: test [odo] I1106 03:04:33.794028 13540 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/test, destBase: /projects, destFile: test [odo] I1106 03:04:33.794037 13540 copy.go:171] Corrected destinations: base: /projects file: test [odo] I1106 03:04:33.794642 13540 copy.go:127] Got abs path: /tmp/1833339464/test/test.js [odo] I1106 03:04:33.794662 13540 copy.go:128] Making /tmp/1833339464 relative to /tmp/1833339464/test/test.js [odo] I1106 03:04:33.794675 13540 copy.go:144] makeTar srcFile: 1833339464/test/test.js [odo] I1106 03:04:33.794684 13540 copy.go:145] makeTar destFile: test/test.js [odo] I1106 03:04:33.794692 13540 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/test/test.js, destBase: /projects, destFile: test/test.js [odo] I1106 03:04:33.794706 13540 copy.go:171] Corrected destinations: base: /projects file: test/test.js [odo] I1106 03:04:34.007751 13540 copy.go:127] Got abs path: /tmp/1833339464/.gitignore [odo] I1106 03:04:34.007774 13540 copy.go:128] Making /tmp/1833339464 relative to /tmp/1833339464/.gitignore [odo] I1106 03:04:34.007785 13540 copy.go:144] makeTar srcFile: 1833339464/.gitignore [odo] I1106 03:04:34.007791 13540 copy.go:145] makeTar destFile: .gitignore [odo] I1106 03:04:34.007798 13540 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/.gitignore, destBase: /projects, destFile: .gitignore [odo] I1106 03:04:34.007803 13540 copy.go:171] Corrected destinations: base: /projects file: .gitignore [odo] I1106 03:04:34.008446 13540 copy.go:127] Got abs path: /tmp/1833339464/devfile.yaml [odo] I1106 03:04:34.008485 13540 copy.go:128] Making /tmp/1833339464 relative to /tmp/1833339464/devfile.yaml [odo] I1106 03:04:34.008502 13540 copy.go:144] makeTar srcFile: 1833339464/devfile.yaml [odo] I1106 03:04:34.008509 13540 copy.go:145] makeTar destFile: devfile.yaml [odo] I1106 03:04:34.008521 13540 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/devfile.yaml, destBase: /projects, destFile: devfile.yaml [odo] I1106 03:04:34.008526 13540 copy.go:171] Corrected destinations: base: /projects file: devfile.yaml [odo] I1106 03:04:34.009249 13540 copy.go:127] Got abs path: /tmp/1833339464/package.json [odo] I1106 03:04:34.009264 13540 copy.go:128] Making /tmp/1833339464 relative to /tmp/1833339464/package.json [odo] I1106 03:04:34.009273 13540 copy.go:144] makeTar srcFile: 1833339464/package.json [odo] I1106 03:04:34.009288 13540 copy.go:145] makeTar destFile: package.json [odo] I1106 03:04:34.009297 13540 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/package.json, destBase: /projects, destFile: package.json [odo] I1106 03:04:34.009303 13540 copy.go:171] Corrected destinations: base: /projects file: package.json [odo] I1106 03:04:34.009934 13540 copy.go:127] Got abs path: /tmp/1833339464/server.js [odo] I1106 03:04:34.009951 13540 copy.go:128] Making /tmp/1833339464 relative to /tmp/1833339464/server.js [odo] I1106 03:04:34.009959 13540 copy.go:144] makeTar srcFile: 1833339464/server.js [odo] I1106 03:04:34.009965 13540 copy.go:145] makeTar destFile: server.js [odo] I1106 03:04:34.009973 13540 copy.go:165] recursiveTar arguments: srcBase: /tmp, srcFile: 1833339464/server.js, destBase: /projects, destFile: server.js [odo] I1106 03:04:34.009978 13540 copy.go:171] Corrected destinations: base: /projects file: server.js [odo] ✓ Syncing files into the container [532ms] [odo] I1106 03:04:34.068298 13540 kubeexec.go:40] GetProcessInfoForCommand for "run" [odo] I1106 03:04:34.068319 13540 exec.go:37] Executing command [/bin/sh -c cat /opt/odo/.odo_cmd_run.pid || true] for pod: my-nodejs-app-app-7d5b5c9545-phlm5 in container: runtime [odo] I1106 03:04:34.299149 13540 exec.go:96] cat: /opt/odo/.odo_cmd_run.pid: No such file or directory [odo] I1106 03:04:34.310361 13540 innerloop.go:131] running=false, execRequired=true [odo] • Building your application in container (command: install) ... [odo] I1106 03:04:34.310623 13540 exec.go:37] Executing command [/bin/sh -c cd ${PROJECT_SOURCE} && (npm install) 1>>/proc/1/fd/1 2>>/proc/1/fd/2] for pod: my-nodejs-app-app-7d5b5c9545-phlm5 in container: runtime [odo] ✗ Building your application in container (command: install) [3s] [odo] I1106 03:04:37.254007 13540 exec.go:54] ExecuteCommand returned an an err: error while streaming command: command terminated with exit code 1. for command '[/bin/sh -c cd ${PROJECT_SOURCE} && (npm install) 1>>/proc/1/fd/1 2>>/proc/1/fd/2]' [odo] stdout: [] [odo] stderr: [] [odo] npm notice [odo] npm notice New major version of npm available! 8.19.4 -> 10.2.3 [odo] npm notice Changelog: [odo] npm notice Run `npm install -g npm@10.2.3` to update! [odo] npm notice [odo] npm ERR! code FETCH_ERROR [odo] npm ERR! errno FETCH_ERROR [odo] npm ERR! invalid json response body at https://registry.npmjs.org/express reason: Invalid response body while trying to fetch https://registry.npmjs.org/express: 139711064706048:error:1408F119:SSL routines:ssl3_get_record:decryption failed or bad record mac:ssl/record/ssl3_record.c:677: [odo] npm ERR! [odo] [odo] npm ERR! A complete log of this run can be found in: [odo] npm ERR! /opt/app-root/src/.npm/_logs/2023-11-06T03_04_34_882Z-debug-0.log [odo] I1106 03:04:37.398183 13540 status.go:34] setting inner loop State "Ready" [odo] I1106 03:04:37.400080 13540 watch.go:437] Error from Push: watch command was unable to push component: unable to exec command [/bin/sh -c cd ${PROJECT_SOURCE} && (npm install) 1>>/proc/1/fd/1 2>>/proc/1/fd/2]: error while streaming command: command terminated with exit code 1 [odo] Error occurred on Push - watch command was unable to push component: unable to exec command [/bin/sh -c cd ${PROJECT_SOURCE} && (npm install) 1>>/proc/1/fd/1 2>>/proc/1/fd/2]: error while streaming command: command terminated with exit code 1 [odo] [odo] [odo] ↪ Dev mode [odo] Status: [odo] Watching for changes in the current directory /tmp/1833339464 [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] I1106 03:04:37.400356 13540 watch.go:271] deployment watcher Event: Type: MODIFIED, name: my-nodejs-app-app, rv: 41242486, generation: 1, pods: 1 [odo] I1106 03:04:37.500843 13540 watch.go:348] filesystem watch event: CREATE "/tmp/1833339464/.odo/odo-file-index.json" [odo] I1106 03:04:37.501778 13540 watch.go:348] filesystem watch event: WRITE "/tmp/1833339464/.odo/odo-file-index.json" [odo] read: read /dev/stdin: input/output error [odo] I1106 03:04:39.174726 13540 watch.go:333] Dev mode interrupted by user [odo] I1106 03:04:39.193368 13540 implem.go:114] The path for preference file is /tmp/2400515420/preference.yaml [odo] I1106 03:04:39.195038 13540 segment.go:268] Checking telemetry enable status [odo] I1106 03:04:39.195086 13540 segment.go:286] Sending telemetry disabled by env variable [odo] Cleaning resources, please wait [odo] I1106 03:04:39.359279 13540 all.go:46] starting to concurrently query 98 APIs [odo] I1106 03:04:39.359490 13540 all.go:62] fired up all goroutines to query APIs [odo] I1106 03:04:39.870271 13540 all.go:67] all goroutines have returned in 510.996088ms [odo] I1106 03:04:39.870341 13540 all.go:76] query result: objects=357 [FAILED] in [It] - /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:107 @ 11/06/23 03:05:11.842 Running kubectl with args [kubectl get namespace doc-user-guides-quickstart-test66tmv -o jsonpath={.metadata.name}] and odo env: [] [kubectl] doc-user-guides-quickstart-test66tmvDeleting project: doc-user-guides-quickstart-test66tmv Running kubectl with args [kubectl delete namespaces doc-user-guides-quickstart-test66tmv --wait=false] and odo env: [] [kubectl] namespace "doc-user-guides-quickstart-test66tmv" deleted Setting current dir to: /go/odo_1/tests/documentation/user-guides Deleting dir: /tmp/1833339464 Deleting dir: /tmp/2400515420 << Timeline [FAILED] user-guides/quickstart/docs-mdx/nodejs/nodejs_odo_dev_output.mdx Expected : ( """ ... // 10 identical lines ✓ Pod is Running ✓ Syncing files into the container [1s] - ✓ Building your application in container (command: install) [1s] - • Executing the application (command: run) ... - ✓ Waiting for the application to be ready [1s] - - Forwarding from 127.0.0.1:20001 -> 3000 + ✗ Building your application in container (command: install) [1s] + Error occurred on Push - watch command was unable to push component: unable to exec command [/bin/sh -c cd ${PROJECT_SOURCE} && (npm install) 1>>/proc/1/fd/1 2>>/proc/1/fd/2]: error while streaming command: command terminated with exit code 1 ↪ Dev mode Status: ... // 6 identical lines """ ) to be empty In [It] at: /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:107 @ 11/06/23 03:05:11.842 ------------------------------ • [59.693 seconds] User guides: Quickstart test Go quickstart guide should test the complete go quickstart output in order /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:116 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo init' from /tmp/3078365332 << Captured StdOut/StdErr Output ------------------------------ • [60.332 seconds] User guides: Quickstart test .NET quickstart guide should test the complete dotnet quickstart output in order /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:166 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo init' from /tmp/3369857591 << Captured StdOut/StdErr Output ------------------------------ • [89.748 seconds] User guides: Quickstart test Java quickstart guide should test the complete java springboot quickstart output in order /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:231 Captured StdOut/StdErr Output >> Using Devfile Registry URL at: "https://registry.stage.devfile.io" Spawning '/go/bin/odo init' from /tmp/3022787666 << Captured StdOut/StdErr Output ------------------------------ Summarizing 1 Failure: [FAIL] User guides: Quickstart test nodejs [It] should test the complete nodejs quickstart output in order /go/odo_1/tests/documentation/user-guides/doc_user_guides_quickstart_test.go:107 Ran 5 of 5 Specs in 89.857 seconds FAIL! -- 4 Passed | 1 Failed | 0 Pending | 0 Skipped Ginkgo ran 2 suites in 3m9.56315783s There were failures detected in the following suites: user-guides ./tests/documentation/user-guides Test Suite Failed exit status 1 make: *** [Makefile:241: test-doc-automation] Error 1