Watchr: On update: docpad generates 0 files. #435

Closed
kr1zmo opened this Issue Feb 22, 2013 · 22 comments

Projects

None yet

2 participants

@kr1zmo

Running NODE v0.8.20 on Debian.

When first running docpad everything seems fine. After I open and edit 1 file it regenerates 0 files.

www@deb-server:~$ lsb_release -a
No LSB modules are available.
Distributor ID: Debian
Description:    Debian GNU/Linux 6.0.6 (squeeze)
Release:    6.0.6
Codename:   squeeze
www@deb-server:~$ 

dump:

info: Welcome to DocPad v6.21.10
info: Plugins: coffeescript, eco, livereload, marked, partials, stylus, text
info: Environment: development
info: DocPad listening to http://localhost:9778/ on directory /var/local/www/project/site/out
info: LiveReload listening to new socket on channel /docpad-livereload with log level 1
info: Generating...
info: Generated all 18 files in 0.185 seconds
info: Watching setup starting...
info: Watching setup
info: The action completed successfully



info: DocPad listening to http://localhost:9778/ on directory /var/local/www/project/site/out
info: LiveReload listening to new socket on channel /docpad-livereload with log level 1
info: Generating...
info: Generated all 18 files in 0.168 seconds
info: Watching setup starting...
info: Watching setup
info: The action completed successfully
info: Regenerating at 19:34:24
info: Generating...
info: Generated 0 files in 0.002 seconds
info: Regenerated at 19:34:24

@kr1zmo

So oddly when using transmit this occurs, but not when using the same connection scheme on sublime text sftp. I may make a video showcasing the confusion. I have no idea.

@balupton
DocPad member

Ok cool, a video would help, also can you run docpad in debug mode by using the -d flag (e.g. docpad run -d) then post the output to something like gist or pastie. That'll give us more detail about what could be going on. Thanks :)

@kr1zmo

Video: In the video, I say mongoose models: I mean't backbone models. http://youtu.be/kzS4wcU1Xxk

Not working: Used Transmit + Sublime text.

debug: Change detected at 04:14:49 create /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:49.875] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Adding document: /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:49.876] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Change detected at 04:14:49 update /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:49.882] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Regenerating at 04:14:49
    → [2013-02-25 04:14:49.982] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Performing the action generate
    → [2013-02-25 04:14:49.983] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Generating...
    → [2013-02-25 04:14:49.984] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading 1 files
    → [2013-02-25 04:14:49.988] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading file: index.html_safe_save_042128.eco
    → [2013-02-25 04:14:49.998] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading the file: /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:49.999] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded the file: /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:50.003] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded file: index.html_safe_save_042128.eco
    → [2013-02-25 04:14:50.006] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded 1 files
    → [2013-02-25 04:14:50.007] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Contextualizing 1 files
    → [2013-02-25 04:14:50.008] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Contextualized 1 files
    → [2013-02-25 04:14:50.010] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering 1 files
    → [2013-02-25 04:14:50.011] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering the file: /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:50.013] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering completed for: /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:50.015] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendered 1 files
    → [2013-02-25 04:14:50.016] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Writing 1 files
    → [2013-02-25 04:14:50.017] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Writing the rendered file: /var/local/www/project/site/out/index.html_safe_save_042128 utf8
    → [2013-02-25 04:14:50.018] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Change detected at 04:14:50 delete /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:14:50.020] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Delete the file: /var/local/www/project/site/out/index.html
    → [2013-02-25 04:14:50.021] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Wrote the rendered file: /var/local/www/project/site/out/index.html_safe_save_042128 utf8
    → [2013-02-25 04:14:50.022] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Wrote 1 files
    → [2013-02-25 04:14:50.023] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Generated 1 files in 0.036 seconds
    → [2013-02-25 04:14:50.024] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Performed the action generate
    → [2013-02-25 04:14:50.027] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Deleted the file: /var/local/www/project/site/out/index.html
    → [2013-02-25 04:14:50.028] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Regenerated at 04:14:50
    → [2013-02-25 04:14:50.029] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Change detected at 04:14:50 delete /var/local/www/project/site/src/documents/index.html_safe_save_042128.eco
    → [2013-02-25 04:14:50.062] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Delete the file: /var/local/www/project/site/out/index.html_safe_save_042128
    → [2013-02-25 04:14:50.063] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Deleted the file: /var/local/www/project/site/out/index.html_safe_save_042128
    → [2013-02-25 04:14:50.064] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Regenerating at 04:14:50
    → [2013-02-25 04:14:50.166] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Performing the action generate
    → [2013-02-25 04:14:50.167] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Generating...
    → [2013-02-25 04:14:50.169] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading 0 files
    → [2013-02-25 04:14:50.172] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded 0 files
    → [2013-02-25 04:14:50.173] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Contextualizing 0 files
    → [2013-02-25 04:14:50.174] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Contextualized 0 files
    → [2013-02-25 04:14:50.175] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering 0 files
    → [2013-02-25 04:14:50.176] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendered 0 files
    → [2013-02-25 04:14:50.178] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Writing 0 files
    → [2013-02-25 04:14:50.179] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Wrote 0 files
    → [2013-02-25 04:14:50.180] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Generated 0 files in 0.01 seconds
    → [2013-02-25 04:14:50.182] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Performed the action generate
    → [2013-02-25 04:14:50.184] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Regenerated at 04:14:50
    → [2013-02-25 04:14:50.186] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]

Working: Used Sublime Text.

debug: Change detected at 04:16:28 update /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:16:28.984] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Regenerating at 04:16:29
    → [2013-02-25 04:16:29.086] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Performing the action generate
    → [2013-02-25 04:16:29.087] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Generating...
    → [2013-02-25 04:16:29.089] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading 1 files
    → [2013-02-25 04:16:29.092] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading file: index.html.eco
    → [2013-02-25 04:16:29.093] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loading the file: /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:16:29.094] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded the file: /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:16:29.095] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Adding html file: /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:16:29.098] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded file: index.html.eco
    → [2013-02-25 04:16:29.099] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Loaded 1 files
    → [2013-02-25 04:16:29.100] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Contextualizing 1 files
    → [2013-02-25 04:16:29.101] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Contextualized 1 files
    → [2013-02-25 04:16:29.103] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering 1 files
    → [2013-02-25 04:16:29.104] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering the file: /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:16:29.105] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendering completed for: /var/local/www/project/site/src/documents/index.html.eco
    → [2013-02-25 04:16:29.107] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Rendered 1 files
    → [2013-02-25 04:16:29.108] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Writing 1 files
    → [2013-02-25 04:16:29.109] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Writing the rendered file: /var/local/www/project/site/out/index.html utf8
    → [2013-02-25 04:16:29.110] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Wrote the rendered file: /var/local/www/project/site/out/index.html utf8
    → [2013-02-25 04:16:29.111] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Wrote 1 files
    → [2013-02-25 04:16:29.112] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Generated 1 files in 0.02 seconds
    → [2013-02-25 04:16:29.112] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
debug: Performed the action generate
    → [2013-02-25 04:16:29.115] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
info: Regenerated at 04:16:29
    → [2013-02-25 04:16:29.116] [/usr/local/lib/node_modules/docpad/out/lib/docpad.js:1422] [DocPad.log]
@kr1zmo

Any testing that has been performed on this @balupton?

@balupton
DocPad member

I have a suspicion it could be related to the recent bevry/watchr issues in regards to the preferred watch method, however I've been pretty ill the last week and only recently recovering. Will need some more time before I'm able to look into this, but if my suspicion is correct then the advice in the watchr issues should help.

@balupton
DocPad member

With DocPad v6.27.0, try add the following to your docpad configuration file:

watchOptions: preferredMethods: ['watchFile','watch']

Does that fix the issue?

@kr1zmo
@balupton
DocPad member

Sweet, I'll close it for now. If it pops back up please re-open :) 🍰

@balupton balupton closed this Mar 25, 2013
@kr1zmo

Will you integrate this into the master in the future to avoid specifying the preferredMethods: ["watchFile", "watch"]?

Any what was occurring with the previous methods for detecting files?

@balupton
DocPad member

Yeah, it's something worth considering - there's more info here bevry/watchr#34

@balupton balupton reopened this Apr 3, 2013
@balupton
DocPad member

Hey @kr1zmo would I be able to remote into your machine sometime, as the trick with fixing this issue for good is first reproducing it - all the setups I've tried can't reproduce the problem.

@kr1zmo

Security mechanisms within my workplace would disallow this as well as policy, but I will see what I can do. Okay.

@balupton
DocPad member

Can you give v6.30.3 of DocPad a go, it will install with v2.4.1 of watchr that will hopefully fix the issue. Closing for now, will happily re-open if it is still present.

@balupton balupton closed this Apr 10, 2013
@kr1zmo

Nope, I did exactly what you said docpad@6.30.3 & watchr@2.4.3, the issue persists. I can post a video. I know you need access to a Debian server probably, what do you develop on?

@balupton
DocPad member

Okay, can you do the following:

git clone https://github.com/bevry/watchr.git watchr
cd watchr
npm i
cake compile
npm link

Then in the directory you are having the trouble:

watchr

That will run for about a minute, during that minute, perform the saving of files that you were doing before. Once watchr has exited, post the output on a gist and link it back here. No need to have DocPad open.

That will give me enough to go away and figure out where it is going wrong. If you can screencast this it will also help :) Thanks so much mate, looking forward to getting this resolved.

@balupton balupton reopened this Apr 11, 2013
@kr1zmo

Did this gist not help?

@balupton
DocPad member

Sorry, been in transit so haven't had a chance to look at this.

@kr1zmo

Okay.

@kr1zmo

With version: 6.45.0, when removing a file, the index page that list all items in the folder fails to regenerate.

src
  | documents
     | index.html.eco
     | writing/
       | test.html (remove this)
@kr1zmo

Here is the output from watchr:

a log message occured: { '0': 'debug',
  '1': 'watch event triggered on /Users/Joshua/Documents/Projects/test/docpad/src/documents/writing/test.html:',
  '2': [ 'rename', null ] }
a log message occured: { '0': 'debug',
  '1': 'determined delete:',
  '2': '/Users/Joshua/Documents/Projects/test/docpad/src/documents/writing/test.html' }
a log message occured: { '0': 'debug',
  '1': 'close: /Users/Joshua/Documents/Projects/test/docpad/src/documents/writing/test.html' }
a change event occured: { '0': 'delete',
  '1': '/Users/Joshua/Documents/Projects/test/docpad/src/documents/writing/test.html',
  '2': null,
  '3':
   { dev: 16777218,
     mode: 33188,
     nlink: 1,
     uid: 501,
     gid: 20,
     rdev: 0,
     blksize: 4096,
     ino: 3258280,
     size: 33,
     blocks: 8,
     atime: Sat Jul 06 2013 00:36:20 GMT-0400 (EDT),
     mtime: Sat Jul 06 2013 00:36:19 GMT-0400 (EDT),
     ctime: Sat Jul 06 2013 00:36:19 GMT-0400 (EDT) } }
a log message occured: { '0': 'debug',
  '1': 'watch event triggered on /Users/Joshua/Documents/Projects/test/docpad/src/documents/writing:',
  '2': [ 'rename', 'test.html' ] }
@balupton
DocPad member

Closing. Could be a bug in bevry/watchr. Follow it up there if it is still a problem.

@balupton balupton closed this Nov 28, 2013
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment