___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | 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 2018-03-22 16:09:30,641 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2018-03-22 16:09:30,643 [DEBUG] [doorpi.doorpi] run 2018-03-22 16:09:30,643 [DEBUG] [doorpi.doorpi] prepare 2018-03-22 16:09:30,644 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2018-03-22 16:09:30,657 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2018-03-22 16:09:30,662 [DEBUG] [doorpi.conf.config_object] __init__ 2018-03-22 16:09:30,663 [TRACE] [doorpi.conf.config_object] get_from_config 2018-03-22 16:09:30,666 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2018-03-22 16:09:30,667 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2018-03-22 16:09:30,668 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2018-03-22 16:09:30,708 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2018-03-22 16:09:30,709 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2018-03-22 16:09:30,710 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2018-03-22 16:09:30,710 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2018-03-22 16:09:30,711 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2018-03-22 16:09:30,711 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2018-03-22 16:09:30,712 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2018-03-22 16:09:30,712 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2018-03-22 16:09:30,713 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2018-03-22 16:09:30,714 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2018-03-22 16:09:30,714 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2018-03-22 16:09:30,715 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2018-03-22 16:09:30,715 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2018-03-22 16:09:30,720 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2018-03-22 16:09:30,722 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2018-03-22 16:09:30,726 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2018-03-22 16:09:30,726 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2018-03-22 16:09:30,727 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2018-03-22 16:09:30,728 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2018-03-22 16:09:30,736 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2018-03-22 16:09:30,737 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2018-03-22 16:09:30,738 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2018-03-22 16:09:30,738 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2018-03-22 16:09:30,739 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2018-03-22 16:09:30,739 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2018-03-22 16:09:30,740 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2018-03-22 16:09:30,742 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2018-03-22 16:09:30,743 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2018-03-22 16:09:30,744 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2018-03-22 16:09:30,745 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2018-03-22 16:09:30,747 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2018-03-22 16:09:30,747 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2018-03-22 16:09:30,749 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2018-03-22 16:09:30,750 [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 2018-03-22 16:09:30,752 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2018-03-22 16:09:30,752 [WARNING] [root] section WritePermission not found in configfile 2018-03-22 16:09:30,753 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2018-03-22 16:09:30,754 [WARNING] [root] section ReadPermission not found in configfile 2018-03-22 16:09:30,755 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2018-03-22 16:09:30,755 [WARNING] [root] section Group not found in configfile 2018-03-22 16:09:30,756 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2018-03-22 16:09:30,757 [WARNING] [root] section User not found in configfile 2018-03-22 16:09:30,757 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2018-03-22 16:09:30,758 [TRACE] [doorpi.conf.config_object] get_sections returns ['DoorPiWeb', 'keyboards', 'EVENT_OnStartup', 'SIP-Phone', 'DoorPi'] 2018-03-22 16:09:30,759 [ERROR] [doorpi.status.webserver] no ReadPermission found 2018-03-22 16:09:30,759 [ERROR] [doorpi.status.webserver] no groups in configfile! 2018-03-22 16:09:30,760 [ERROR] [doorpi.status.webserver] no WritePermission found 2018-03-22 16:09:30,761 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2018-03-22 16:09:30,762 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2018-03-22 16:09:30,763 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2018-03-22 16:09:30,765 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,766 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2018-03-22 16:09:30,766 [DEBUG] [doorpi.action.handler] [9UDGR5] fire for event OnWebServerStart this actions [] 2018-03-22 16:09:30,767 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,768 [TRACE] [doorpi.action.handler] [9UDGR5] try to fire action WebServerStartupAction with args () and kwargs {} 2018-03-22 16:09:30,768 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,769 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2018-03-22 16:09:30,770 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,771 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,772 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,772 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,773 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,773 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,774 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,775 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,775 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,776 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,777 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,777 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,778 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2018-03-22 16:09:30,779 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['dummy'] 2018-03-22 16:09:30,779 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: dummy) 2018-03-22 16:09:30,780 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'dummy' to handler 2018-03-22 16:09:30,781 [TRACE] [doorpi.conf.config_object] get_string for key dummy in section keyboards (default: dummy) returns dummy 2018-03-22 16:09:30,781 [WARNING] [root] section dummy_InputPins not found in configfile 2018-03-22 16:09:30,782 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2018-03-22 16:09:30,783 [WARNING] [root] section dummy_OutputPins not found in configfile 2018-03-22 16:09:30,784 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2018-03-22 16:09:30,785 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section dummy_keyboard (default: 2000) returns 2000.0 2018-03-22 16:09:30,785 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section dummy_keyboard (default: 0) returns 0 2018-03-22 16:09:30,786 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section dummy_keyboard (default: True) returns True 2018-03-22 16:09:30,790 [DEBUG] [doorpi.keyboard.from_dummy] doorpi.keyboard.from_dummy loaded 2018-03-22 16:09:30,791 [DEBUG] [doorpi.keyboard.from_dummy] __init__(input_pins = [], output_pins = [], bouncetime = 2000.0, polarity = 0) 2018-03-22 16:09:30,792 [WARNING] [root] section dummy_InputPins not found in configfile 2018-03-22 16:09:30,793 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2018-03-22 16:09:30,793 [WARNING] [root] section dummy_OutputPins not found in configfile 2018-03-22 16:09:30,794 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2018-03-22 16:09:30,795 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2018-03-22 16:09:30,795 [WARNING] [root] section dummy_OutputPins not found in configfile 2018-03-22 16:09:30,796 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2018-03-22 16:09:30,800 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2018-03-22 16:09:30,805 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2018-03-22 16:09:30,809 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2018-03-22 16:09:30,812 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2018-03-22 16:09:31,049 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2018-03-22 16:09:31,053 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2018-03-22 16:09:31,058 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2018-03-22 16:09:31,207 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2018-03-22 16:09:31,220 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2018-03-22 16:09:31,221 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns dummy 2018-03-22 16:09:31,226 [DEBUG] [doorpi.sipphone.from_dummy] doorpi.sipphone.from_dummy loaded 2018-03-22 16:09:31,227 [WARNING] [doorpi.sipphone.from_dummy] No sipphone in config - use dummy sipphone without functionality 2018-03-22 16:09:31,229 [DEBUG] [doorpi.sipphone.from_dummy] __init__ 2018-03-22 16:09:31,230 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2018-03-22 16:09:31,230 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,231 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_dummy was added 2018-03-22 16:09:31,232 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_dummy 2018-03-22 16:09:31,232 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,233 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_dummy 2018-03-22 16:09:31,234 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,234 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_dummy 2018-03-22 16:09:31,235 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2018-03-22 16:09:31,236 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,236 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.from_dummy 2018-03-22 16:09:31,237 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,238 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.from_dummy 2018-03-22 16:09:31,238 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,239 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.from_dummy 2018-03-22 16:09:31,240 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.from_dummy asyncron 2018-03-22 16:09:31,242 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_dummy asyncron 2018-03-22 16:09:31,244 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,245 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneStart from doorpi.sipphone.from_dummy asyncron 2018-03-22 16:09:31,247 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,249 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2018-03-22 16:09:31,249 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2018-03-22 16:09:31,250 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneStart - skip fire_event OnSipPhoneStart from doorpi.sipphone.from_dummy 2018-03-22 16:09:31,251 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2018-03-22 16:09:31,252 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2018-03-22 16:09:31,253 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2018-03-22 16:09:31,255 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2018-03-22 16:09:31,256 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2018-03-22 16:09:31,257 [WARNING] [root] section dummy_InputPins not found in configfile 2018-03-22 16:09:31,258 [WARNING] [root] section DTMF not found in configfile 2018-03-22 16:09:31,258 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2018-03-22 16:09:31,259 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2018-03-22 16:09:31,260 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2018-03-22 16:09:31,262 [DEBUG] [doorpi.action.handler] [XK391I] fire for event OnStartup this actions [] 2018-03-22 16:09:31,263 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2018-03-22 16:09:31,264 [TRACE] [doorpi.action.handler] [XK391I] try to fire action SleepAction with args (1.0,) and kwargs {} 2018-03-22 16:09:31,265 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2018-03-22 16:09:32,267 [TRACE] [doorpi.action.handler] [XK391I] finished fire_event for event_name OnStartup 2018-03-22 16:09:32,268 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2018-03-22 16:09:32,270 [INFO] [doorpi.doorpi] DoorPi started successfully 2018-03-22 16:09:32,271 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2018-03-22 16:09:32,272 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2018-03-22 16:09:32,272 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/