2016-06-04 18:46:01,070 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.4 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-06-04 18:46:01,071 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-06-04 18:46:01,072 [DEBUG] [doorpi.doorpi] run 2016-06-04 18:46:01,072 [DEBUG] [doorpi.doorpi] prepare 2016-06-04 18:46:01,072 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-06-04 18:46:01,077 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-06-04 18:46:01,083 [DEBUG] [doorpi.conf.config_object] __init__ 2016-06-04 18:46:01,084 [TRACE] [doorpi.conf.config_object] get_from_config 2016-06-04 18:46:01,088 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-06-04 18:46:01,089 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-06-04 18:46:01,090 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-06-04 18:46:01,093 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-06-04 18:46:01,093 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-06-04 18:46:01,094 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-06-04 18:46:01,094 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-06-04 18:46:01,095 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-06-04 18:46:01,095 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-06-04 18:46:01,095 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-06-04 18:46:01,096 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-06-04 18:46:01,096 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-06-04 18:46:01,096 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-06-04 18:46:01,097 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-06-04 18:46:01,097 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-06-04 18:46:01,097 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-06-04 18:46:01,099 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-06-04 18:46:01,100 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-06-04 18:46:01,104 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-06-04 18:46:01,104 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-06-04 18:46:01,105 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-06-04 18:46:01,105 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-06-04 18:46:01,107 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2016-06-04 18:46:01,108 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2016-06-04 18:46:01,110 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 8080 2016-06-04 18:46:01,111 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-06-04 18:46:01,111 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-06-04 18:46:01,112 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-06-04 18:46:01,112 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-06-04 18:46:01,112 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-06-04 18:46:01,113 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-06-04 18:46:01,114 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-06-04 18:46:01,115 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-06-04 18:46:01,116 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-06-04 18:46:01,116 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-06-04 18:46:01,117 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-06-04 18:46:01,118 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-06-04 18:46:01,119 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-06-04 18:46:01,119 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-06-04 18:46:01,120 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-06-04 18:46:01,120 [WARNING] [root] section WritePermission not found in configfile 2016-06-04 18:46:01,121 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2016-06-04 18:46:01,121 [WARNING] [root] section ReadPermission not found in configfile 2016-06-04 18:46:01,122 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-06-04 18:46:01,122 [WARNING] [root] section Group not found in configfile 2016-06-04 18:46:01,122 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2016-06-04 18:46:01,123 [WARNING] [root] section User not found in configfile 2016-06-04 18:46:01,123 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2016-06-04 18:46:01,124 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnKeyPressed_prodsystem.1', 'keyboards', 'prodsystem_InputPins', 'prodsystem_keyboard', 'DTMF', 'SIP-Phone', 'prodsystem_OutputPins', 'EVENT_OnStartup', 'DoorPiWeb', 'EVENT_OnKeyPressed_prodsystem.2', 'EVENT_OnKeyDown_prodsystem.3', 'DoorPi'] 2016-06-04 18:46:01,124 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-06-04 18:46:01,125 [ERROR] [doorpi.status.webserver] no groups in configfile! 2016-06-04 18:46:01,125 [ERROR] [doorpi.status.webserver] no WritePermission found 2016-06-04 18:46:01,125 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-06-04 18:46:01,126 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-06-04 18:46:01,126 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-06-04 18:46:01,128 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,129 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-06-04 18:46:01,129 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,130 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,130 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,130 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,131 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,131 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,132 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,132 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,132 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,133 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,133 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,133 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,134 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,134 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,134 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-06-04 18:46:01,135 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnTimeSecondEvenNumber 2016-06-04 18:46:01,136 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['nextion', 'prodsystem'] 2016-06-04 18:46:01,136 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: nextion, prodsystem) 2016-06-04 18:46:01,136 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'nextion' to handler 2016-06-04 18:46:01,137 [TRACE] [doorpi.conf.config_object] get_string for key nextion in section keyboards (default: dummy) returns nextion 2016-06-04 18:46:01,137 [WARNING] [root] section nextion_InputPins not found in configfile 2016-06-04 18:46:01,138 [TRACE] [doorpi.conf.config_object] get_keys for section nextion_InputPins returns [] 2016-06-04 18:46:01,138 [WARNING] [root] section nextion_OutputPins not found in configfile 2016-06-04 18:46:01,139 [TRACE] [doorpi.conf.config_object] get_keys for section nextion_OutputPins returns [] 2016-06-04 18:46:01,139 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section nextion_keyboard (default: 2000) returns 2000.0 2016-06-04 18:46:01,140 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section nextion_keyboard (default: 0) returns 0 2016-06-04 18:46:01,140 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section nextion_keyboard (default: True) returns True Exception in thread OnWebServerStart from doorpi.status.webserver: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-06-04 18:46:11,125 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2016-06-04 18:46:11,126 [DEBUG] [doorpi.doorpi] destroy doorpi 2016-06-04 18:46:11,126 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1995542528)>] 2016-06-04 18:46:11,127 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron Exception in thread BeforeShutdown from doorpi.doorpi: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Traceback (most recent call last): File "/usr/local/bin/doorpi_cli", line 11, in sys.exit(entry_point()) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 169, in entry_point raise SystemExit(main_as_application(sys.argv)) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 155, in main_as_application finally: doorpi.DoorPi().destroy() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 205, in destroy self.event_handler.fire_event_synchron('OnShutdown', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) sqlite3.OperationalError: database is locked