ok now we have a new message
root@vps:~# bench start 04:24:39 system | redis_socketio.1 started (pid=1502) 04:24:39 system | watch.1 started (pid=1507) 04:24:39 system | web.1 started (pid=1512) 04:24:39 system | redis_queue.1 started (pid=1509) 04:24:39 system | schedule.1 started (pid=1513) 04:24:39 system | redis_cache.1 started (pid=1523) 04:24:39 system | worker_default.1 started (pid=1522) 04:24:39 system | socketio.1 started (pid=1519) 04:24:39 system | worker_long.1 started (pid=1504) 04:24:39 system | worker_short.1 started (pid=1525) 04:24:39 redis_queue.1 | [1533] 22 Nov 04:24:39.583 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984. 04:24:39 redis_socketio.1 | [1520] 22 Nov 04:24:39.583 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984. 04:24:39 redis_cache.1 | [1530] 22 Nov 04:24:39.583 # Unable to set the max number of files limit to 10032 (Operation not permitted), setting the max clients configuration to 3984. 04:24:39 redis_socketio.1 | _._ 04:24:39 redis_socketio.1 | _.-``__ ''-._ 04:24:39 redis_cache.1 | _._ 04:24:39 redis_cache.1 | _.-``__ ''-._ 04:24:39 redis_cache.1 | _.-``
. _. ''-._ Redis 2.8.4 (00000000/0) 64 bit 04:24:39 redis_cache.1 | .-`` .-```. ```\/ _.,_ ''-._ 04:24:39 redis_queue.1 | _._ 04:24:39 redis_queue.1 | _.-``__ ''-._ 04:24:39 redis_queue.1 | _.-``
. _. ''-._ Redis 2.8.4 (00000000/0) 64 bit 04:24:39 redis_queue.1 | .-`` .-```. ```\/ _.,_ ''-._ 04:24:39 redis_queue.1 | ( ' , .-
| , ) Running in stand alone mode 04:24:39 redis_queue.1 | |
-.-...-
__…-.-._|'` _.-'| Port: 11000 04:24:39 redis_queue.1 | | `-._ `._ / _.-' | PID: 1533 04:24:39 redis_queue.1 | `-._ `-._ `-./ _.-' _.-' 04:24:39 redis_queue.1 | |`-._`-._ `-.__.-' _.-'_.-'| 04:24:39 redis_queue.1 | | `-._`-._ _.-'_.-' | http://redis.io 04:24:39 redis_queue.1 | `-._ `-._`-.__.-'_.-' _.-' 04:24:39 redis_queue.1 | |`-._`-._ `-.__.-' _.-'_.-'| 04:24:39 redis_queue.1 | | `-._`-._ _.-'_.-' | 04:24:39 redis_queue.1 | `-._ `-._`-.__.-'_.-' _.-' 04:24:39 redis_queue.1 | `-._ `-.__.-' _.-' 04:24:39 redis_queue.1 | `-._ _.-' 04:24:39 redis_queue.1 | `-.__.-' 04:24:39 redis_queue.1 | 04:24:39 redis_queue.1 | [1533] 22 Nov 04:24:39.585 # Server started, Redis version 2.8.4 04:24:39 redis_cache.1 | ( ' , .-` | `, ) Running in stand alone mode 04:24:39 redis_cache.1 | |`-._`-...-` __...-.
-.|‘_.-'| Port: 13000 04:24:39 redis_cache.1 | |
-._ ._ / _.-' | PID: 1530 04:24:39 redis_cache.1 |
-._ -._
-./ .-’ .-’
04:24:39 redis_cache.1 | |-._
-. -.__.-' _.-'_.-'| 04:24:39 redis_cache.1 | |
-.-._ _.-'_.-' | http://redis.io 04:24:39 redis_cache.1 |
-._ -._
-..-‘_.-’ .-’
04:24:39 redis_socketio.1 | .- `. `_. ''-._ Redis 2.8.4 (00000000/0) 64 bit 04:24:39 redis_socketio.1 | .-
.-.
/ ., ‘’-.
04:24:39 redis_socketio.1 | ( ’ , .- |
, ) Running in stand alone mode
04:24:39 redis_socketio.1 | |-._
-…- __...-.``-._|'
.-'| Port: 12000
04:24:39 redis_socketio.1 | | -._
. / .-’ | PID: 1520
04:24:39 redis_socketio.1 | -._
-. -./ _.-' _.-' 04:24:39 redis_socketio.1 | |
-.-._
-..-’ .-'.-‘|
04:24:39 redis_socketio.1 | | -._
-._ .-'.-’ | http://redis.io
04:24:39 redis_socketio.1 | -._
-.-.__.-'_.-' _.-' 04:24:39 redis_socketio.1 | |
-.-._
-..-’ .-'.-‘|
04:24:39 redis_socketio.1 | | -._
-._ .-'.-’ |
04:24:39 redis_socketio.1 | -._
-.-.__.-'_.-' _.-' 04:24:39 redis_socketio.1 |
-. -.__.-' _.-' 04:24:39 redis_socketio.1 |
-._ .-’
04:24:39 redis_socketio.1 | -.__.-' 04:24:39 redis_socketio.1 | 04:24:39 redis_socketio.1 | [1520] 22 Nov 04:24:39.585 # Server started, Redis version 2.8.4 04:24:39 redis_socketio.1 | [1520] 22 Nov 04:24:39.585 # 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. 04:24:39 redis_cache.1 | |
-.-._
-..-’ .-'.-‘|
04:24:39 redis_cache.1 | | -._
-._ .-'.-’ |
04:24:39 redis_cache.1 | -._
-.-.__.-'_.-' _.-' 04:24:39 redis_cache.1 |
-. -.__.-' _.-' 04:24:39 redis_cache.1 |
-._ .-’
04:24:39 redis_cache.1 | `-.__.-’
04:24:39 redis_cache.1 |
04:24:39 redis_cache.1 | [1530] 22 Nov 04:24:39.584 # Server started, Redis version 2.8.4
04:24:39 redis_cache.1 | [1530] 22 Nov 04:24:39.585 # 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.
04:24:39 redis_queue.1 | [1533] 22 Nov 04:24:39.587 # 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.
04:24:39 redis_cache.1 | [1530] 22 Nov 04:24:39.647 * The server is now ready to accept connections on port 13000
04:24:39 redis_socketio.1 | [1520] 22 Nov 04:24:39.647 * The server is now ready to accept connections on port 12000
04:24:39 redis_queue.1 | [1533] 22 Nov 04:24:39.647 * The server is now ready to accept connections on port 11000
04:24:42 worker_default.1 | 04:24:42 RQ worker u’rq:worker:vps.1534.default’ started, version 0.6.0
04:24:42 worker_default.1 | 04:24:42 Cleaning registries for queue: default
04:24:42 worker_default.1 | 04:24:42
04:24:42 worker_long.1 | 04:24:42 RQ worker u’rq:worker:vps.1521.long’ started, version 0.6.0
04:24:42 worker_default.1 | 04:24:42 *** Listening on default…
04:24:42 worker_short.1 | 04:24:42 RQ worker u’rq:worker:vps.1535.short’ started, version 0.6.0
04:24:42 worker_long.1 | 04:24:42 Cleaning registries for queue: long
04:24:42 worker_short.1 | 04:24:42 Cleaning registries for queue: short
04:24:42 worker_short.1 | 04:24:42
04:24:42 worker_short.1 | 04:24:42 *** Listening on short…
04:24:42 worker_long.1 | 04:24:42
04:24:42 worker_long.1 | 04:24:42 *** Listening on long…
04:24:47 watch.1 | Wrote ./assets/js/print_format_v3.min.js - 25k
04:24:49 web.1 | * Running on http://0.0.0.0:8000/ (Press CTRL+C to quit)
04:24:49 watch.1 | Wrote ./assets/js/frappe-web.min.js - 141k
04:24:52 web.1 | * Restarting with inotify reloader
04:24:53 web.1 | * Debugger is active!
04:24:56 watch.1 | Wrote ./assets/js/form.min.js - 175k
04:24:56 web.1 | * Debugger pin code: 187-159-122
04:24:58 watch.1 | Wrote ./assets/js/report.min.js - 250k
04:25:00 watch.1 | Wrote ./assets/js/erpnext.min.js - 101k
04:25:01 watch.1 | Wrote ./assets/css/list.min.css - 8k
04:25:02 watch.1 | Wrote ./assets/js/item-dashboard.min.js - 7k
04:25:02 watch.1 | Wrote ./assets/js/d3.min.js - 380k
04:25:03 watch.1 | Wrote ./assets/css/report.min.css - 8k
04:25:04 watch.1 | Wrote ./assets/css/frappe-web.css - 54k
04:25:09 watch.1 | Wrote ./assets/js/list.min.js - 146k
04:25:11 watch.1 | Wrote ./assets/js/dialog.min.js - 85k
04:25:15 web.1 | Traceback (most recent call last):
04:25:15 web.1 | File “/home/frappe/frappe-bench/apps/frappe/frappe/website/context.py”, line 47, in update_controller_context
04:25:15 web.1 | ret = module.get_context(context)
04:25:15 web.1 | File “/home/frappe/frappe-bench/apps/frappe/frappe/www/desk.py”, line 18, in get_context
04:25:15 web.1 | frappe.throw((“You are not permitted to access this page.”), frappe.PermissionError)
04:25:15 web.1 | File “/home/frappe/frappe-bench/apps/frappe/frappe/init.py”, line 300, in throw
04:25:15 web.1 | msgprint(msg, raise_exception=exc, title=title, indicator=‘red’)
04:25:15 web.1 | File “/home/frappe/frappe-bench/apps/frappe/frappe/init.py”, line 293, in msgprint
04:25:15 web.1 | _raise_exception()
04:25:15 web.1 | File “/home/frappe/frappe-bench/apps/frappe/frappe/init.py”, line 266, in _raise_exception
04:25:15 web.1 | raise raise_exception, encode(msg)
04:25:15 web.1 | PermissionError: You are not permitted to access this page.
04:25:15 web.1 |
04:25:16 web.1 | 127.0.0.1 - - [22/Nov/2016 04:25:16] “GET /desk HTTP/1.0” 200 -
04:25:16 watch.1 | Wrote ./assets/js/desk.min.js - 328k
04:25:16 watch.1 | Wrote ./assets/css/form.min.css - 4k
04:25:17 watch.1 | Wrote ./assets/js/editor.min.js - 45k
04:25:17 watch.1 | Wrote ./assets/css/erpnext.css - 3k
04:25:18 watch.1 | Wrote ./assets/js/libs.min.js - 891k
04:25:18 watch.1 | Wrote ./assets/js/erpnext-web.min.js - 4k
04:25:19 watch.1 | Wrote ./assets/css/desk.min.css - 266k
04:25:20 watch.1 | Wrote ./assets/css/module.min.css - 2k
04:25:56 socketio.1 | listening on *: 9000
04:26:04 web.1 | 127.0.0.1 - - [22/Nov/2016 04:26:04] “GET /api/method/frappe.async.get_user_info?sid=81a14493030f0d7f7e6bd4d3c1bc6d57d3978519f1f9c5cf7d5419cb HTTP/1.0” 200 -
04:26:10 web.1 | Module import failed for Page (frappe.core.doctype.page.page_dashboard)
04:26:25 web.1 | 127.0.0.1 - - [22/Nov/2016 04:26:25] “GET /api/method/frappe.async.get_user_info?sid=81a14493030f0d7f7e6bd4d3c1bc6d57d3978519f1f9c5cf7d5419cb HTTP/1.0” 200 -
04:26:32 web.1 | 127.0.0.1 - - [22/Nov/2016 04:26:32] “POST / HTTP/1.0” 200 -
04:26:33 web.1 | 127.0.0.1 - - [22/Nov/2016 04:26:33] “GET /desk HTTP/1.0” 200 -
04:27:02 web.1 | 127.0.0.1 - - [22/Nov/2016 04:27:02] “GET /api/method/frappe.async.get_user_info?sid=81a14493030f0d7f7e6bd4d3c1bc6d57d3978519f1f9c5cf7d5419cb HTTP/1.0” 200 -
04:27:35 web.1 | 127.0.0.1 - - [22/Nov/2016 04:27:35] “POST / HTTP/1.0” 200 -
04:28:04 web.1 | 127.0.0.1 - - [22/Nov/2016 04:28:04] “POST / HTTP/1.0” 200 -
04:28:33 web.1 | 127.0.0.1 - - [22/Nov/2016 04:28:33] “POST / HTTP/1.0” 200 -
04:28:47 worker_short.1 | 04:28:47 short: frappe.utils.background_jobs.execute_job(async=True, event=u’all’, job_name=u’frappe.email.queue.flush’, kwargs={}, method=u’frappe.email.queue.flush’, site=u’site1.local’, user=u’Administrator’) (96569dd1-cae9-4cdf-8a8b-81d0790ea88b)
04:28:50 worker_short.1 | 04:28:50 short: Job OK (96569dd1-cae9-4cdf-8a8b-81d0790ea88b)
04:28:50 worker_short.1 | 04:28:50 Result is kept for 500 seconds
04:28:50 worker_short.1 | 04:28:50
04:28:50 worker_short.1 | 04:28:50 *** Listening on short…
04:28:50 worker_short.1 | 04:28:50 short: frappe.utils.background_jobs.execute_job(async=True, event=u’all’, job_name=u’frappe.email.doctype.email_account.email_account.pull’, kwargs={}, method=u’frappe.email.doctype.email_account.email_account.pull’, site=u’site1.local’, user=u’Administrator’) (c21c69d9-35fa-4d36-a553-953bc7fe254e)
04:28:52 worker_short.1 | 04:28:52 short: Job OK (c21c69d9-35fa-4d36-a553-953bc7fe254e)
04:28:52 worker_short.1 | 04:28:52 Result is kept for 500 seconds
04:28:52 worker_short.1 | 04:28:52
04:28:52 worker_short.1 | 04:28:52 *** Listening on short…
04:28:52 worker_short.1 | 04:28:52 short: frappe.utils.background_jobs.execute_job(async=True, event=u’all’, job_name=u’frappe.email.doctype.email_account.email_account.notify_unreplied’, kwargs={}, method=u’frappe.email.doctype.email_account.email_account.notify_unreplied’, site=u’site1.local’, user=u’Administrator’) (76e2a5a4-d6b0-4c20-aafc-5fda91080b2f)
04:28:52 worker_short.1 | 04:28:52 short: Job OK (76e2a5a4-d6b0-4c20-aafc-5fda91080b2f)
04:28:52 worker_short.1 | 04:28:52 Result is kept for 500 seconds
04:28:52 worker_short.1 | 04:28:52
04:28:52 worker_short.1 | 04:28:52 *** Listening on short…
04:28:52 worker_short.1 | 04:28:52 short: frappe.utils.background_jobs.execute_job(async=True, event=u’all’, job_name=u’frappe.oauth.delete_oauth2_data’, kwargs={}, method=u’frappe.oauth.delete_oauth2_data’, site=u’site1.local’, user=u’Administrator’) (8de4fd79-cc1f-4640-a657-d212c722bd70)
04:28:53 worker_short.1 | 04:28:53 short: Job OK (8de4fd79-cc1f-4640-a657-d212c722bd70)
04:28:53 worker_short.1 | 04:28:53 Result is kept for 500 seconds
04:28:53 worker_short.1 | 04:28:53
04:28:53 worker_short.1 | 04:28:53 *** Listening on short…
04:29:03 web.1 | 127.0.0.1 - - [22/Nov/2016 04:29:03] “POST / HTTP/1.0” 200 -
04:29:34 web.1 | 127.0.0.1 - - [22/Nov/2016 04:29:34] “POST / HTTP/1.0” 200 -
04:30:06 web.1 | 127.0.0.1 - - [22/Nov/2016 04:30:06] “POST / HTTP/1.0” 200 -
04:30:33 web.1 | 127.0.0.1 - - [22/Nov/2016 04:30:33] “POST / HTTP/1.0” 200 -
04:31:04 web.1 | 127.0.0.1 - - [22/Nov/2016 04:31:04] “POST / HTTP/1.0” 200 -
`