Bench start on Ubuntu 17.10

benjamin · April 19, 2018, 7:15am

Hello,

I installed frappe-bench on a new ubuntu 17.10 using the hitchhiker guides but whenever i run bench start, it hangs on the rebuilding email.css. please see log below

bench start
07:14:02 system           | redis_queue.1 started (pid=2520)
07:14:02 system           | schedule.1 started (pid=2530)
07:14:02 system           | web.1 started (pid=2523)
07:14:02 system           | worker_default.1 started (pid=2536)
07:14:02 system           | watch.1 started (pid=2528)
07:14:02 system           | redis_cache.1 started (pid=2538)
07:14:02 system           | worker_long.1 started (pid=2518)
07:14:02 system           | redis_socketio.1 started (pid=2515)
07:14:02 system           | worker_short.1 started (pid=2546)
07:14:02 system           | socketio.1 started (pid=2537)
07:14:02 redis_queue.1    | 2525:C 19 Apr 07:14:02.421 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
07:14:02 redis_queue.1    | 2525:C 19 Apr 07:14:02.421 # Redis version=4.0.1, bits=64, commit=00000000, modified=0, pid=2525, just started
07:14:02 redis_queue.1    | 2525:C 19 Apr 07:14:02.421 # Configuration loaded
07:14:02 redis_socketio.1 | 2527:C 19 Apr 07:14:02.421 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.424 # You requested maxclients of 10000 requiring at least 10032 max file descriptors.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.424 # Server can't set maximum open files to 10032 because of OS error: Operation not permitted.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.424 # Current maximum open files is 4096. maxclients has been reduced to 4064 to compensate for low ulimit. If you need higher maxclients increase 'ulimit -n'.
07:14:02 redis_socketio.1 | 2527:C 19 Apr 07:14:02.421 # Redis version=4.0.1, bits=64, commit=00000000, modified=0, pid=2527, just started
07:14:02 redis_socketio.1 | 2527:C 19 Apr 07:14:02.421 # Configuration loaded
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.422 # You requested maxclients of 10000 requiring at least 10032 max file descriptors.
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.422 # Server can't set maximum open files to 10032 because of OS error: Operation not permitted.
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.422 # Current maximum open files is 4096. maxclients has been reduced to 4064 to compensate for low ulimit. If you need higher maxclients increase 'ulimit -n'.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.462 * Running mode=standalone, port=11000.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.462 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.462 # Server initialized
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.462 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.462 # WARNING you have Transparent Huge Pages (THP) support enabled in your kernel. This will create latency and memory usage issues with Redis. To fix this issue run the command 'echo never > /sys/kernel/mm/transparent_hugepage/enabled' as root, and add it to your /etc/rc.local in order to retain the setting after a reboot. Redis must be restarted after THP is disabled.
07:14:02 redis_queue.1    | 2525:M 19 Apr 07:14:02.462 * Ready to accept connections
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.461 * Running mode=standalone, port=12000.
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.462 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.462 # Server initialized
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.462 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.462 # WARNING you have Transparent Huge Pages (THP) support enabled in your kernel. This will create latency and memory usage issues with Redis. To fix this issue run the command 'echo never > /sys/kernel/mm/transparent_hugepage/enabled' as root, and add it to your /etc/rc.local in order to retain the setting after a reboot. Redis must be restarted after THP is disabled.
07:14:02 redis_socketio.1 | 2527:M 19 Apr 07:14:02.463 * Ready to accept connections
07:14:02 redis_cache.1    | 2543:C 19 Apr 07:14:02.495 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
07:14:02 redis_cache.1    | 2543:C 19 Apr 07:14:02.495 # Redis version=4.0.1, bits=64, commit=00000000, modified=0, pid=2543, just started
07:14:02 redis_cache.1    | 2543:C 19 Apr 07:14:02.495 # Configuration loaded
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.496 # You requested maxclients of 10000 requiring at least 10032 max file descriptors.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.496 # Server can't set maximum open files to 10032 because of OS error: Operation not permitted.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.496 # Current maximum open files is 4096. maxclients has been reduced to 4064 to compensate for low ulimit. If you need higher maxclients increase 'ulimit -n'.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.497 * Running mode=standalone, port=13000.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.497 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.497 # Server initialized
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.497 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.497 # WARNING you have Transparent Huge Pages (THP) support enabled in your kernel. This will create latency and memory usage issues with Redis. To fix this issue run the command 'echo never > /sys/kernel/mm/transparent_hugepage/enabled' as root, and add it to your /etc/rc.local in order to retain the setting after a reboot. Redis must be restarted after THP is disabled.
07:14:02 redis_cache.1    | 2543:M 19 Apr 07:14:02.497 * Ready to accept connections
07:14:05 web.1            |  * Running on http://0.0.0.0:8000/ (Press CTRL+C to quit)
07:14:05 web.1            |  * Restarting with inotify reloader
07:14:06 web.1            |  * Debugger is active!
07:14:07 web.1            |  * Debugger PIN: 194-376-787
07:14:10 watch.1          | 
07:14:10 watch.1          | > frappe@ watch /home/dolispy/frappe-bench/apps/frappe
07:14:10 watch.1          | > node rollup/watch.js
07:14:10 watch.1          | 
07:14:11 socketio.1       | listening on *: 9000
07:14:20 watch.1          | 
07:14:20 watch.1          | Rollup Watcher Started
07:14:20 watch.1          | 
07:14:20 watch.1          | Watching...
07:14:20 watch.1          | Rebuilding frappe-web.css
07:14:20 watch.1          | Rebuilding frappe-web.min.js
07:14:23 watch.1          | Rebuilding control.min.js
07:14:23 watch.1          | Rebuilding dialog.min.js
07:14:24 watch.1          | Rebuilding desk.min.css
07:14:24 watch.1          | Rebuilding frappe-rtl.css
07:14:24 watch.1          | Rebuilding desk.min.js
07:14:28 watch.1          | Rebuilding module.min.css
07:14:28 watch.1          | Rebuilding form.min.css
07:14:28 watch.1          | Rebuilding form.min.js
07:14:29 watch.1          | Rebuilding list.min.css
07:14:29 watch.1          | Rebuilding list.min.js
07:14:30 watch.1          | Rebuilding report.min.css
07:14:30 watch.1          | Rebuilding report.min.js
07:14:31 watch.1          | Rebuilding web_form.min.js
07:14:33 watch.1          | Rebuilding web_form.css
07:14:33 watch.1          | Rebuilding print_format_v3.min.js
07:14:33 watch.1          | Rebuilding email.css

it doesn’t give any error just hangs there. Can anyone please help?

netchampfaris · April 19, 2018, 7:20am

I don’t see any problem with this. Can you access your site on port 8000

benjamin · April 19, 2018, 7:34am

no i cant…i get this page isnt working

benjamin · April 19, 2018, 7:38am

the command has finished running…please find the error now
07:14:33 watch.1 | Rebuilding email.css
08:33:45 web.1 | 0.0.0.0 does not exist
08:33:45 web.1 | ----------------------------------------
08:33:45 web.1 | Exception happened during processing of request from (‘127.0.0.1’, 50784)
08:33:45 web.1 | Traceback (most recent call last):
08:33:45 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 596, in process_request_thread
08:33:45 web.1 | self.finish_request(request, client_address)
08:33:45 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 331, in finish_request
08:33:45 web.1 | self.RequestHandlerClass(request, client_address, self)
08:33:45 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 652, in init
08:33:45 web.1 | self.handle()
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 293, in handle
08:33:45 web.1 | rv = BaseHTTPRequestHandler.handle(self)
08:33:45 web.1 | File “/usr/lib/python2.7/BaseHTTPServer.py”, line 340, in handle
08:33:45 web.1 | self.handle_one_request()
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 328, in handle_one_request
08:33:45 web.1 | return self.run_wsgi()
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 270, in run_wsgi
08:33:45 web.1 | execute(self.server.app)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 260, in execute
08:33:45 web.1 | for data in application_iter:
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/debug/init.py”, line 288, in debug_application
08:33:45 web.1 | app_iter = self.app(environ, start_response)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/apps/frappe/frappe/middlewares.py”, line 15, in call
08:33:45 web.1 | return super(StaticDataMiddleware, self).call(environ, start_response)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/wsgi.py”, line 766, in call
08:33:45 web.1 | return self.app(environ, start_response)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/wsgi.py”, line 766, in call
08:33:45 web.1 | return self.app(environ, start_response)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/local.py”, line 228, in application
08:33:45 web.1 | return ClosingIterator(app(environ, start_response), self.cleanup)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/wrappers.py”, line 308, in application
08:33:45 web.1 | resp = f(*args[:-2] + (request,))
08:33:45 web.1 | File “/home/dolispy/frappe-bench/apps/frappe/frappe/app.py”, line 59, in application
08:33:45 web.1 | init_request(request)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/apps/frappe/frappe/app.py”, line 110, in init_request
08:33:45 web.1 | frappe.init(site=site, sites_path=_sites_path)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/apps/frappe/frappe/init.py”, line 138, in init
08:33:45 web.1 | local.conf = _dict(get_site_config())
08:33:45 web.1 | File “/home/dolispy/frappe-bench/apps/frappe/frappe/init.py”, line 195, in get_site_config
08:33:45 web.1 | sys.exit(1)
08:33:45 web.1 | SystemExit: 1
08:33:45 web.1 | ----------------------------------------
08:33:45 web.1 | 0.0.0.0 does not exist
08:33:45 web.1 | ----------------------------------------
08:33:45 web.1 | Exception happened during processing of request from (‘127.0.0.1’, 50786)
08:33:45 web.1 | Traceback (most recent call last):
08:33:45 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 596, in process_request_thread
08:33:45 web.1 | self.finish_request(request, client_address)
08:33:45 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 331, in finish_request
08:33:45 web.1 | self.RequestHandlerClass(request, client_address, self)
08:33:45 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 652, in init
08:33:45 web.1 | self.handle()
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 293, in handle
08:33:45 web.1 | rv = BaseHTTPRequestHandler.handle(self)
08:33:45 web.1 | File “/usr/lib/python2.7/BaseHTTPServer.py”, line 340, in handle
08:33:45 web.1 | self.handle_one_request()
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 328, in handle_one_request
08:33:45 web.1 | return self.run_wsgi()
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 270, in run_wsgi
08:33:45 web.1 | execute(self.server.app)
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 260, in execute
08:33:45 web.1 | for data in application_iter:
08:33:45 web.1 | File “/home/dolispy/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/debug/init.py”, line 288, in debug_application
08:33:45 web.1 | app_iter = self.app(environ, start_response)

benjamin · April 19, 2018, 7:42am

also when i rerun bench start, i get this new error

bench start
08:40:19 system | worker_long.1 started (pid=5015)
08:40:19 system | web.1 started (pid=5027)
08:40:19 system | schedule.1 started (pid=5035)
08:40:19 system | redis_queue.1 started (pid=5022)
08:40:19 redis_queue.1 | 5028:C 19 Apr 08:40:19.379 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
08:40:19 redis_queue.1 | 5028:C 19 Apr 08:40:19.379 # Redis version=4.0.1, bits=64, commit=00000000, modified=0, pid=5028, just started
08:40:19 redis_queue.1 | 5028:C 19 Apr 08:40:19.379 # Configuration loaded
08:40:19 redis_queue.1 | 5028:M 19 Apr 08:40:19.380 # You requested maxclients of 10000 requiring at least 10032 max file descriptors.
08:40:19 redis_queue.1 | 5028:M 19 Apr 08:40:19.380 # Server can’t set maximum open files to 10032 because of OS error: Operation not permitted.
08:40:19 redis_queue.1 | 5028:M 19 Apr 08:40:19.380 # Current maximum open files is 4096. maxclients has been reduced to 4064 to compensate for low ulimit. If you need higher maxclients increase ‘ulimit -n’.
08:40:19 redis_queue.1 | 5028:M 19 Apr 08:40:19.380 # Creating Server TCP listening socket 127.0.0.1:11000: bind: Address already in use
08:40:19 system | redis_queue.1 stopped (rc=1)
08:40:19 system | socketio.1 started (pid=5034)
08:40:19 system | redis_socketio.1 started (pid=5013)
08:40:19 redis_socketio.1 | 5023:C 19 Apr 08:40:19.372 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
08:40:19 redis_socketio.1 | 5023:C 19 Apr 08:40:19.372 # Redis version=4.0.1, bits=64, commit=00000000, modified=0, pid=5023, just started
08:40:19 redis_socketio.1 | 5023:C 19 Apr 08:40:19.372 # Configuration loaded
08:40:19 redis_socketio.1 | 5023:M 19 Apr 08:40:19.373 # You requested maxclients of 10000 requiring at least 10032 max file descriptors.
08:40:19 redis_socketio.1 | 5023:M 19 Apr 08:40:19.373 # Server can’t set maximum open files to 10032 because of OS error: Operation not permitted.
08:40:19 redis_socketio.1 | 5023:M 19 Apr 08:40:19.373 # Current maximum open files is 4096. maxclients has been reduced to 4064 to compensate for low ulimit. If you need higher maxclients increase ‘ulimit -n’.
08:40:19 redis_socketio.1 | 5023:M 19 Apr 08:40:19.373 # Creating Server TCP listening socket 127.0.0.1:12000: bind: Address already in use
08:40:19 system | redis_socketio.1 stopped (rc=1)
08:40:19 system | watch.1 started (pid=5016)
08:40:19 system | redis_cache.1 started (pid=5032)
08:40:19 redis_cache.1 | 5039:C 19 Apr 08:40:19.388 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
08:40:19 redis_cache.1 | 5039:C 19 Apr 08:40:19.388 # Redis version=4.0.1, bits=64, commit=00000000, modified=0, pid=5039, just started
08:40:19 redis_cache.1 | 5039:C 19 Apr 08:40:19.388 # Configuration loaded
08:40:19 redis_cache.1 | 5039:M 19 Apr 08:40:19.389 # You requested maxclients of 10000 requiring at least 10032 max file descriptors.
08:40:19 redis_cache.1 | 5039:M 19 Apr 08:40:19.389 # Server can’t set maximum open files to 10032 because of OS error: Operation not permitted.
08:40:19 redis_cache.1 | 5039:M 19 Apr 08:40:19.389 # Current maximum open files is 4096. maxclients has been reduced to 4064 to compensate for low ulimit. If you need higher maxclients increase ‘ulimit -n’.
08:40:19 redis_cache.1 | 5039:M 19 Apr 08:40:19.389 # Creating Server TCP listening socket 127.0.0.1:13000: bind: Address already in use
08:40:19 system | redis_cache.1 stopped (rc=1)
08:40:19 system | worker_default.1 started (pid=5041)
08:40:19 system | worker_short.1 started (pid=5036)
08:40:19 system | sending SIGTERM to worker_long.1 (pid 5015)
08:40:19 system | sending SIGTERM to watch.1 (pid 5016)
08:40:19 system | sending SIGTERM to web.1 (pid 5027)
08:40:19 system | sending SIGTERM to schedule.1 (pid 5035)
08:40:19 system | sending SIGTERM to worker_default.1 (pid 5041)
08:40:19 system | sending SIGTERM to socketio.1 (pid 5034)
08:40:19 system | sending SIGTERM to worker_short.1 (pid 5036)
08:40:19 system | watch.1 stopped (rc=-15)
08:40:19 system | schedule.1 stopped (rc=-15)
08:40:19 system | worker_short.1 stopped (rc=-15)
08:40:19 system | web.1 stopped (rc=-15)
08:40:19 system | worker_default.1 stopped (rc=-15)
08:40:19 system | worker_long.1 stopped (rc=-15)
08:40:19 system | socketio.1 stopped (rc=-15)

and now the browser throws up this error

RuntimeError
RuntimeError: no object bound to conf

Traceback (most recent call last)
File “/home/dolispy/frappe-bench/apps/frappe/frappe/middlewares.py”, line 15, in call
return super(StaticDataMiddleware, self).call(environ, start_response)
File “/home/dolispy/frappe-bench/env/lib/python2.7/site-packages/werkzeug/wsgi.py”, line 766, in call
return self.app(environ, start_response)
File “/home/dolispy/frappe-bench/env/lib/python2.7/site-packages/werkzeug/wsgi.py”, line 766, in call
return self.app(environ, start_response)
File “/home/dolispy/frappe-bench/env/lib/python2.7/site-packages/werkzeug/local.py”, line 228, in application
return ClosingIterator(app(environ, start_response), self.cleanup)
File “/home/dolispy/frappe-bench/env/lib/python2.7/site-packages/werkzeug/wrappers.py”, line 308, in application
resp = f(*args[:-2] + (request,))
File “/home/dolispy/frappe-bench/apps/frappe/frappe/app.py”, line 88, in application
response = handle_exception(e)
File “/home/dolispy/frappe-bench/apps/frappe/frappe/app.py”, line 190, in handle_exception
make_error_snapshot(e)
File “/home/dolispy/frappe-bench/apps/frappe/frappe/utils/error.py”, line 22, in make_error_snapshot
if frappe.conf.disable_error_snapshot:
File “/home/dolispy/frappe-bench/env/lib/python2.7/site-packages/werkzeug/local.py”, line 347, in getattr
return getattr(self._get_current_object(), name)
File “/home/dolispy/frappe-bench/env/lib/python2.7/site-packages/werkzeug/local.py”, line 310, in _get_current_object
raise RuntimeError(‘no object bound to %s’ % self.name)
RuntimeError: no object bound to conf
The debugger caught an exception in your WSGI application. You can now look at the traceback which led to the error.
To switch between the interactive traceback and the plaintext one, you can click on the “Traceback” headline. From the text traceback you can also create a paste of it. For code execution mouse-over the frame you want to debug and click on the console icon on the right side.

You can execute arbitrary Python code in the stack frames and there are some extra helpers available for introspection:

dump() shows all variables in the frame
dump(obj) dumps all that’s known about the object
Brought to you by DON’T PANIC, your friendly Werkzeug powered traceback interpreter.

netchampfaris · April 19, 2018, 9:44am

Since you started bench earlier, the port 8000 is in use. You will have kill all python and node processes that bench uses and then start bench again.

I don’t see any problem with bench start in your traces (other than address is in use). Have you created a new site? What does your currentsite.txt look like?

benjamin · April 19, 2018, 11:22am

thank you @netchampfaris for your help so far…
This was the link I followed Home · frappe/frappe Wiki · GitHub . After creating a new bench, supervisorctl was not found. I had to run sudo setup production before it installed and then created my site and installed erpnext. The new challenge I have is running my localhost doesnt bring up the login page. It keeps bringing up the page cannot be loaded error
Have you created a new site? Yes I have
What does your currentsite.txt look like? It just has the name of the site I created

netchampfaris · April 20, 2018, 7:20am

There is no bench start in production. If you want to develop, you need not worry about supervisor.