Bench stopped when I change javascript or python code in development mode

1

Hi, I’m new developing with frappe framewoks.

Erpnext is installed in VM Ubuntu 14.02 and the version of the software is:

ERPNext: v9.2.15
Frappe Framework: v9.x.x-develop (cfb34ca) (develop)

When I change samo code in js or python the bench stops automatically. I have to start againt to continue.
Is this a normal behaviour?

I paste the console log:

12:18:40 system | watch.1 started (pid=7588)
12:18:40 system | redis_queue.1 started (pid=7597)
12:18:40 system | worker_default.1 started (pid=7586)
12:18:40 redis_queue.1 | [7601] 30 Nov 12:18:40.477 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984.
12:18:40 redis_queue.1 | [7601] 30 Nov 12:18:40.477 # Warning: 32 bit instance detected but no memory limit set. Setting 3 GB maxmemory limit with ‘noeviction’ policy now.
12:18:40 redis_queue.1 | .
12:18:40 redis_queue.1 | .-__ ''-._ 12:18:40 redis_queue.1 | _.- . . ‘’-._ Redis 2.8.4 (00000000/0) 32 bit
12:18:40 redis_queue.1 | .- .-```. ```\/ _.,_ ''-._ 12:18:40 redis_queue.1 | ( ' , .-` | `, ) Running in stand alone mode 12:18:40 redis_queue.1 | |`-._`-...-` __...-.-.|'_.-'| Port: 11000 12:18:40 redis_queue.1 | | -. ._ / _.-' | PID: 7601 12:18:40 redis_queue.1 | -._ -._ -./ .-’ .-’
12:18:40 redis_queue.1 | |-._-. -.__.-' _.-'_.-'| 12:18:40 redis_queue.1 | | -.-._ _.-'_.-' | http://redis.io 12:18:40 redis_queue.1 | -._ -._-..-'.-’ .-’
12:18:40 redis_queue.1 | |-._-. -.__.-' _.-'_.-'| 12:18:40 redis_queue.1 | | -.-._ _.-'_.-' | 12:18:40 redis_queue.1 | -._ -._-..-‘.-’ .-’
12:18:40 redis_queue.1 | -._ -..-’ _.-’
12:18:40 redis_queue.1 | -._ _.-' 12:18:40 redis_queue.1 | -..-’
12:18:40 redis_queue.1 |
12:18:40 redis_queue.1 | [7601] 30 Nov 12:18:40.477 # Server started, Redis version 2.8.4
12:18:40 redis_queue.1 | [7601] 30 Nov 12:18:40.477 # 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.
12:18:40 redis_queue.1 | [7601] 30 Nov 12:18:40.477 * The server is now ready to accept connections on port 11000
12:18:40 system | schedule.1 started (pid=7587)
12:18:40 system | web.1 started (pid=7589)
12:18:40 system | socketio.1 started (pid=7591)
12:18:40 system | worker_long.1 started (pid=7590)
12:18:40 system | redis_cache.1 started (pid=7592)
12:18:40 redis_cache.1 | [7611] 30 Nov 12:18:40.482 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984.
12:18:40 redis_cache.1 | .
12:18:40 redis_cache.1 | .-__ ''-._ 12:18:40 redis_cache.1 | _.- . . ‘’-. Redis 2.8.4 (00000000/0) 32 bit
12:18:40 redis_cache.1 | .- .-```. ```\/ _.,_ ''-._ 12:18:40 redis_cache.1 | ( ' , .-` | `, ) Running in stand alone mode 12:18:40 redis_cache.1 | |`-._`-...-` __...-.-.|’_.-'| Port: 13000 12:18:40 redis_cache.1 | | -._ ._ / _.-' | PID: 7611 12:18:40 redis_cache.1 | -._ -._ -./ .-’ .-’
12:18:40 redis_cache.1 | |-._-. -.__.-' _.-'_.-'| 12:18:40 redis_cache.1 | | -.-._ _.-'_.-' | http://redis.io 12:18:40 redis_cache.1 | -._ -._-..-'.-’ .-’
12:18:40 redis_cache.1 | |-._-. -.__.-' _.-'_.-'| 12:18:40 redis_cache.1 | | -.-._ _.-'_.-' | 12:18:40 redis_cache.1 | -._ -._-..-‘.-’ .-’
12:18:40 redis_cache.1 | -._ -..-’ _.-’
12:18:40 redis_cache.1 | -._ _.-' 12:18:40 redis_cache.1 | -..-’
12:18:40 redis_cache.1 |
12:18:40 redis_cache.1 | [7611] 30 Nov 12:18:40.483 # Server started, Redis version 2.8.4
12:18:40 redis_cache.1 | [7611] 30 Nov 12:18:40.483 # 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.
12:18:40 redis_cache.1 | [7611] 30 Nov 12:18:40.483 * The server is now ready to accept connections on port 13000
12:18:40 system | redis_socketio.1 started (pid=7595)
12:18:40 redis_socketio.1 | [7614] 30 Nov 12:18:40.502 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984.
12:18:40 redis_socketio.1 | [7614] 30 Nov 12:18:40.502 # Warning: 32 bit instance detected but no memory limit set. Setting 3 GB maxmemory limit with ‘noeviction’ policy now.
12:18:40 redis_socketio.1 | .
12:18:40 redis_socketio.1 | .-__ ''-._ 12:18:40 redis_socketio.1 | _.- . . ‘’-. Redis 2.8.4 (00000000/0) 32 bit
12:18:40 redis_socketio.1 | .- .-```. ```\/ _.,_ ''-._ 12:18:40 redis_socketio.1 | ( ' , .-` | `, ) Running in stand alone mode 12:18:40 redis_socketio.1 | |`-._`-...-` __...-.-.|’_.-'| Port: 12000 12:18:40 redis_socketio.1 | | -._ ._ / _.-' | PID: 7614 12:18:40 redis_socketio.1 | -._ -._ -./ .-’ .-’
12:18:40 redis_socketio.1 | |-._-. -.__.-' _.-'_.-'| 12:18:40 redis_socketio.1 | | -.-._ _.-'_.-' | http://redis.io 12:18:40 redis_socketio.1 | -._ -._-..-'.-’ .-’
12:18:40 redis_socketio.1 | |-._-. -.__.-' _.-'_.-'| 12:18:40 redis_socketio.1 | | -.-._ _.-'_.-' | 12:18:40 redis_socketio.1 | -._ -._-..-‘_.-’ _.-’
12:18:40 redis_socketio.1 | -._ -..-’ _.-’
12:18:40 redis_socketio.1 | -._ _.-' 12:18:40 redis_socketio.1 | -..-’
12:18:40 redis_socketio.1 |
12:18:40 redis_socketio.1 | [7614] 30 Nov 12:18:40.502 # Server started, Redis version 2.8.4
12:18:40 redis_socketio.1 | [7614] 30 Nov 12:18:40.502 # 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.
12:18:40 redis_socketio.1 | [7614] 30 Nov 12:18:40.502 * The server is now ready to accept connections on port 12000
12:18:40 system | worker_short.1 started (pid=7596)
12:18:42 socketio.1 | listening on *: 9000
12:18:44 socketio.1 | { [Error: connect ECONNREFUSED 127.0.0.1:8000]
12:18:44 socketio.1 | code: ‘ECONNREFUSED’,
12:18:44 socketio.1 | errno: ‘ECONNREFUSED’,
12:18:44 socketio.1 | syscall: ‘connect’,
12:18:44 socketio.1 | address: ‘127.0.0.1’,
12:18:44 socketio.1 | port: 8000,
12:18:44 socketio.1 | response: undefined }
12:18:49 worker_default.1 | 12:18:49 RQ worker u’rq:worker:erpnext.7598.default’ started, version 0.8.2
12:18:49 worker_default.1 | 12:18:49 Cleaning registries for queue: default
12:18:49 worker_default.1 | 12:18:49
12:18:49 worker_default.1 | 12:18:49 *** Listening on default…
12:18:49 web.1 | * Running on http://0.0.0.0:8000/ (Press CTRL+C to quit)
12:18:49 worker_long.1 | 12:18:49 RQ worker u’rq:worker:erpnext.7603.long’ started, version 0.8.2
12:18:49 worker_long.1 | 12:18:49 Cleaning registries for queue: long
12:18:49 worker_long.1 | 12:18:49
12:18:49 worker_long.1 | 12:18:49 *** Listening on long…
12:18:49 web.1 | * Restarting with inotify reloader
12:18:49 worker_short.1 | 12:18:49 RQ worker u’rq:worker:erpnext.7623.short’ started, version 0.8.2
12:18:49 worker_short.1 | 12:18:49 Cleaning registries for queue: short
12:18:49 worker_short.1 | 12:18:49
12:18:49 worker_short.1 | 12:18:49 *** Listening on short…
12:18:51 web.1 | * Debugger is active!
12:18:51 web.1 | * Debugger PIN: 252-848-793
12:18:51 watch.1 | compiling avatar.less
12:18:51 watch.1 | compiling calendar.less
12:18:51 watch.1 | compiling common.less
12:18:51 watch.1 | compiling desk.less
12:18:51 watch.1 | compiling desktop.less
12:18:51 watch.1 | compiling docs.less
12:18:51 watch.1 | compiling email.less
12:18:51 watch.1 | compiling form.less
12:18:52 watch.1 | compiling form_grid.less
12:18:52 watch.1 | compiling gantt.less
12:18:52 watch.1 | compiling indicator.less
12:18:52 watch.1 | compiling kanban.less
12:18:52 watch.1 | compiling list.less
12:18:52 watch.1 | compiling mixins.less
12:18:52 watch.1 | compiling mobile.less
12:18:52 watch.1 | compiling module.less
12:18:52 watch.1 | compiling navbar.less
12:18:52 watch.1 | compiling page.less
12:18:52 watch.1 | compiling report.less
12:18:52 watch.1 | compiling role_editor.less
12:18:52 watch.1 | compiling sidebar.less
12:18:52 watch.1 | compiling slickgrid.less
12:18:52 watch.1 | compiling tree.less
12:18:52 watch.1 | compiling website.less
12:18:52 watch.1 | compiling email.less
12:18:52 watch.1 | compiling erpnext.less
12:18:52 watch.1 | compiling hub.less
12:18:52 watch.1 | compiling pos.less
12:18:52 watch.1 | compiling website.less
12:18:52 watch.1 | file watching on *: 6787
12:18:54 web.1 | 127.0.0.1 - - [30/Nov/2017 12:18:54] “POST / HTTP/1.1” 200 -
12:18:54 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:18:54] “POST / HTTP/1.1” 200 -
12:18:54 watch.1 | Less files compiled
12:18:54 watch.1 | Wrote css/frappe-web.css - 65.43 KB
12:18:55 watch.1 | Wrote js/frappe-web.min.js - 133.33 KB
12:18:57 watch.1 | Wrote js/control.min.js - 75.18 KB
12:18:58 watch.1 | Wrote js/dialog.min.js - 119.92 KB
12:18:58 watch.1 | Wrote css/desk.min.css - 311.18 KB
12:18:58 watch.1 | Wrote css/frappe-rtl.css - 32.73 KB
12:18:58 watch.1 | Wrote js/libs.min.js - 1.13 MB
12:19:04 watch.1 | Wrote js/desk.min.js - 466.93 KB
12:19:04 watch.1 | Wrote css/module.min.css - 2.16 KB
12:19:04 watch.1 | Wrote css/form.min.css - 4.55 KB
12:19:08 watch.1 | Wrote js/form.min.js - 198.98 KB
12:19:08 watch.1 | Wrote css/list.min.css - 15.44 KB
12:19:10 watch.1 | Wrote js/list.min.js - 153.1 KB
12:19:10 watch.1 | Wrote css/report.min.css - 8.31 KB
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /desk HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /desk HTTP/1.1” 200 -
12:19:11 watch.1 | Wrote js/report.min.js - 261.66 KB
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/desk.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/desk.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/list.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/list.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/form.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/form.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/report.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/report.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/module.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/module.min.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/erpnext.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/css/erpnext.css?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/js/list.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:11 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:11] “GET /assets/js/list.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/desk.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/desk.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/libs.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/libs.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/control.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/control.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/form.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/form.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/frappe/js/frappe/toolbar.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/frappe/js/frappe/toolbar.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/report.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/report.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/erpnext.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:12] “GET /assets/js/erpnext.min.js?ver=1512040567.41 HTTP/1.1” 200 -
12:19:12 watch.1 | Wrote js/web_form.min.js - 247.91 KB
12:19:12 watch.1 | Wrote css/web_form.css - 24.69 KB
12:19:12 watch.1 | Wrote js/print_format_v3.min.js - 23.64 KB
12:19:12 watch.1 | Wrote css/erpnext.css - 8.08 KB
12:19:12 watch.1 | Wrote js/erpnext-web.min.js - 3.96 KB
12:19:14 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:14] “GET /api/method/frappe.async.get_user_info?sid=ec104956928090e673263bccc0b335c4fd1eded856a30320099a5e83 HTTP/1.1” 200 -
12:19:14 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:14] “GET /api/method/frappe.async.get_user_info?sid=ec104956928090e673263bccc0b335c4fd1eded856a30320099a5e83 HTTP/1.1” 200 -
12:19:14 watch.1 | Wrote js/erpnext.min.js - 154.33 KB
12:19:14 watch.1 | Wrote js/item-dashboard.min.js - 8.14 KB
12:19:15 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:15] “POST / HTTP/1.1” 200 -
12:19:15 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:15] “POST / HTTP/1.1” 200 -
12:19:19 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:19] “POST / HTTP/1.1” 200 -
12:19:19 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:19] “POST / HTTP/1.1” 200 -
12:19:22 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:22] “POST / HTTP/1.1” 200 -
12:19:22 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:22] “POST / HTTP/1.1” 200 -
12:19:46 web.1 | 127.0.0.1 - - [30/Nov/2017 12:19:46] “POST / HTTP/1.1” 200 -
12:19:46 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:19:46] “POST / HTTP/1.1” 200 -
12:22:16 web.1 | 127.0.0.1 - - [30/Nov/2017 12:22:16] “POST / HTTP/1.1” 200 -
12:22:16 web.1 | INFO:werkzeug:127.0.0.1 - - [30/Nov/2017 12:22:16] “POST / HTTP/1.1” 200 -
^C12:22:29 system | SIGINT received
12:22:29 system | sending SIGTERM to redis_socketio.1 (pid 7595)
12:22:29 system | sending SIGTERM to worker_long.1 (pid 7590)
12:22:29 system | sending SIGTERM to watch.1 (pid 7588)
12:22:29 system | sending SIGTERM to redis_queue.1 (pid 7597)
12:22:29 system | sending SIGTERM to web.1 (pid 7589)
12:22:29 system | sending SIGTERM to schedule.1 (pid 7587)
12:22:29 system | sending SIGTERM to worker_default.1 (pid 7586)
12:22:29 system | sending SIGTERM to socketio.1 (pid 7591)
12:22:29 system | sending SIGTERM to redis_cache.1 (pid 7592)
12:22:29 system | sending SIGTERM to worker_short.1 (pid 7596)
12:22:29 worker_long.1 | 12:22:29 Warm shut down requested
12:22:29 redis_queue.1 | [7601 | signal handler] (1512040949) Received SIGTERM, scheduling shutdown…
12:22:29 redis_socketio.1 | [7614 | signal handler] (1512040949) Received SIGTERM, scheduling shutdown…
12:22:29 redis_cache.1 | [7611 | signal handler] (1512040949) Received SIGTERM, scheduling shutdown…
12:22:29 worker_default.1 | 12:22:29 Warm shut down requested
12:22:29 redis_socketio.1 | [7614] 30 Nov 12:22:29.928 # User requested shutdown…
12:22:29 redis_socketio.1 | [7614] 30 Nov 12:22:29.929 # Redis is now ready to exit, bye bye…
12:22:29 system | redis_socketio.1 stopped (rc=-15)
12:22:29 redis_cache.1 | [7611] 30 Nov 12:22:29.929 # User requested shutdown…
12:22:29 redis_cache.1 | [7611] 30 Nov 12:22:29.929 # Redis is now ready to exit, bye bye…
12:22:29 system | redis_cache.1 stopped (rc=-15)
12:22:29 system | schedule.1 stopped (rc=-15)
12:22:29 system | socketio.1 stopped (rc=-15)
12:22:29 worker_short.1 | 12:22:29 Warm shut down requested
12:22:30 redis_queue.1 | [7601] 30 Nov 12:22:30.008 # User requested shutdown…
12:22:30 redis_queue.1 | [7601] 30 Nov 12:22:30.008 # Redis is now ready to exit, bye bye…
12:22:30 system | redis_queue.1 stopped (rc=-15)
12:22:30 system | watch.1 stopped (rc=-15)
12:22:30 system | worker_default.1 stopped (rc=-15)
12:22:30 system | worker_long.1 stopped (rc=-15)
12:22:30 system | worker_short.1 stopped (rc=-15)
12:22:30 web.1 | Exception in thread Thread-2 (most likely raised during interpreter shutdown):Exception in thread Thread-1 (most likely raised during interpreter shutdown):
12:22:30 web.1 | Traceback (most recent call last):
12:22:30 web.1 | File “/usr/lib/python2.7/threading.py”, line 810, in __bootstrap_inner
12:22:30 web.1 | File “/usr/lib/python2.7/threading.py”, line 763, in run
12:22:30 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 702, in inner
12:22:30 web.1 | Traceback (most recent call last):
12:22:30 web.1 |
12:22:30 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 539, in serve_forever File “/usr/lib/python2.7/threading.py”, line 810, in __bootstrap_inner
12:22:30 web.1 |
12:22:30 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/watchdog/observers/api.py”, line 237, in run File “/usr/lib/python2.7/SocketServer.py”, line 241, in serve_forever
12:22:30 web.1 |
12:22:30 web.1 | File “/usr/lib/python2.7/threading.py”, line 585, in set<type ‘exceptions.AttributeError’>: ‘NoneType’ object has no attribute ‘Empty’
12:22:30 web.1 |
12:22:30 web.1 | File “/usr/lib/python2.7/threading.py”, line 406, in notifyAll
12:22:30 web.1 | <type ‘exceptions.TypeError’>: ‘NoneType’ object is not callable
12:22:30 system | web.1 stopped (rc=-15)

Thanks

2

You should not change code files directly on master branch. If you are on develop branch, then it should not be a problem unless there is a syntax error in your core js files.

3

You probably hit Ctrl + C ?

1 Like
4

Pawan, thanks for your answer.
I’m in develop branch.
The bench stops when I change a js or py file without sintax error. For example, I launch the bench, I add a new blank line into a js file and automatically the bench stops with de log above.
Thanks

5

Sorry, you are right. I pasted the wrong log.

This is the right one:

frappe@erpnext:~/frappe-bench$ bench start
08:57:13 system | worker_long.1 started (pid=3243)
08:57:13 system | redis_socketio.1 started (pid=3242)
08:57:13 system | watch.1 started (pid=3247)
08:57:13 system | redis_queue.1 started (pid=3246)
08:57:13 system | schedule.1 started (pid=3244)
08:57:13 redis_socketio.1 | [3245] 01 Dec 08:57:13.213 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984.
08:57:13 redis_socketio.1 | [3245] 01 Dec 08:57:13.213 # Warning: 32 bit instance detected but no memory limit set. Setting 3 GB maxmemory limit with ‘noeviction’ policy now.
08:57:13 redis_socketio.1 | .
08:57:13 redis_socketio.1 | .-__ ''-._ 08:57:13 redis_socketio.1 | _.- . . ‘’-._ Redis 2.8.4 (00000000/0) 32 bit
08:57:13 redis_socketio.1 | .- .-```. ```\/ _.,_ ''-._ 08:57:13 redis_socketio.1 | ( ' , .-` | `, ) Running in stand alone mode 08:57:13 redis_socketio.1 | |`-._`-...-` __...-.-.|'_.-'| Port: 12000 08:57:13 redis_socketio.1 | | -. ._ / _.-' | PID: 3245 08:57:13 redis_socketio.1 | -._ -._ -./ .-’ .-’
08:57:13 redis_socketio.1 | |-._-. -.__.-' _.-'_.-'| 08:57:13 redis_socketio.1 | | -.-._ _.-'_.-' | http://redis.io 08:57:13 redis_socketio.1 | -._ -._-..-'.-’ .-’
08:57:13 redis_socketio.1 | |-._-. -.__.-' _.-'_.-'| 08:57:13 redis_socketio.1 | | -.-._ _.-'_.-' | 08:57:13 redis_socketio.1 | -._ -._-..-‘.-’ .-’
08:57:13 redis_socketio.1 | -._ -..-’ _.-’
08:57:13 redis_socketio.1 | -._ _.-' 08:57:13 redis_socketio.1 | -..-’
08:57:13 redis_socketio.1 |
08:57:13 redis_socketio.1 | [3245] 01 Dec 08:57:13.215 # Server started, Redis version 2.8.4
08:57:13 redis_socketio.1 | [3245] 01 Dec 08:57:13.215 # 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.
08:57:13 redis_queue.1 | [3253] 01 Dec 08:57:13.214 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984.
08:57:13 redis_queue.1 | [3253] 01 Dec 08:57:13.214 # Warning: 32 bit instance detected but no memory limit set. Setting 3 GB maxmemory limit with ‘noeviction’ policy now.
08:57:13 redis_queue.1 | .
08:57:13 redis_queue.1 | .-__ ''-._ 08:57:13 redis_queue.1 | _.- . . ‘’-. Redis 2.8.4 (00000000/0) 32 bit
08:57:13 redis_queue.1 | .- .-```. ```\/ _.,_ ''-._ 08:57:13 redis_queue.1 | ( ' , .-` | `, ) Running in stand alone mode 08:57:13 redis_queue.1 | |`-._`-...-` __...-.-.|’_.-'| Port: 11000 08:57:13 redis_queue.1 | | -._ ._ / _.-' | PID: 3253 08:57:13 redis_queue.1 | -._ -._ -./ .-’ .-’
08:57:13 redis_queue.1 | |-._-. -.__.-' _.-'_.-'| 08:57:13 redis_queue.1 | | -.-._ _.-'_.-' | http://redis.io 08:57:13 redis_queue.1 | -._ -._-..-'.-’ .-’
08:57:13 redis_queue.1 | |-._-. -.__.-' _.-'_.-'| 08:57:13 redis_queue.1 | | -.-._ _.-'_.-' | 08:57:13 redis_queue.1 | -._ -._-..-‘.-’ .-’
08:57:13 redis_queue.1 | -._ -..-’ _.-’
08:57:13 redis_queue.1 | -._ _.-' 08:57:13 redis_queue.1 | -..-’
08:57:13 redis_queue.1 |
08:57:13 redis_queue.1 | [3253] 01 Dec 08:57:13.214 # Server started, Redis version 2.8.4
08:57:13 redis_queue.1 | [3253] 01 Dec 08:57:13.215 # 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.
08:57:13 redis_socketio.1 | [3245] 01 Dec 08:57:13.220 * The server is now ready to accept connections on port 12000
08:57:13 redis_queue.1 | [3253] 01 Dec 08:57:13.220 * The server is now ready to accept connections on port 11000
08:57:13 system | worker_default.1 started (pid=3251)
08:57:13 system | web.1 started (pid=3252)
08:57:13 system | socketio.1 started (pid=3269)
08:57:13 system | redis_cache.1 started (pid=3274)
08:57:13 redis_cache.1 | [3275] 01 Dec 08:57:13.282 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984.
08:57:13 redis_cache.1 | .
08:57:13 redis_cache.1 | .-__ ''-._ 08:57:13 redis_cache.1 | _.- . . ‘’-. Redis 2.8.4 (00000000/0) 32 bit
08:57:13 redis_cache.1 | .- .-```. ```\/ _.,_ ''-._ 08:57:13 redis_cache.1 | ( ' , .-` | `, ) Running in stand alone mode 08:57:13 redis_cache.1 | |`-._`-...-` __...-.-.|’_.-'| Port: 13000 08:57:13 redis_cache.1 | | -._ ._ / _.-' | PID: 3275 08:57:13 redis_cache.1 | -._ -._ -./ .-’ .-’
08:57:13 redis_cache.1 | |-._-. -.__.-' _.-'_.-'| 08:57:13 redis_cache.1 | | -.-._ _.-'_.-' | http://redis.io 08:57:13 redis_cache.1 | -._ -._-..-'.-’ .-’
08:57:13 redis_cache.1 | |-._-. -.__.-' _.-'_.-'| 08:57:13 redis_cache.1 | | -.-._ _.-'_.-' | 08:57:13 redis_cache.1 | -._ -._-..-‘_.-’ _.-’
08:57:13 redis_cache.1 | -._ -..-’ _.-’
08:57:13 redis_cache.1 | -._ _.-' 08:57:13 redis_cache.1 | -..-’
08:57:13 redis_cache.1 |
08:57:13 redis_cache.1 | [3275] 01 Dec 08:57:13.283 # Server started, Redis version 2.8.4
08:57:13 redis_cache.1 | [3275] 01 Dec 08:57:13.283 # 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.
08:57:13 redis_cache.1 | [3275] 01 Dec 08:57:13.283 * The server is now ready to accept connections on port 13000
08:57:13 system | worker_short.1 started (pid=3271)
08:57:18 socketio.1 | listening on *: 9000
08:57:20 worker_long.1 | 08:57:20 RQ worker u’rq:worker:erpnext.3248.long’ started, version 0.8.2
08:57:20 worker_long.1 | 08:57:20 Cleaning registries for queue: long
08:57:20 worker_long.1 | 08:57:20
08:57:20 worker_long.1 | 08:57:20 *** Listening on long…
08:57:20 worker_default.1 | 08:57:20 RQ worker u’rq:worker:erpnext.3262.default’ started, version 0.8.2
08:57:20 worker_default.1 | 08:57:20 Cleaning registries for queue: default
08:57:20 worker_default.1 | 08:57:20
08:57:20 worker_default.1 | 08:57:20 *** Listening on default…
08:57:20 worker_short.1 | 08:57:20 RQ worker u’rq:worker:erpnext.3276.short’ started, version 0.8.2
08:57:20 worker_short.1 | 08:57:20 Cleaning registries for queue: short
08:57:20 worker_short.1 | 08:57:20
08:57:20 worker_short.1 | 08:57:20 *** Listening on short…
08:57:21 web.1 | * Running on http://0.0.0.0:8000/ (Press CTRL+C to quit)
08:57:21 web.1 | * Restarting with inotify reloader
08:57:22 web.1 | * Debugger is active!
08:57:22 web.1 | * Debugger PIN: 138-624-133
08:57:34 watch.1 | compiling avatar.less
08:57:34 watch.1 | compiling calendar.less
08:57:34 watch.1 | compiling common.less
08:57:34 watch.1 | compiling desk.less
08:57:34 watch.1 | compiling desktop.less
08:57:34 watch.1 | compiling docs.less
08:57:34 watch.1 | compiling email.less
08:57:34 watch.1 | compiling form.less
08:57:34 watch.1 | compiling form_grid.less
08:57:34 watch.1 | compiling gantt.less
08:57:34 watch.1 | compiling indicator.less
08:57:34 watch.1 | compiling kanban.less
08:57:34 watch.1 | compiling list.less
08:57:34 watch.1 | compiling mixins.less
08:57:34 watch.1 | compiling mobile.less
08:57:34 watch.1 | compiling module.less
08:57:34 watch.1 | compiling navbar.less
08:57:34 watch.1 | compiling page.less
08:57:34 watch.1 | compiling report.less
08:57:34 watch.1 | compiling role_editor.less
08:57:34 watch.1 | compiling sidebar.less
08:57:34 watch.1 | compiling slickgrid.less
08:57:34 watch.1 | compiling tree.less
08:57:34 watch.1 | compiling website.less
08:57:34 watch.1 | compiling email.less
08:57:34 watch.1 | compiling erpnext.less
08:57:34 watch.1 | compiling hub.less
08:57:34 watch.1 | compiling pos.less
08:57:34 watch.1 | compiling website.less
08:57:34 watch.1 | file watching on *: 6787
08:57:35 watch.1 | Less files compiled
08:57:35 watch.1 | Wrote css/frappe-web.css - 65.43 KB
08:57:40 watch.1 | Wrote js/frappe-web.min.js - 133.33 KB
08:57:43 watch.1 | Wrote js/control.min.js - 75.18 KB
08:57:43 watch.1 | Wrote js/dialog.min.js - 119.92 KB
08:57:43 watch.1 | Wrote css/desk.min.css - 311.18 KB
08:57:43 watch.1 | Wrote css/frappe-rtl.css - 32.73 KB
08:57:44 watch.1 | Wrote js/libs.min.js - 1.13 MB
08:57:49 watch.1 | Wrote js/desk.min.js - 466.93 KB
08:57:49 watch.1 | Wrote css/module.min.css - 2.16 KB
08:57:49 watch.1 | Wrote css/form.min.css - 4.55 KB
08:57:51 watch.1 | Wrote js/form.min.js - 198.98 KB
08:57:51 watch.1 | Wrote css/list.min.css - 15.44 KB
08:57:53 watch.1 | Wrote js/list.min.js - 153.1 KB
08:57:53 watch.1 | Wrote css/report.min.css - 8.31 KB
08:57:54 watch.1 | Wrote js/report.min.js - 261.66 KB
08:57:54 watch.1 | Wrote js/web_form.min.js - 247.91 KB
08:57:54 watch.1 | Wrote css/web_form.css - 24.69 KB
08:57:54 watch.1 | Wrote js/print_format_v3.min.js - 23.64 KB
08:57:54 watch.1 | Wrote css/erpnext.css - 8.08 KB
08:57:54 watch.1 | Wrote js/erpnext-web.min.js - 3.96 KB
08:57:56 watch.1 | Wrote js/erpnext.min.js - 154.33 KB
08:57:56 watch.1 | Wrote js/item-dashboard.min.js - 8.14 KB
09:00:14 watch.1 | /home/frappe/frappe-bench/apps/frappe/frappe/build.js:293
09:00:14 watch.1 | if (sources.includes(filename)) {
09:00:14 watch.1 | ^
09:00:14 watch.1 |
09:00:14 watch.1 | TypeError: sources.includes is not a function
09:00:14 watch.1 | at FSWatcher. (/home/frappe/frappe-bench/apps/frappe/frappe/build.js:293:16)
09:00:14 watch.1 | at emitTwo (events.js:100:13)
09:00:14 watch.1 | at FSWatcher.emit (events.js:185:7)
09:00:14 watch.1 | at FSWatcher. (/home/frappe/frappe-bench/node_modules/chokidar/index.js:196:15)
09:00:14 watch.1 | at FSWatcher._emit (/home/frappe/frappe-bench/node_modules/chokidar/index.js:238:5)
09:00:14 watch.1 | at FSWatcher.NodeFsHandler._handleFile (/home/frappe/frappe-bench/node_modules/chokidar/lib/nodefs-handler.js:275:10)
09:00:14 watch.1 | at FSWatcher. (/home/frappe/frappe-bench/node_modules/chokidar/lib/nodefs-handler.js:473:21)
09:00:14 watch.1 | at FSReqWrap.oncomplete (fs.js:82:15)
09:00:15 system | watch.1 stopped (rc=0)
09:00:15 system | sending SIGTERM to redis_socketio.1 (pid 3242)
09:00:15 system | sending SIGTERM to worker_long.1 (pid 3243)
09:00:15 system | sending SIGTERM to redis_queue.1 (pid 3246)
09:00:15 system | sending SIGTERM to web.1 (pid 3252)
09:00:15 system | sending SIGTERM to schedule.1 (pid 3244)
09:00:15 system | sending SIGTERM to worker_default.1 (pid 3251)
09:00:15 system | sending SIGTERM to socketio.1 (pid 3269)
09:00:15 system | sending SIGTERM to redis_cache.1 (pid 3274)
09:00:15 system | sending SIGTERM to worker_short.1 (pid 3271)
09:00:15 worker_long.1 | 09:00:15 Warm shut down requested
09:00:15 redis_socketio.1 | [3245 | signal handler] (1512115215) Received SIGTERM, scheduling shutdown…
09:00:15 redis_queue.1 | [3253 | signal handler] (1512115215) Received SIGTERM, scheduling shutdown…
09:00:15 system | schedule.1 stopped (rc=-15)
09:00:15 worker_default.1 | 09:00:15 Warm shut down requested
09:00:15 system | socketio.1 stopped (rc=-15)
09:00:15 redis_cache.1 | [3275 | signal handler] (1512115215) Received SIGTERM, scheduling shutdown…
09:00:15 worker_short.1 | 09:00:15 Warm shut down requested
09:00:15 redis_cache.1 | [3275] 01 Dec 09:00:15.121 # User requested shutdown…
09:00:15 redis_cache.1 | [3275] 01 Dec 09:00:15.121 # Redis is now ready to exit, bye bye…
09:00:15 system | redis_cache.1 stopped (rc=-15)
09:00:15 redis_socketio.1 | [3245] 01 Dec 09:00:15.134 # User requested shutdown…
09:00:15 redis_socketio.1 | [3245] 01 Dec 09:00:15.134 # Redis is now ready to exit, bye bye…
09:00:15 system | redis_socketio.1 stopped (rc=-15)
09:00:15 redis_queue.1 | [3253] 01 Dec 09:00:15.148 # User requested shutdown…
09:00:15 redis_queue.1 | [3253] 01 Dec 09:00:15.148 # Redis is now ready to exit, bye bye…
09:00:15 system | redis_queue.1 stopped (rc=-15)
09:00:15 system | worker_long.1 stopped (rc=-15)
09:00:15 system | worker_short.1 stopped (rc=-15)
09:00:15 system | worker_default.1 stopped (rc=-15)
09:00:15 web.1 | Exception in thread Thread-2 (most likely raised during interpreter shutdown):Exception in thread Thread-1 (most likely raised during interpreter shutdown):Exception in thread Thread-5 (most likely raised during interpreter shutdown):
09:00:15 web.1 |
09:00:15 web.1 |
09:00:15 web.1 | Traceback (most recent call last):
09:00:15 web.1 | Traceback (most recent call last):Traceback (most recent call last): File “/usr/lib/python2.7/threading.py”, line 810, in __bootstrap_inner
09:00:15 web.1 |
09:00:15 web.1 | File “/usr/lib/python2.7/threading.py”, line 810, in __bootstrap_inner File “/usr/lib/python2.7/threading.py”, line 810, in __bootstrap_inner
09:00:15 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/watchdog/observers/api.py”, line 182, in run
09:00:15 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/watchdog/observers/api.py”, line 237, in run
09:00:15 web.1 | File “/usr/lib/python2.7/threading.py”, line 763, in run
09:00:15 web.1 |
09:00:15 web.1 |
09:00:15 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/watchdog/observers/inotify.py”, line 131, in queue_events<type ‘exceptions.AttributeError’>: ‘NoneType’ object has no attribute ‘Empty’ File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 702, in inner
09:00:15 web.1 |
09:00:15 web.1 |
09:00:15 web.1 | File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/watchdog/observers/inotify_buffer.py”, line 82, in read_event File “/home/frappe/frappe-bench/env/local/lib/python2.7/site-packages/werkzeug/serving.py”, line 539, in serve_forever
09:00:15 web.1 |
09:00:15 web.1 | File “/usr/lib/python2.7/SocketServer.py”, line 241, in serve_forever<type ‘exceptions.AttributeError’>: ‘NoneType’ object has no attribute ‘time’
09:00:15 web.1 |
09:00:15 web.1 | File “/usr/lib/python2.7/threading.py”, line 585, in set
09:00:15 web.1 | File “/usr/lib/python2.7/threading.py”, line 406, in notifyAll
09:00:15 web.1 | <type ‘exceptions.TypeError’>: ‘NoneType’ object is not callable
09:00:15 system | web.1 stopped (rc=-15)

Thanks

6

Am facing the same issue, in develop branch. Did you solve this? @JUAN_CARLOS_RICO_OCH

12:00:37 watch.1 | /home/ubuntu/frappe-bench/apps/frappe/frappe/build.js:295
12:00:37 watch.1 | if (sources.includes(filename)) {
12:00:37 watch.1 | ^
12:00:37 watch.1 |
12:00:37 watch.1 | TypeError: sources.includes is not a function
12:00:37 watch.1 | at FSWatcher. (/home/ubuntu/frappe-bench/apps/frappe/frappe/build.js:295:16)
12:00:37 watch.1 | at emitTwo (events.js:87:13)
12:00:37 watch.1 | at FSWatcher.emit (events.js:172:7)
12:00:37 watch.1 | at FSWatcher. (/home/ubuntu/frappe-bench/node_modules/chokidar/index.js:196:15)
12:00:37 watch.1 | at FSWatcher._emit (/home/ubuntu/frappe-bench/node_modules/chokidar/index.js:238:5)
12:00:37 watch.1 | at FSWatcher. (/home/ubuntu/frappe-bench/node_modules/chokidar/lib/nodefs-handler.js:263:16)
12:00:37 watch.1 | at FSReqWrap.oncomplete (fs.js:82:15)
12:00:37 system | watch.1 stopped (rc=0)

7

I am facing the same issue.

8

Hello Team,

There was something problem in watch_js function of build.js file which is located in frappe-bench/apps/frappe/frappe directory. error is “sources.includes is not a function”.

So I have replaced build.js file and build.py file with frappe oldest version 8.5.7 file and now it works like charm.

You can get those files from git repository of frappe and just you have to replace those file in above directory.

Then you can start developemnt on your local server.