pi@raspberrypi:~ $ sudo doorpi_cli --trace --debug 2021-03-20 20:45:16,576 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) 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 2021-03-20 20:45:16,576 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace', '--debug'] 2021-03-20 20:45:16,577 [DEBUG] [doorpi.doorpi] run 2021-03-20 20:45:16,577 [DEBUG] [doorpi.doorpi] prepare 2021-03-20 20:45:16,577 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=True, test=False, trace=True) 2021-03-20 20:45:16,579 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2021-03-20 20:45:16,581 [DEBUG] [doorpi.conf.config_object] __init__ 2021-03-20 20:45:16,582 [TRACE] [doorpi.conf.config_object] get_from_config 2021-03-20 20:45:16,583 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2021-03-20 20:45:16,583 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2021-03-20 20:45:16,584 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2021-03-20 20:45:16,586 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2021-03-20 20:45:16,587 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2021-03-20 20:45:16,587 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2021-03-20 20:45:16,587 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2021-03-20 20:45:16,588 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2021-03-20 20:45:16,588 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2021-03-20 20:45:16,588 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2021-03-20 20:45:16,588 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2021-03-20 20:45:16,589 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2021-03-20 20:45:16,589 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2021-03-20 20:45:16,589 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2021-03-20 20:45:16,589 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2021-03-20 20:45:16,589 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2021-03-20 20:45:16,591 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2021-03-20 20:45:16,591 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2021-03-20 20:45:16,593 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2021-03-20 20:45:16,593 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2021-03-20 20:45:16,594 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2021-03-20 20:45:16,594 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2021-03-20 20:45:16,596 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2021-03-20 20:45:16,596 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2021-03-20 20:45:16,597 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2021-03-20 20:45:16,597 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2021-03-20 20:45:16,597 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2021-03-20 20:45:16,597 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2021-03-20 20:45:16,598 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2021-03-20 20:45:16,599 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2021-03-20 20:45:16,599 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2021-03-20 20:45:16,600 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2021-03-20 20:45:16,600 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2021-03-20 20:45:16,601 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2021-03-20 20:45:16,601 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2021-03-20 20:45:16,602 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2021-03-20 20:45:16,602 [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 2021-03-20 20:45:16,603 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2021-03-20 20:45:16,603 [WARNING] [root] section WritePermission not found in configfile 2021-03-20 20:45:16,603 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2021-03-20 20:45:16,603 [WARNING] [root] section ReadPermission not found in configfile 2021-03-20 20:45:16,604 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2021-03-20 20:45:16,604 [WARNING] [root] section Group not found in configfile 2021-03-20 20:45:16,604 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2021-03-20 20:45:16,605 [WARNING] [root] section User not found in configfile 2021-03-20 20:45:16,605 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2021-03-20 20:45:16,605 [TRACE] [doorpi.conf.config_object] get_sections returns ['DoorPiWeb', 'keyboards', 'EVENT_OnStartup', 'SIP-Phone', 'DoorPi'] 2021-03-20 20:45:16,606 [ERROR] [doorpi.status.webserver] no ReadPermission found 2021-03-20 20:45:16,606 [ERROR] [doorpi.status.webserver] no groups in configfile! 2021-03-20 20:45:16,607 [ERROR] [doorpi.status.webserver] no WritePermission found 2021-03-20 20:45:16,607 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2021-03-20 20:45:16,607 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2021-03-20 20:45:16,608 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2021-03-20 20:45:16,609 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,609 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2021-03-20 20:45:16,610 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,610 [DEBUG] [doorpi.action.handler] [0OA0QC] fire for event OnWebServerStart this actions [] 2021-03-20 20:45:16,610 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,610 [TRACE] [doorpi.action.handler] [0OA0QC] try to fire action WebServerStartupAction with args () and kwargs {} 2021-03-20 20:45:16,611 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,611 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2021-03-20 20:45:16,611 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,612 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,612 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,613 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,613 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,613 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,613 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,614 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,614 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,614 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,615 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,615 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2021-03-20 20:45:16,615 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['dummy'] 2021-03-20 20:45:16,616 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: dummy) 2021-03-20 20:45:16,616 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'dummy' to handler 2021-03-20 20:45:16,616 [TRACE] [doorpi.conf.config_object] get_string for key dummy in section keyboards (default: dummy) returns dummy 2021-03-20 20:45:16,617 [WARNING] [root] section dummy_InputPins not found in configfile 2021-03-20 20:45:16,617 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2021-03-20 20:45:16,617 [WARNING] [root] section dummy_OutputPins not found in configfile 2021-03-20 20:45:16,618 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2021-03-20 20:45:16,618 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section dummy_keyboard (default: 2000) returns 2000.0 2021-03-20 20:45:16,618 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section dummy_keyboard (default: 0) returns 0 2021-03-20 20:45:16,619 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section dummy_keyboard (default: True) returns True 2021-03-20 20:45:16,619 [DEBUG] [doorpi.keyboard.from_dummy] doorpi.keyboard.from_dummy loaded 2021-03-20 20:45:16,620 [DEBUG] [doorpi.keyboard.from_dummy] __init__(input_pins = [], output_pins = [], bouncetime = 2000.0, polarity = 0) 2021-03-20 20:45:16,620 [WARNING] [root] section dummy_InputPins not found in configfile 2021-03-20 20:45:16,620 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2021-03-20 20:45:16,621 [WARNING] [root] section dummy_OutputPins not found in configfile 2021-03-20 20:45:16,621 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2021-03-20 20:45:16,621 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2021-03-20 20:45:16,622 [WARNING] [root] section dummy_OutputPins not found in configfile 2021-03-20 20:45:16,622 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2021-03-20 20:45:16,623 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2021-03-20 20:45:16,624 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2021-03-20 20:45:16,625 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2021-03-20 20:45:16,627 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2021-03-20 20:45:16,655 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2021-03-20 20:45:16,657 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2021-03-20 20:45:16,658 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2021-03-20 20:45:16,700 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2021-03-20 20:45:16,703 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2021-03-20 20:45:16,704 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns dummy 2021-03-20 20:45:16,705 [DEBUG] [doorpi.sipphone.from_dummy] doorpi.sipphone.from_dummy loaded 2021-03-20 20:45:16,706 [WARNING] [doorpi.sipphone.from_dummy] No sipphone in config - use dummy sipphone without functionality 2021-03-20 20:45:16,707 [DEBUG] [doorpi.sipphone.from_dummy] __init__ 2021-03-20 20:45:16,708 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2021-03-20 20:45:16,708 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,708 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_dummy was added 2021-03-20 20:45:16,709 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_dummy 2021-03-20 20:45:16,709 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,709 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_dummy 2021-03-20 20:45:16,709 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,710 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_dummy 2021-03-20 20:45:16,710 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2021-03-20 20:45:16,710 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,711 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.from_dummy 2021-03-20 20:45:16,711 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,711 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.from_dummy 2021-03-20 20:45:16,711 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,712 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.from_dummy 2021-03-20 20:45:16,712 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.from_dummy asyncron 2021-03-20 20:45:16,713 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_dummy asyncron 2021-03-20 20:45:16,714 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,714 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneStart from doorpi.sipphone.from_dummy asyncron 2021-03-20 20:45:16,715 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,717 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2021-03-20 20:45:16,717 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2021-03-20 20:45:16,717 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2021-03-20 20:45:16,718 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2021-03-20 20:45:16,718 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2021-03-20 20:45:16,719 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneStart - skip fire_event OnSipPhoneStart from doorpi.sipphone.from_dummy 2021-03-20 20:45:16,719 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2021-03-20 20:45:16,720 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2021-03-20 20:45:16,721 [WARNING] [root] section dummy_InputPins not found in configfile 2021-03-20 20:45:16,721 [WARNING] [root] section DTMF not found in configfile 2021-03-20 20:45:16,721 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2021-03-20 20:45:16,722 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2021-03-20 20:45:16,722 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2021-03-20 20:45:16,723 [DEBUG] [doorpi.action.handler] [UJT7ZS] fire for event OnStartup this actions [] 2021-03-20 20:45:16,723 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2021-03-20 20:45:16,724 [TRACE] [doorpi.action.handler] [UJT7ZS] try to fire action SleepAction with args (1.0,) and kwargs {} 2021-03-20 20:45:16,724 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2021-03-20 20:45:17,727 [TRACE] [doorpi.action.handler] [UJT7ZS] finished fire_event for event_name OnStartup 2021-03-20 20:45:17,727 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2021-03-20 20:45:17,729 [INFO] [doorpi.doorpi] DoorPi started successfully 2021-03-20 20:45:17,730 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2021-03-20 20:45:17,731 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2021-03-20 20:45:17,731 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/