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.

Sign up for GitHub

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

Jump to bottom

pool is locked even if it is the first time to access the server #120

Closed
mookjp opened this issue Dec 28, 2014 · 3 comments
Closed

pool is locked even if it is the first time to access the server #120

mookjp opened this issue Dec 28, 2014 · 3 comments
Assignees
@ainoya
Labels
bug

Comments

@mookjp
Copy link
Owner

mookjp commented Dec 28, 2014

Below is the log when I access to http://1--0---0.pool.dev at first in my local Vagrant environment.

I think this was caused by our new hook.rb because builder is not kicked by front.
hook.rb is going to run builder when it got request even if it is request for image, css and etc.

pool$ build
BuilderLogDevice is initialized
I, [2014-12-29T01:31:29.834081 #52]  INFO -- : repository url: https://github.com/mookjp/flaskapp.git
I, [2014-12-29T01:31:29.834186 #52]  INFO -- : repository workspace_path exists: /app/images/app_repo
I, [2014-12-29T01:31:29.834431 #52]  INFO -- : Starting Git
I, [2014-12-29T01:31:31.383243 #52]  INFO -- : git fetch 'origin'  2>&1
D, [2014-12-29T01:31:31.383389 #52] DEBUG -- : 
I, [2014-12-29T01:31:31.383429 #52]  INFO -- : 
I, [2014-12-29T01:31:32.389267 #52]  INFO -- : git fetch 'origin'  2>&1
D, [2014-12-29T01:31:32.389394 #52] DEBUG -- : 
I, [2014-12-29T01:31:32.394539 #52]  INFO -- : git rev-parse '1--0--0'  2>&1
D, [2014-12-29T01:31:32.394704 #52] DEBUG -- : fatal: ambiguous argument '1--0--0': unknown revision or path not in the working tree.
I, [2014-12-29T01:31:32.401147 #52]  INFO -- : git branch '-a'  2>&1
D, [2014-12-29T01:31:32.401282 #52] DEBUG -- : * master
I, [2014-12-29T01:31:32.407477 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.407598 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.411601 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.411741 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.415974 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.416104 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.421102 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.421139 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.425243 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.425358 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.429644 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.429880 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.433664 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:31:32.433826 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:31:32.437572 #52]  INFO -- : git tag   2>&1
D, [2014-12-29T01:31:32.437691 #52] DEBUG -- : 1.0.0
I, [2014-12-29T01:31:32.441606 #52]  INFO -- : git show-ref '--tags' '-s' '1.0.0'  2>&1
D, [2014-12-29T01:31:32.441721 #52] DEBUG -- : 6ffb403c8725ad315e2d0c0b356f0e5813449598
I, [2014-12-29T01:31:32.441823 #52]  INFO -- : Initialized. Git commit id: 6ffb403c8725ad315e2d0c0b356f0e5813449598
I, [2014-12-29T01:31:32.441998 #52]  INFO -- : Build for 6ffb403c8725ad315e2d0c0b356f0e5813449598 ...
I, [2014-12-29T01:31:32.442053 #52]  INFO -- : repository url: https://github.com/mookjp/flaskapp.git
I, [2014-12-29T01:31:32.442108 #52]  INFO -- : repository workspace_path doesn't exist: /app/images/workspace/6ffb403c8725ad315e2d0c0b356f0e5813449598
I, [2014-12-29T01:31:32.442201 #52]  INFO -- : cloning: https://github.com/mookjp/flaskapp.git,6ffb403c8725ad315e2d0c0b356f0e5813449598,6ffb403c8725ad315e2d0c0b356f0e5813449598
I, [2014-12-29T01:31:34.168833 #52]  INFO -- : Note: checking out '6ffb403c8725ad315e2d0c0b356f0e5813449598'.
I, [2014-12-29T01:31:34.168967 #52]  INFO -- : Start building docker image...
I, [2014-12-29T01:31:34.233964 #52]  INFO -- : Sending build context to Docker daemon 111.1 kB
I, [2014-12-29T01:31:34.234303 #52]  INFO -- : Sending build context to Docker daemon 
I, [2014-12-29T01:31:34.285332 #52]  INFO -- : Step 0 : FROM ubuntu:14.04
I, [2014-12-29T01:31:35.796969 #52]  INFO -- : ubuntu:14.04: The image you are pulling has been verified
I, [2014-12-29T01:31:35.798519 #52]  INFO -- : 
I, [2014-12-29T01:31:35.798966 #52]  INFO -- : 
I, [2014-12-29T01:31:35.799264 #52]  INFO -- : 
I, [2014-12-29T01:31:35.799328 #52]  INFO -- : 
I, [2014-12-29T01:31:35.799987 #52]  INFO -- : 
BuilderLogDevice is initialized
I, [2014-12-29T01:32:40.913949 #52]  INFO -- : repository url: https://github.com/mookjp/flaskapp.git
I, [2014-12-29T01:32:40.914586 #52]  INFO -- : repository workspace_path exists: /app/images/app_repo
I, [2014-12-29T01:32:40.914851 #52]  INFO -- : Starting Git
I, [2014-12-29T01:32:42.739094 #52]  INFO -- : git fetch 'origin'  2>&1
D, [2014-12-29T01:32:42.739230 #52] DEBUG -- : 
I, [2014-12-29T01:32:42.739274 #52]  INFO -- : 
I, [2014-12-29T01:32:44.669484 #52]  INFO -- : git fetch 'origin'  2>&1
D, [2014-12-29T01:32:44.669484 #52] DEBUG -- : 
I, [2014-12-29T01:32:44.673172 #52]  INFO -- : git rev-parse '1--0--0'  2>&1
D, [2014-12-29T01:32:44.673331 #52] DEBUG -- : fatal: ambiguous argument '1--0--0': unknown revision or path not in the working tree.
I, [2014-12-29T01:32:44.679502 #52]  INFO -- : git branch '-a'  2>&1
D, [2014-12-29T01:32:44.679651 #52] DEBUG -- : * master
I, [2014-12-29T01:32:44.683106 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.683222 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.686971 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.687484 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.692934 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.693060 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.697163 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.697313 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.703919 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.704055 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.712191 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.712328 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.716400 #52]  INFO -- : git config '--list'  2>&1
D, [2014-12-29T01:32:44.716522 #52] DEBUG -- : url.https://.insteadof=git://
I, [2014-12-29T01:32:44.727395 #52]  INFO -- : git tag   2>&1
D, [2014-12-29T01:32:44.727588 #52] DEBUG -- : 1.0.0
I, [2014-12-29T01:32:44.744734 #52]  INFO -- : git show-ref '--tags' '-s' '1.0.0'  2>&1
D, [2014-12-29T01:32:44.744979 #52] DEBUG -- : 6ffb403c8725ad315e2d0c0b356f0e5813449598
I, [2014-12-29T01:32:44.745320 #52]  INFO -- : Initialized. Git commit id: 6ffb403c8725ad315e2d0c0b356f0e5813449598
I, [2014-12-29T01:32:44.745477 #52]  INFO -- : Locked! Other environment is under building process
I, [2014-12-29T01:32:44.745538 #52]  INFO -- : Please access after finishing another building process...
I, [2014-12-29T01:32:47.799097 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:32:50.858879 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:32:53.899835 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:32:56.957477 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:00.010180 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:03.051942 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:06.094459 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:09.137801 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:12.205152 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:15.250469 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:18.332928 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:21.393376 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:24.433460 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:27.479680 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:30.571284 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:33.625940 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:36.665476 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:39.720469 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:42.765042 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:45.821966 #52]  INFO -- : retrying.. container ready..
I, [2014-12-29T01:33:48.889686 #52]  INFO -- : retrying.. container ready..
@mookjp mookjp added the bug label Dec 28, 2014
@ainoya
Copy link
Collaborator

ainoya commented Dec 28, 2014

I think EventSource connection in javascript disconnected while streaming build logs, and it seems re-connect process runs inside EventSource function.
It may occur frequently in condition that network is unstable for example, Wi-Fi under weak antenna signal.
To fix this bug, build handler detect building image status and resume output build logs if building process is already started. This issue is also related to #26, #90

@ainoya ainoya self-assigned this Dec 28, 2014
@ainoya ainoya mentioned this issue Dec 29, 2014
Merged
@mookjp
Copy link
Owner Author

mookjp commented Jan 16, 2015

This is on my vagrant environment and I think it may not be related to network. and on current master, 37f0925, it still shows the same log.

@mookjp
Copy link
Owner Author

mookjp commented Jan 16, 2015

After I updated vagrant box coreos-alpha to 561.0.0, this error does not happen.

@mookjp mookjp closed this as completed Jan 16, 2015
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@ainoya ainoya

Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@mookjp @ainoya