Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[duplicate] odo watch deletes all files #3307

Closed
amitkrout opened this issue Jun 5, 2020 · 22 comments
Closed

[duplicate] odo watch deletes all files #3307

amitkrout opened this issue Jun 5, 2020 · 22 comments
Assignees
Labels
flake Categorizes issue or PR as related to a flaky test. priority/High Important issue; should be worked on before any other issues (except priority/Critical issue(s)).
Projects

Comments

@amitkrout
Copy link
Contributor

amitkrout commented Jun 5, 2020

/kind bug

What versions of software are you using?

Operating System:
CI
Output of odo version:
master

How did you run odo exactly?

#3307 (comment)

Actual behavior

watch stuck

Expected behavior

watch should push the changes

Any logs, error output, etc?

@openshift-ci-robot openshift-ci-robot added the kind/bug Categorizes issue or PR as related to a bug. label Jun 5, 2020
@amitkrout amitkrout self-assigned this Jun 5, 2020
@amitkrout amitkrout added this to For consideration in Sprint 185 via automation Jun 5, 2020
@amitkrout amitkrout added the flake Categorizes issue or PR as related to a flaky test. label Jun 5, 2020
@amitkrout amitkrout moved this from For consideration to To do in Sprint 185 Jun 5, 2020
@amitkrout
Copy link
Contributor Author

amitkrout commented Jun 14, 2020

It seems the issue is with pushing files steps

$ odo watch nodejs-app --context tests/examples/source/nodejs/
[...]
Pushing files...
 ✓  Waiting for component to start [629ms]
 ✓  Syncing files to the component [4s]
 ✓  Building component [5s]
[...]

I think it stuck in any one of these steps may be in the step Waiting for component to start or Building component. I need to figure out if this is the case then why the function WatchNonRetCmdStdOut does not throw any error.

Cluster load increases with consistently pushing and build files due to the multiple changes (file/dir create , delete and update in server.js file) in the context dir causing the issue. Just to confirm it i am going to keep the server.js file change in the first place for watch command.

EDIT: It worked, i mean route successfully hit the application end point by keeping the server.js file change in the first place for watch command verification.

@girishramnani girishramnani added this to For consideration in Sprint 186 via automation Jun 22, 2020
@girishramnani girishramnani removed this from To do in Sprint 185 Jun 22, 2020
@girishramnani girishramnani moved this from For consideration to To do in Sprint 186 Jun 22, 2020
@dharmit
Copy link
Member

dharmit commented Jun 22, 2020

To be split into two. @amitkrout to open another issue where we'll need dev inputs.

@amitkrout amitkrout added the priority/High Important issue; should be worked on before any other issues (except priority/Critical issue(s)). label Jun 24, 2020
@kadel
Copy link
Member

kadel commented Jun 24, 2020

@amitkrout Can you please provide steps how it can be reproduced outside of our tests?

@amitkrout
Copy link
Contributor Author

amitkrout commented Jun 25, 2020

@amitkrout Can you please provide steps how it can be reproduced outside of our tests?

@kadel
Steps to reproduce:

$ odo project create test123

$ oc --request-timeout 5m import-image nodejs --namespace=test123 --from=registry.access.redhat.com/rhscl/nodejs-10-rhel7:latest --confirm

$ oc annotate istag/nodejs:latest --namespace=test123 tags=builder --overwrite

$ odo create nodejs nodejs-app --project test123 --context tests/examples/source/nodejs/

$ odo push --context tests/examples/source/nodejs/

$ odo url create --port 8080 --context tests/examples/source/nodejs/

$ odo push --context tests/examples/source/nodejs/

$ curl -ik http://nodejs-app-8080-app-test123.apps.gamore46installer25thjune.devcluster.openshift.com
// Hello world from node.js! 

// Update the message in the file https://github.com/openshift/odo/blob/master/tests/examples/source/nodejs/server.js#L12

$ odo push --context tests/examples/source/nodejs/

$ curl -ik http://nodejs-app-8080-app-test123.apps.gamore46installer25thjune.devcluster.openshift.com
// Should display the updated message 

Create a shell script check.sh and put it in the context dir tests/examples/source/nodejs/
$ cat check.sh 
#!/bin/sh

# fail if some commands fails
set -e
# show commands
set -x

touch {a..z}.txt
sleep 3
rm {a..z}.txt
mkdir {1...9}
rm -rf {1...9}
sed -i "" "s/Hello/Hello 1/g" server.js

$ odo watch nodejs-app --context tests/examples/source/nodejs/

// open another terminal. Go to the context dir and run check.sh from there
$ ./check.sh

// Keep executing the shell script more than once or so, you will hit the issue, I mean watch will stuck 

Waiting for something to change in /Users/amit/go/src/github.com/openshift/odo/tests/examples/source/nodejs
Pushing files...
 ✓  Waiting for component to start [355ms]
 ✓  Syncing files to the component [7s]
 ✓  Building component [4s]
Waiting for something to change in /Users/amit/go/src/github.com/openshift/odo/tests/examples/source/nodejs
File . changed
File /Users/amit/go/src/github.com/openshift/odo/tests/examples/source/nodejs/server.js changed
Pushing files...
 ✓  Waiting for component to start [331ms]
 ◓  Syncing files to the component

So this output confirms that the issue is with watch command itself.

Then what is the issue with the test script:

Timeout is happening while curling for a particular text instead of watch command. This is happening because watch command fails to capture the actual failure due to faulty script https://github.com/openshift/odo/blob/master/tests/integration/devfile/utils/utils.go#L212. The fault lies in the go routine, actually we are not handling buffer output efficiently for each change in the source. Asper our current code it looks in the buffer for a string Waiting for something to change and it does not even bother if very next file changes push stuck or fails.

Screen Shot 2020-06-25 at 3 59 45 PM

@amitkrout
Copy link
Contributor Author

Issue #3420 created for watch verification test script refactor

@amitkrout amitkrout changed the title Route fails to hit application endpoint on supported images like java and nodejs component watch stuck over multiple file changes in a single shoot Jun 25, 2020
@girishramnani girishramnani assigned mik-dass and unassigned amitkrout Jun 25, 2020
@mik-dass
Copy link
Contributor

mik-dass commented Jul 1, 2020

I tried the above scenario and everything works me


Syncing to component nodejs
I0701 14:37:16.746144   25672 adapter.go:129] Push: componentName: nodejs, path: /home/mrinaldas/nodejs-tes, files: [/home/mrinaldas/nodejs-tes/a.txt /home/mrinaldas/nodejs-tes/b.txt /home/mrinaldas/nodejs-tes/c.txt /home/mrinaldas/nodejs-tes/d.txt /home/mrinaldas/nodejs-tes/e.txt /home/mrinaldas/nodejs-tes/f.txt /home/mrinaldas/nodejs-tes/g.txt /home/mrinaldas/nodejs-tes/h.txt /home/mrinaldas/nodejs-tes/i.txt /home/mrinaldas/nodejs-tes/j.txt /home/mrinaldas/nodejs-tes/k.txt /home/mrinaldas/nodejs-tes/l.txt /home/mrinaldas/nodejs-tes/m.txt /home/mrinaldas/nodejs-tes/n.txt /home/mrinaldas/nodejs-tes/o.txt /home/mrinaldas/nodejs-tes/p.txt /home/mrinaldas/nodejs-tes/q.txt /home/mrinaldas/nodejs-tes/r.txt /home/mrinaldas/nodejs-tes/s.txt /home/mrinaldas/nodejs-tes/t.txt /home/mrinaldas/nodejs-tes/u.txt /home/mrinaldas/nodejs-tes/v.txt /home/mrinaldas/nodejs-tes/w.txt /home/mrinaldas/nodejs-tes/x.txt /home/mrinaldas/nodejs-tes/y.txt /home/mrinaldas/nodejs-tes/z.txt], delFiles: [], isForcePush: true
 •  Syncing files to the component  ...
I0701 14:37:16.746389   25672 adapter.go:151] Creating /projects/nodejs-starter on the remote container if it doesn't already exist
I0701 14:37:16.746439   25672 exec.go:27] Executing command [mkdir -p /projects/nodejs-starter] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
I0701 14:37:20.328190   25672 adapter.go:178] Copying files /home/mrinaldas/nodejs-tes/a.txt /home/mrinaldas/nodejs-tes/b.txt /home/mrinaldas/nodejs-tes/c.txt /home/mrinaldas/nodejs-tes/d.txt /home/mrinaldas/nodejs-tes/e.txt /home/mrinaldas/nodejs-tes/f.txt /home/mrinaldas/nodejs-tes/g.txt /home/mrinaldas/nodejs-tes/h.txt /home/mrinaldas/nodejs-tes/i.txt /home/mrinaldas/nodejs-tes/j.txt /home/mrinaldas/nodejs-tes/k.txt /home/mrinaldas/nodejs-tes/l.txt /home/mrinaldas/nodejs-tes/m.txt /home/mrinaldas/nodejs-tes/n.txt /home/mrinaldas/nodejs-tes/o.txt /home/mrinaldas/nodejs-tes/p.txt /home/mrinaldas/nodejs-tes/q.txt /home/mrinaldas/nodejs-tes/r.txt /home/mrinaldas/nodejs-tes/s.txt /home/mrinaldas/nodejs-tes/t.txt /home/mrinaldas/nodejs-tes/u.txt /home/mrinaldas/nodejs-tes/v.txt /home/mrinaldas/nodejs-tes/w.txt /home/mrinaldas/nodejs-tes/x.txt /home/mrinaldas/nodejs-tes/y.txt /home/mrinaldas/nodejs-tes/z.txt to pod
I0701 14:37:20.328285   25672 sync.go:34] CopyFile arguments: localPath /home/mrinaldas/nodejs-tes, dest /projects/nodejs-starter/nodejs-tes, targetPath /projects/nodejs-starter, copyFiles [/home/mrinaldas/nodejs-tes/a.txt /home/mrinaldas/nodejs-tes/b.txt /home/mrinaldas/nodejs-tes/c.txt /home/mrinaldas/nodejs-tes/d.txt /home/mrinaldas/nodejs-tes/e.txt /home/mrinaldas/nodejs-tes/f.txt /home/mrinaldas/nodejs-tes/g.txt /home/mrinaldas/nodejs-tes/h.txt /home/mrinaldas/nodejs-tes/i.txt /home/mrinaldas/nodejs-tes/j.txt /home/mrinaldas/nodejs-tes/k.txt /home/mrinaldas/nodejs-tes/l.txt /home/mrinaldas/nodejs-tes/m.txt /home/mrinaldas/nodejs-tes/n.txt /home/mrinaldas/nodejs-tes/o.txt /home/mrinaldas/nodejs-tes/p.txt /home/mrinaldas/nodejs-tes/q.txt /home/mrinaldas/nodejs-tes/r.txt /home/mrinaldas/nodejs-tes/s.txt /home/mrinaldas/nodejs-tes/t.txt /home/mrinaldas/nodejs-tes/u.txt /home/mrinaldas/nodejs-tes/v.txt /home/mrinaldas/nodejs-tes/w.txt /home/mrinaldas/nodejs-tes/x.txt /home/mrinaldas/nodejs-tes/y.txt /home/mrinaldas/nodejs-tes/z.txt], globalExps [/home/mrinaldas/nodejs-tes/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/home/mrinaldas/nodejs-tes/.odo/env]
I0701 14:37:20.328379   25672 pods.go:140] Executing command tar xf - -C /projects/nodejs-starter
I0701 14:37:20.328558   25672 sync.go:75] makeTar arguments: srcPath: /home/mrinaldas/nodejs-tes, destPath: /projects/nodejs-starter/nodejs-tes, files: [/home/mrinaldas/nodejs-tes/a.txt /home/mrinaldas/nodejs-tes/b.txt /home/mrinaldas/nodejs-tes/c.txt /home/mrinaldas/nodejs-tes/d.txt /home/mrinaldas/nodejs-tes/e.txt /home/mrinaldas/nodejs-tes/f.txt /home/mrinaldas/nodejs-tes/g.txt /home/mrinaldas/nodejs-tes/h.txt /home/mrinaldas/nodejs-tes/i.txt /home/mrinaldas/nodejs-tes/j.txt /home/mrinaldas/nodejs-tes/k.txt /home/mrinaldas/nodejs-tes/l.txt /home/mrinaldas/nodejs-tes/m.txt /home/mrinaldas/nodejs-tes/n.txt /home/mrinaldas/nodejs-tes/o.txt /home/mrinaldas/nodejs-tes/p.txt /home/mrinaldas/nodejs-tes/q.txt /home/mrinaldas/nodejs-tes/r.txt /home/mrinaldas/nodejs-tes/s.txt /home/mrinaldas/nodejs-tes/t.txt /home/mrinaldas/nodejs-tes/u.txt /home/mrinaldas/nodejs-tes/v.txt /home/mrinaldas/nodejs-tes/w.txt /home/mrinaldas/nodejs-tes/x.txt /home/mrinaldas/nodejs-tes/y.txt /home/mrinaldas/nodejs-tes/z.txt]
 ✓  Syncing files to the component [9s]

Executing devfile commands for component nodejs
 •  Executing install command "npm install"  ...
I0701 14:37:25.448374   25672 exec.go:27] Executing command [/bin/sh -c cd ${CHE_PROJECTS_ROOT}/nodejs-starter && npm install] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
up to date in 0.212s
found 0 vulnerabilities

npm WARN saveError ENOENT: no such file or directory, open '/projects/nodejs-starter/package.json'
npm notice created a lockfile as package-lock.json. You should commit this file.
npm WARN enoent ENOENT: no such file or directory, open '/projects/nodejs-starter/package.json'
npm WARN nodejs-starter No description
npm WARN nodejs-starter No repository field.
npm WARN nodejs-starter No README data
npm WARN nodejs-starter No license field.

 ✓  Executing install command "npm install" [5s]
I0701 14:37:30.159353   25672 adapter.go:377] Executing devfile command run
 •  Executing run command "npm start"  ...
I0701 14:37:30.159433   25672 exec.go:27] Executing command [/opt/odo/bin/supervisord ctl stop all] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
devrun                                     OK
debugrun                                   OK
I0701 14:37:33.435626   25672 exec.go:27] Executing command [/opt/odo/bin/supervisord ctl start devrun] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
devrun: started
 ✓  Executing run command "npm start" [8s]
Waiting for something to change in /home/mrinaldas/nodejs-tes
I0701 14:37:38.555284   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/a.txt": REMOVE
I0701 14:37:38.555584   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/a.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.555635   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/a.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/a.txt
I0701 14:37:38.555735   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/a.txt": REMOVE
I0701 14:37:38.555792   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/a.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.555828   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/a.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/a.txt
I0701 14:37:38.555990   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/b.txt": REMOVE
I0701 14:37:38.556048   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/b.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556079   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/b.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/b.txt
I0701 14:37:38.556121   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/b.txt": REMOVE
I0701 14:37:38.556162   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/b.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556199   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/b.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/b.txt
I0701 14:37:38.556278   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/c.txt": REMOVE
I0701 14:37:38.556373   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/c.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556430   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/c.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/c.txt
I0701 14:37:38.556487   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/c.txt": REMOVE
I0701 14:37:38.556532   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/c.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556561   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/c.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/c.txt
I0701 14:37:38.556585   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/d.txt": REMOVE
I0701 14:37:38.556624   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/d.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556654   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/d.txt: invalid argument
I0701 14:37:38.556689   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.556726   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556753   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.556780   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/e.txt": REMOVE
I0701 14:37:38.556818   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/e.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556845   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/e.txt: invalid argument
I0701 14:37:38.556883   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.556923   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.556949   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.556977   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/f.txt": REMOVE
I0701 14:37:38.557015   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/f.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557043   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/f.txt: invalid argument
I0701 14:37:38.557117   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.557214   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557264   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.557311   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/g.txt": REMOVE
I0701 14:37:38.557378   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/g.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557428   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/g.txt: invalid argument
I0701 14:37:38.557512   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.557578   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557631   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.557663   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/h.txt": REMOVE
I0701 14:37:38.557707   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/h.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557735   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/h.txt: invalid argument
I0701 14:37:38.557772   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.557806   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557832   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.557860   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/i.txt": REMOVE
I0701 14:37:38.557898   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/i.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.557925   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/i.txt: invalid argument
I0701 14:37:38.557959   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.557996   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558022   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.558051   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/j.txt": REMOVE
I0701 14:37:38.558090   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/j.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558117   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/j.txt: invalid argument
I0701 14:37:38.558146   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.558181   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558207   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.558234   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/k.txt": REMOVE
I0701 14:37:38.558271   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/k.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558297   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/k.txt: invalid argument
I0701 14:37:38.558360   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.558489   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558537   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.558575   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/l.txt": REMOVE
I0701 14:37:38.558626   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/l.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558666   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/l.txt: invalid argument
I0701 14:37:38.558705   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.558750   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558783   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.558810   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/m.txt": REMOVE
I0701 14:37:38.558855   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/m.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.558889   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/m.txt: invalid argument
I0701 14:37:38.558960   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.559025   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559057   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.559086   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/n.txt": REMOVE
I0701 14:37:38.559125   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/n.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559155   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/n.txt: invalid argument
I0701 14:37:38.559193   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.559244   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559277   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.559309   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/o.txt": REMOVE
I0701 14:37:38.559354   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/o.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559388   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/o.txt: invalid argument
I0701 14:37:38.559423   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.559502   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559541   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.559573   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/p.txt": REMOVE
I0701 14:37:38.559620   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/p.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559653   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/p.txt: invalid argument
I0701 14:37:38.559687   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.559729   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559760   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.559791   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/q.txt": REMOVE
I0701 14:37:38.559835   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/q.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559868   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/q.txt: invalid argument
I0701 14:37:38.559902   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.559944   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.559976   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.560007   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/r.txt": REMOVE
I0701 14:37:38.560049   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/r.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560082   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/r.txt: invalid argument
I0701 14:37:38.560109   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.560151   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560181   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.560253   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/s.txt": REMOVE
I0701 14:37:38.560337   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/s.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560376   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/s.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/s.txt
I0701 14:37:38.560415   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/s.txt": REMOVE
I0701 14:37:38.560487   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/s.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560521   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/s.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/s.txt
I0701 14:37:38.560554   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/t.txt": REMOVE
I0701 14:37:38.560599   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/t.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560630   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/t.txt: invalid argument
I0701 14:37:38.560675   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.560715   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560752   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.560790   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/u.txt": REMOVE
I0701 14:37:38.560833   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/u.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.560863   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/u.txt: invalid argument
I0701 14:37:38.560921   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.561033   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561101   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.561157   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/v.txt": REMOVE
I0701 14:37:38.561231   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/v.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561287   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/v.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/v.txt
I0701 14:37:38.561341   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/v.txt": REMOVE
I0701 14:37:38.561411   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/v.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561481   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/v.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/v.txt
I0701 14:37:38.561537   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/w.txt": REMOVE
I0701 14:37:38.561584   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/w.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561613   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/w.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/w.txt
I0701 14:37:38.561641   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/w.txt": REMOVE
I0701 14:37:38.561678   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/w.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561703   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/w.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/w.txt
I0701 14:37:38.561785   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/x.txt": REMOVE
I0701 14:37:38.561844   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/x.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561880   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/x.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/x.txt
I0701 14:37:38.561924   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/x.txt": REMOVE
I0701 14:37:38.561967   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/x.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.561994   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/x.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/x.txt
I0701 14:37:38.562024   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/y.txt": REMOVE
I0701 14:37:38.562060   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/y.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.562090   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/y.txt: invalid argument
I0701 14:37:38.562170   25672 watch.go:187] filesystem watch event: "": REMOVE
I0701 14:37:38.562252   25672 watch.go:229] Matching  with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.562291   25672 watch.go:246] error removing watch for : can't remove non-existent inotify watch for: .
I0701 14:37:38.562321   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/z.txt": REMOVE
I0701 14:37:38.562364   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/z.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.562394   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/z.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/z.txt
I0701 14:37:38.562516   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/z.txt": REMOVE
I0701 14:37:38.562568   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/z.txt with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.562594   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/z.txt: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/z.txt
I0701 14:37:38.562621   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/{1...9}": REMOVE
I0701 14:37:38.562660   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/{1...9} with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.562686   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/{1...9}: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/{1...9}
I0701 14:37:38.562952   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/sedLWN1I6": RENAME
I0701 14:37:38.563001   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/sedLWN1I6 with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.563031   25672 watch.go:246] error removing watch for /home/mrinaldas/nodejs-tes/sedLWN1I6: can't remove non-existent inotify watch for: /home/mrinaldas/nodejs-tes/sedLWN1I6
I0701 14:37:38.563062   25672 watch.go:187] filesystem watch event: "/home/mrinaldas/nodejs-tes/server.js": CREATE
I0701 14:37:38.563114   25672 watch.go:229] Matching /home/mrinaldas/nodejs-tes/server.js with [/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/.odo/env]. Matched false, err: <nil>
I0701 14:37:38.563163   25672 watch.go:77] adding watch on path /home/mrinaldas/nodejs-tes/server.js
File /home/mrinaldas/nodejs-tes/sedLWN1I6 changed
File /home/mrinaldas/nodejs-tes/server.js changed
Pushing files...
I0701 14:37:39.975262   25672 watch.go:313] Copying files [/home/mrinaldas/nodejs-tes/sedLWN1I6 /home/mrinaldas/nodejs-tes/server.js] to pod
I0701 14:37:40.398942   25672 pods.go:30] Waiting for component=nodejs pod
I0701 14:37:40.715506   25672 pods.go:57] Status of nodejs-5f5688dddf-z6ppx pod is Running
I0701 14:37:40.715556   25672 pods.go:63] Pod nodejs-5f5688dddf-z6ppx is Running

Validation
 •  Validating the devfile  ...
I0701 14:37:40.715813   25672 command.go:65] the command group of kind "init" is not found in the devfile
I0701 14:37:40.715844   25672 command.go:205] No init command was provided
I0701 14:37:40.715866   25672 utils.go:98] Found component "Container" with name "runtime"
I0701 14:37:40.715896   25672 command.go:222] Build command: install
I0701 14:37:40.715915   25672 utils.go:98] Found component "Container" with name "runtime"
I0701 14:37:40.715935   25672 command.go:229] Run command: run
 ✓  Validating the devfile [232884ns]

Creating Kubernetes resources for component nodejs
I0701 14:37:40.716020   25672 utils.go:98] Found component "Container" with name "runtime"
I0701 14:37:40.716200   25672 utils.go:98] Found component "Container" with name "runtime"
I0701 14:37:40.716227   25672 utils.go:98] Found component "Container" with name "runtime"
I0701 14:37:40.716245   25672 utils.go:238] Updating container runtime entrypoint with supervisord
I0701 14:37:40.716274   25672 utils.go:135] Updating container runtime with supervisord volume mounts
I0701 14:37:40.716303   25672 utils.go:145] Updating container runtime env with run command
I0701 14:37:40.716331   25672 utils.go:154] Updating container runtime env with run command's workdir
I0701 14:37:40.716353   25672 utils.go:190] Updating container runtime env with debug command
I0701 14:37:40.716371   25672 utils.go:199] Updating container runtime env with debug command's workdir
I0701 14:37:40.716388   25672 utils.go:208] Updating container runtime env with debug command's debugPort
I0701 14:37:40.716412   25672 utils.go:98] Found component "Container" with name "runtime"
I0701 14:37:40.716472   25672 adapter.go:250] Creating deployment nodejs
I0701 14:37:40.716500   25672 adapter.go:251] The component name is nodejs
I0701 14:37:42.939734   25672 adapter.go:255] The component already exists, attempting to update it
I0701 14:37:43.262994   25672 adapter.go:260] Successfully updated component nodejs
I0701 14:37:44.082389   25672 adapter.go:282] Successfully update Service for component nodejs
I0701 14:37:44.082425   25672 deployments.go:47] Waiting for nodejs deployment rollout
 •  Waiting for component to start  ...
 ✓  Waiting for component to start [616ms]
I0701 14:37:44.699245   25672 deployments.go:84] Deployment "nodejs" successfully rolled out
I0701 14:37:44.699260   25672 deployments.go:88] Waiting for deployment spec update to be observed...
I0701 14:37:44.699300   25672 pods.go:30] Waiting for component=nodejs pod
I0701 14:37:46.543153   25672 pods.go:57] Status of nodejs-5f5688dddf-z6ppx pod is Running
I0701 14:37:46.543204   25672 pods.go:63] Pod nodejs-5f5688dddf-z6ppx is Running
I0701 14:37:46.543372   25672 preference.go:182] The path for preference file is /home/mrinaldas/.odo/preference.yaml
I0701 14:37:48.180030   25672 url.go:402] Listing ingresses with label selector: app.kubernetes.io/instance=nodejs
I0701 14:37:48.794056   25672 url.go:379] Listing routes with label selector: app.kubernetes.io/part-of=,app.kubernetes.io/instance=nodejs
I0701 14:37:48.794095   25672 occlient.go:2684] Listing routes with label selector: app.kubernetes.io/part-of=,app.kubernetes.io/instance=nodejs

Applying URL changes
 ✓  URLs are synced with the cluster, no changes are required.

Syncing to component nodejs
I0701 14:37:49.408271   25672 adapter.go:129] Push: componentName: nodejs, path: /home/mrinaldas/nodejs-tes, files: [/home/mrinaldas/nodejs-tes/sedLWN1I6 /home/mrinaldas/nodejs-tes/server.js], delFiles: [a.txt a.txt b.txt b.txt c.txt c.txt d.txt e.txt f.txt g.txt h.txt i.txt j.txt k.txt l.txt m.txt n.txt o.txt p.txt q.txt r.txt s.txt s.txt t.txt u.txt v.txt v.txt w.txt w.txt x.txt x.txt y.txt z.txt z.txt {1...9} sedLWN1I6], isForcePush: false
 •  Syncing files to the component  ...
I0701 14:37:49.408561   25672 adapter.go:151] Creating /projects/nodejs-starter on the remote container if it doesn't already exist
I0701 14:37:49.408603   25672 exec.go:27] Executing command [mkdir -p /projects/nodejs-starter] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
I0701 14:37:53.299715   25672 adapter.go:223] remote files marked for deletion are [/projects/nodejs-starter/a.txt /projects/nodejs-starter/a.txt /projects/nodejs-starter/b.txt /projects/nodejs-starter/b.txt /projects/nodejs-starter/c.txt /projects/nodejs-starter/c.txt /projects/nodejs-starter/d.txt /projects/nodejs-starter/e.txt /projects/nodejs-starter/f.txt /projects/nodejs-starter/g.txt /projects/nodejs-starter/h.txt /projects/nodejs-starter/i.txt /projects/nodejs-starter/j.txt /projects/nodejs-starter/k.txt /projects/nodejs-starter/l.txt /projects/nodejs-starter/m.txt /projects/nodejs-starter/n.txt /projects/nodejs-starter/o.txt /projects/nodejs-starter/p.txt /projects/nodejs-starter/q.txt /projects/nodejs-starter/r.txt /projects/nodejs-starter/s.txt /projects/nodejs-starter/s.txt /projects/nodejs-starter/t.txt /projects/nodejs-starter/u.txt /projects/nodejs-starter/v.txt /projects/nodejs-starter/v.txt /projects/nodejs-starter/w.txt /projects/nodejs-starter/w.txt /projects/nodejs-starter/x.txt /projects/nodejs-starter/x.txt /projects/nodejs-starter/y.txt /projects/nodejs-starter/z.txt /projects/nodejs-starter/z.txt /projects/nodejs-starter/{1...9} /projects/nodejs-starter/sedLWN1I6]
I0701 14:37:53.299750   25672 exec.go:27] Executing command [rm -rf /projects/nodejs-starter/a.txt /projects/nodejs-starter/a.txt /projects/nodejs-starter/b.txt /projects/nodejs-starter/b.txt /projects/nodejs-starter/c.txt /projects/nodejs-starter/c.txt /projects/nodejs-starter/d.txt /projects/nodejs-starter/e.txt /projects/nodejs-starter/f.txt /projects/nodejs-starter/g.txt /projects/nodejs-starter/h.txt /projects/nodejs-starter/i.txt /projects/nodejs-starter/j.txt /projects/nodejs-starter/k.txt /projects/nodejs-starter/l.txt /projects/nodejs-starter/m.txt /projects/nodejs-starter/n.txt /projects/nodejs-starter/o.txt /projects/nodejs-starter/p.txt /projects/nodejs-starter/q.txt /projects/nodejs-starter/r.txt /projects/nodejs-starter/s.txt /projects/nodejs-starter/s.txt /projects/nodejs-starter/t.txt /projects/nodejs-starter/u.txt /projects/nodejs-starter/v.txt /projects/nodejs-starter/v.txt /projects/nodejs-starter/w.txt /projects/nodejs-starter/w.txt /projects/nodejs-starter/x.txt /projects/nodejs-starter/x.txt /projects/nodejs-starter/y.txt /projects/nodejs-starter/z.txt /projects/nodejs-starter/z.txt /projects/nodejs-starter/{1...9} /projects/nodejs-starter/sedLWN1I6] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
I0701 14:37:56.782259   25672 adapter.go:178] Copying files /home/mrinaldas/nodejs-tes/sedLWN1I6 /home/mrinaldas/nodejs-tes/server.js to pod
I0701 14:37:56.782330   25672 sync.go:34] CopyFile arguments: localPath /home/mrinaldas/nodejs-tes, dest /projects/nodejs-starter/nodejs-tes, targetPath /projects/nodejs-starter, copyFiles [/home/mrinaldas/nodejs-tes/sedLWN1I6 /home/mrinaldas/nodejs-tes/server.js], globalExps [/home/mrinaldas/nodejs-tes/home/mrinaldas/nodejs-tes/.git /home/mrinaldas/nodejs-tes/home/mrinaldas/nodejs-tes/.odo/env]
I0701 14:37:56.782392   25672 pods.go:140] Executing command tar xf - -C /projects/nodejs-starter
I0701 14:37:56.782569   25672 sync.go:75] makeTar arguments: srcPath: /home/mrinaldas/nodejs-tes, destPath: /projects/nodejs-starter/nodejs-tes, files: [/home/mrinaldas/nodejs-tes/sedLWN1I6 /home/mrinaldas/nodejs-tes/server.js]
I0701 14:37:56.782677   25672 sync.go:89] Got abs path: /home/mrinaldas/nodejs-tes/server.js
I0701 14:37:56.782700   25672 sync.go:90] Making /home/mrinaldas/nodejs-tes relative to /home/mrinaldas/nodejs-tes/server.js
I0701 14:37:56.782727   25672 sync.go:102] makeTar srcFile: nodejs-tes/server.js
I0701 14:37:56.782747   25672 sync.go:103] makeTar destFile: server.js
I0701 14:37:56.782768   25672 sync.go:121] recursiveTar arguments: srcBase: /home/mrinaldas, srcFile: nodejs-tes/server.js, destBase: /projects/nodejs-starter, destFile: server.js
I0701 14:37:56.782788   25672 sync.go:127] Corrected destinations: base: /projects/nodejs-starter file: server.js
 ✓  Syncing files to the component [12s]

Executing devfile commands for component nodejs
 •  Executing install command "npm install"  ...
I0701 14:38:01.521452   25672 exec.go:27] Executing command [/bin/sh -c cd ${CHE_PROJECTS_ROOT}/nodejs-starter && npm install] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
npm WARN saveError ENOENT: no such file or directory, open '/projects/nodejs-starter/package.json'
npm WARN enoent ENOENT: no such file or directory, open '/projects/nodejs-starter/package.json'
npm WARN nodejs-starter No description
npm WARN nodejs-starter No repository field.
npm WARN nodejs-starter No README data
npm WARN nodejs-starter No license field.

up to date in 0.227s
found 0 vulnerabilities

 ✓  Executing install command "npm install" [5s]
I0701 14:38:06.511242   25672 adapter.go:377] Executing devfile command run
 •  Executing run command "npm start"  ...
I0701 14:38:06.511313   25672 exec.go:27] Executing command [/opt/odo/bin/supervisord ctl stop all] for pod: nodejs-5f5688dddf-z6ppx in container: runtime
devrun                                     OK
debugrun                                   OK
I0701 14:38:10.916248   25672 exec.go:27] Executing command [/opt/odo/bin/supervisord ctl s

Also the above script has some problems. mkdir {1...9} doesn't create folders named from 1 to 9, it creates a folder named {1...9}. Also the sed command in incorrect. The correct one is sed -i "s/Hello/Hello 1/g" server.js. Even after the fixes, watch worked normally for me as the file changes were detected.

@amitkrout
Copy link
Contributor Author

Also the above script has some problems. mkdir {1...9} doesn't create folders named from 1 to 9, it creates a folder named {1...9}.

yes, you are right but it does not matter as i am able to reproduce the issue with this script.

Also the sed command in incorrect. The correct one is sed -i "s/Hello/Hello 1/g" server.js. Even after the fixes, watch worked normally for me as the file changes were detected.

sed command flag works differently on macOS on which i tested.

@mik-dass Its a flake, so try till reproduce it 😉

@mik-dass
Copy link
Contributor

mik-dass commented Jul 6, 2020

@mik-dass Its a flake, so try till reproduce it wink

I have tried multiple times, still couldn't. Please provide the full verbose logs till the point of failure.

@amitkrout
Copy link
Contributor Author

It appears here again - #3462 (comment)

@amitkrout
Copy link
Contributor Author

@mik-dass Its a flake, so try till reproduce it wink

I have tried multiple times, still couldn't. Please provide the full verbose logs till the point of failure.

@mik-dass Can you try by adding more create, delete and replace text command in the same shell script. Again i am saying its a flake. Do not loose hope, i am sure you will be able to reproduce it in few more try.

@mik-dass
Copy link
Contributor

mik-dass commented Jul 7, 2020

Again i am saying its a flake. Do not loose hope, i am sure you will be able to reproduce it in few more try.

If you can reproduce it, please paste the verbose watch output.

Can you try by adding more create, delete and replace text command in the same shell script.

Please update the script and add more commands.

@mik-dass
Copy link
Contributor

mik-dass commented Jul 7, 2020

It appears here again - #3462 (comment)

I think it happens due to #3479 for devfile components.

@mik-dass
Copy link
Contributor

mik-dass commented Jul 9, 2020

None of the attempts of mine have been successful so far. Push triggered by watch is never stuck for me after running the script. I have tried even by changing the sleep value in the script.

@mik-dass mik-dass moved this from To do to In progress in Sprint 186 Jul 9, 2020
@kadel kadel removed the kind/bug Categorizes issue or PR as related to a bug. label Jul 10, 2020
@amitkrout
Copy link
Contributor Author

None of the attempts of mine have been successful so far. Push triggered by watch is never stuck for me after running the script. I have tried even by changing the sleep value in the script.

I also spend some time to reproduce it. None of the time i hit the issue out of 6 try.

@mik-dass The same issue is hit in one of my pr. You can see the verbose log here at - https://deck-ci.apps.ci.l2s4.p1.openshiftapps.com/view/gcs/origin-ci-test/pr-logs/pull/openshift_odo/3546/pull-ci-openshift-odo-master-v4.2-integration-e2e/1281939561458438144#1:build-log.txt%3A565

@mik-dass
Copy link
Contributor

@mik-dass The same issue is hit in one of my pr. You can see the verbose log here at - https://deck-ci.apps.ci.l2s4.p1.openshiftapps.com/view/gcs/origin-ci-test/pr-logs/pull/openshift_odo/3546/pull-ci-openshift-odo-master-v4.2-integration-e2e/1281939561458438144#1:build-log.txt%3A565

I0711 15:35:08.627538   23402 watch.go:187] filesystem watch event: "/tmp/354248242/server.js": WRITE
I0711 15:35:08.627563   23402 watch.go:229] Matching /tmp/354248242/server.js with [/tmp/354248242/.git /tmp/354248242/.odo/odo-file-index.json]. Matched false, err: <nil>
I0711 15:35:08.627587   23402 watch.go:77] adding watch on path /tmp/354248242/server.js
 ✗  readdirent: no such file or directory

This is a different error than the one reported here.

@amitkrout
Copy link
Contributor Author

@mik-dass The same issue is hit in one of my pr. You can see the verbose log here at - https://deck-ci.apps.ci.l2s4.p1.openshiftapps.com/view/gcs/origin-ci-test/pr-logs/pull/openshift_odo/3546/pull-ci-openshift-odo-master-v4.2-integration-e2e/1281939561458438144#1:build-log.txt%3A565

I0711 15:35:08.627538   23402 watch.go:187] filesystem watch event: "/tmp/354248242/server.js": WRITE
I0711 15:35:08.627563   23402 watch.go:229] Matching /tmp/354248242/server.js with [/tmp/354248242/.git /tmp/354248242/.odo/odo-file-index.json]. Matched false, err: <nil>
I0711 15:35:08.627587   23402 watch.go:77] adding watch on path /tmp/354248242/server.js
 ✗  readdirent: no such file or directory

This is a different error than the one reported here.

@mik-dass Yes, but see the end result. It is exactly the same what i was reproduced locally. When i reproduced it for first time, similar error i was getting while curling the url and searching for the updated string. The new string was not updated because push stuck in between as i mentioned above and also attached the screen shoot for reference.

In CI we hit the same error but in a different way. may be what i reproduce locally is rare case but the error we hit in CI is the real flake that we have been trying to reproduce since months. So you can look into the recent failure CI steps in a followup issue. I will try to reproduce it again whenever i will get time.

@mik-dass
Copy link
Contributor

When i reproduced it for first time, similar error i was getting while curling the url and searching for the updated string. The new string was not updated because push stuck in between as i mentioned above and also attached the screen shoot for reference.

The new string was not updated maybe because of #3479.

@mik-dass Yes, but see the end result. It is exactly the same what i was reproduced locally.

This makes it a completely different issue then the current one. Also in the above screenshots or logs it is not clear at all. We should open a new issue with the logs.

@amitkrout
Copy link
Contributor Author

This makes it a completely different issue then the current one. Also in the above screenshots or logs it is not clear at all. We should open a new issue with the logs.

As i mentioned above I will try to reproduce it again with all the verbose log whenever i will get time.

@mik-dass
Copy link
Contributor

As i mentioned above I will try to reproduce it again with all the verbose log whenever i will get time.

Please till then close this issue and open a new issue later on as the error you mentioned in the above logs is completely different.

@amitkrout
Copy link
Contributor Author

error you mentioned in the above logs is completely different.

i can read it from here #3307 (comment) aswell.

Please till then close this issue and open a new issue later on

So you need the same steps #3307 (comment) in another issue with verbose log ?

@mik-dass
Copy link
Contributor

i can read it from here #3307 (comment) as well.

Yes and the description of this issue is about watch being stuck which is not evident from the logs at all. This is a different error.

So you need the same steps #3307 (comment) in another issue with verbose log ?

Yes. Or maybe just rename the title and description of the issue to match what's in the logs here #3307 (comment).

@amitkrout
Copy link
Contributor Author

Now i think i made a mistake by adding a similar failure for reference which is already filed. TBH now i am afraid of updating issue with reference that looks similar to it

Sprint 186 automation moved this from In progress to Done Jul 13, 2020
@amitkrout amitkrout changed the title component watch stuck over multiple file changes in a single shoot [duplicate] odo watch deletes all files Jul 13, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
flake Categorizes issue or PR as related to a flaky test. priority/High Important issue; should be worked on before any other issues (except priority/Critical issue(s)).
Projects
No open projects
Sprint 186
  
Done
Development

Successfully merging a pull request may close this issue.

5 participants