2020-08-14 21:22:23,862 [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 2020-08-14 21:22:23,862 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--debug', '--configfile', '/usr/local/etc/DoorPi/conf/doorpi.ini'] 2020-08-14 21:22:23,863 [DEBUG] [doorpi.doorpi] run 2020-08-14 21:22:23,863 [DEBUG] [doorpi.doorpi] prepare 2020-08-14 21:22:23,863 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile='/usr/local/etc/DoorPi/conf/doorpi.ini', debug=True, test=False, trace=False) 2020-08-14 21:22:23,865 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2020-08-14 21:22:23,870 [DEBUG] [doorpi.conf.config_object] __init__ 2020-08-14 21:22:23,870 [TRACE] [doorpi.conf.config_object] get_from_config 2020-08-14 21:22:23,874 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2020-08-14 21:22:23,874 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2020-08-14 21:22:23,875 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2020-08-14 21:22:23,877 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2020-08-14 21:22:23,877 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2020-08-14 21:22:23,878 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2020-08-14 21:22:23,878 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2020-08-14 21:22:23,878 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2020-08-14 21:22:23,878 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2020-08-14 21:22:23,878 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2020-08-14 21:22:23,879 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2020-08-14 21:22:23,879 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2020-08-14 21:22:23,879 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2020-08-14 21:22:23,879 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2020-08-14 21:22:23,879 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2020-08-14 21:22:23,880 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2020-08-14 21:22:23,881 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2020-08-14 21:22:23,881 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2020-08-14 21:22:23,883 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2020-08-14 21:22:23,883 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2020-08-14 21:22:23,883 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2020-08-14 21:22:23,884 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 8081 2020-08-14 21:22:23,885 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 8081 2020-08-14 21:22:23,886 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2020-08-14 21:22:23,886 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2020-08-14 21:22:23,886 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2020-08-14 21:22:23,887 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2020-08-14 21:22:23,887 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2020-08-14 21:22:23,887 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2020-08-14 21:22:23,888 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2020-08-14 21:22:23,888 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2020-08-14 21:22:23,888 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2020-08-14 21:22:23,889 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2020-08-14 21:22:23,889 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2020-08-14 21:22:23,889 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2020-08-14 21:22:23,890 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2020-08-14 21:22:23,890 [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 2020-08-14 21:22:23,891 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2020-08-14 21:22:23,891 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2020-08-14 21:22:23,891 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrators'] 2020-08-14 21:22:23,891 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators'] 2020-08-14 21:22:23,892 [WARNING] [root] section User not found in configfile 2020-08-14 21:22:23,892 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2020-08-14 21:22:23,892 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['admin'] 2020-08-14 21:22:23,892 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'Group', 'EVENT_OnKeyPressed_rfid.0010327614', 'ReadPermission', 'AdminNumbers', 'keyboards', 'DTMF', 'SIP-Phone', 'WritePermission', 'EVENT_OnKeyUp_0', 'EVENT_OnKeyPressed_onboardpins.1', 'EVENT_OnStartup', 'EVENT_OnKeyPressed_onboardpins.6', 'rfidreader_InputPins', 'DoorPiWeb', 'rfidreader_keyboard', 'onboardpins_InputPins', 'DoorPi'] 2020-08-14 21:22:23,893 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['public'] 2020-08-14 21:22:23,893 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section ReadPermission (default: []) returns ['public'] 2020-08-14 21:22:23,893 [ERROR] [doorpi.status.webserver] user admin is assigned to group administrators but doesn't exist as user 2020-08-14 21:22:23,894 [ERROR] [doorpi.status.webserver] module public doesn't exist but is assigned to group administrators in WritePermission 2020-08-14 21:22:23,894 [ERROR] [doorpi.status.webserver] module public doesn't exist but is assigned to group administrators in ReadPermission 2020-08-14 21:22:23,894 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2020-08-14 21:22:23,894 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2020-08-14 21:22:23,894 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2020-08-14 21:22:23,896 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,896 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2020-08-14 21:22:23,896 [DEBUG] [doorpi.action.handler] [1CSO5R] fire for event OnWebServerStart this actions [] 2020-08-14 21:22:23,897 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,897 [TRACE] [doorpi.action.handler] [1CSO5R] try to fire action WebServerStartupAction with args () and kwargs {} 2020-08-14 21:22:23,897 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,897 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2020-08-14 21:22:23,898 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,898 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,898 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,899 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,899 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,899 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,899 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,899 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,900 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,900 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,900 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,900 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,900 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2020-08-14 21:22:23,901 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['rfidreader', 'onboardpins'] 2020-08-14 21:22:23,901 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: rfidreader, onboardpins) 2020-08-14 21:22:23,901 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'rfidreader' to handler 2020-08-14 21:22:23,901 [TRACE] [doorpi.conf.config_object] get_string for key rfidreader in section keyboards (default: dummy) returns hxgreader 2020-08-14 21:22:23,902 [TRACE] [doorpi.conf.config_object] get_keys for section rfidreader_InputPins returns ['0010327614'] 2020-08-14 21:22:23,902 [WARNING] [root] section rfidreader_OutputPins not found in configfile 2020-08-14 21:22:23,902 [TRACE] [doorpi.conf.config_object] get_keys for section rfidreader_OutputPins returns [] 2020-08-14 21:22:23,902 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section rfidreader_keyboard (default: 2000) returns 5000.0 2020-08-14 21:22:23,903 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section rfidreader_keyboard (default: 0) returns 0 2020-08-14 21:22:23,903 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section rfidreader_keyboard (default: True) returns True 2020-08-14 21:22:23,963 [DEBUG] [doorpi.keyboard.from_hxgreader] doorpi.keyboard.from_hxgreader loaded 2020-08-14 21:22:23,963 [DEBUG] [doorpi.keyboard.from_hxgreader] __init__ (input_pins = ['0010327614']) 2020-08-14 21:22:23,964 [TRACE] [doorpi.action.handler] register Event OnFoundTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,964 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_hxgreader was added 2020-08-14 21:22:23,964 [TRACE] [doorpi.action.handler] added event_name OnFoundTag and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,964 [TRACE] [doorpi.action.handler] register Event OnFoundUnknownTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,965 [TRACE] [doorpi.action.handler] added event_name OnFoundUnknownTag and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,965 [TRACE] [doorpi.action.handler] register Event OnFoundKnownTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,965 [TRACE] [doorpi.action.handler] added event_name OnFoundKnownTag and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,967 [TRACE] [doorpi.keyboard.from_hxgreader] 2 input devices found 2020-08-14 21:22:23,967 [TRACE] [doorpi.keyboard.from_hxgreader] C-Media Electronics Inc. USB PnP Sound Device 2020-08-14 21:22:23,967 [TRACE] [doorpi.conf.config_object] get_string for key device in section rfidreader_keyboard (default: HXGCoLtd Keyboard) returns HXGCoLtd Keyboard 2020-08-14 21:22:23,968 [TRACE] [doorpi.keyboard.from_hxgreader] HXGCoLtd Keyboard 2020-08-14 21:22:23,968 [TRACE] [doorpi.conf.config_object] get_string for key device in section rfidreader_keyboard (default: HXGCoLtd Keyboard) returns HXGCoLtd Keyboard 2020-08-14 21:22:23,968 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,968 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,968 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,969 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0010327614 and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,969 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,969 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_rfidreader.0010327614 and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,969 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,969 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,970 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,970 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0010327614 and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,970 [TRACE] [doorpi.action.handler] register Event OnKeyUp_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,970 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_rfidreader.0010327614 and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,970 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,971 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,971 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,971 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0010327614 and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,971 [TRACE] [doorpi.action.handler] register Event OnKeyDown_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,971 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_rfidreader.0010327614 and registered source doorpi.keyboard.from_hxgreader 2020-08-14 21:22:23,972 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2020-08-14 21:22:24,015 [WARNING] [root] section rfidreader_OutputPins not found in configfile 2020-08-14 21:22:24,015 [TRACE] [doorpi.conf.config_object] get_keys for section rfidreader_OutputPins returns [] 2020-08-14 21:22:24,016 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2020-08-14 21:22:24,016 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2020-08-14 21:22:24,016 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2020-08-14 21:22:24,016 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['0'] 2020-08-14 21:22:24,017 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 2000.0 2020-08-14 21:22:24,017 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2020-08-14 21:22:24,017 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2020-08-14 21:22:24,018 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2020-08-14 21:22:24,035 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0', '3', '2', '5', '4', '7', '6'], output_pins = ['0'], polarity = 0) 2020-08-14 21:22:24,086 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,087 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2020-08-14 21:22:24,087 [TRACE] [doorpi.action.handler] added event_source doorpi.keyboard.from_piface to existing event OnKeyPressed 2020-08-14 21:22:24,087 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,088 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,088 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,088 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,088 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,088 [TRACE] [doorpi.action.handler] added event_source doorpi.keyboard.from_piface to existing event OnKeyUp 2020-08-14 21:22:24,089 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,089 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,089 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,089 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,089 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,090 [TRACE] [doorpi.action.handler] added event_source doorpi.keyboard.from_piface to existing event OnKeyDown 2020-08-14 21:22:24,090 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,090 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,090 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,091 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,091 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,091 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,091 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,091 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,092 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,092 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.0 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,092 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,092 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,092 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,093 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,093 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,093 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.0 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,093 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,093 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,094 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,094 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,094 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,094 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.0 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,095 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,095 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,095 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,095 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_3 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,096 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.3 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,096 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.3 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,096 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,096 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,096 [TRACE] [doorpi.action.handler] register Event OnKeyUp_3 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,097 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_3 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,097 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.3 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,097 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.3 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,097 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,098 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,098 [TRACE] [doorpi.action.handler] register Event OnKeyDown_3 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,098 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_3 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,098 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.3 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,098 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.3 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,099 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,099 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,099 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,099 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,099 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.2 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,100 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.2 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,100 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,100 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,100 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,100 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,101 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.2 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,101 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.2 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,101 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,101 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,102 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,102 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,102 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.2 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,102 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.2 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,102 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,103 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,103 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_5 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,103 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_5 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,103 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.5 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,103 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.5 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,104 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,104 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,104 [TRACE] [doorpi.action.handler] register Event OnKeyUp_5 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,104 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_5 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,104 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.5 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,105 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.5 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,105 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,105 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,105 [TRACE] [doorpi.action.handler] register Event OnKeyDown_5 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,106 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_5 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,106 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.5 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,106 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.5 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,106 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,107 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,107 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_4 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,107 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_4 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,107 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.4 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,107 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.4 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,108 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,108 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,108 [TRACE] [doorpi.action.handler] register Event OnKeyUp_4 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,108 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_4 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,108 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.4 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,109 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.4 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,109 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,109 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,109 [TRACE] [doorpi.action.handler] register Event OnKeyDown_4 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,109 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_4 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,110 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.4 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,110 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.4 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,110 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,110 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,111 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,111 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,111 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,111 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.7 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,111 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,112 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,112 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,112 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,112 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,112 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.7 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,113 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,113 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,113 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,113 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,113 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,114 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.7 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,114 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-14 21:22:24,114 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,114 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_6 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,115 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_6 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,115 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.6 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,115 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.6 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,115 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:24,116 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,116 [TRACE] [doorpi.action.handler] register Event OnKeyUp_6 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,116 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_6 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,116 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.6 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,116 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.6 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,117 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-14 21:22:24,117 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-14 21:22:24,117 [TRACE] [doorpi.action.handler] register Event OnKeyDown_6 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,117 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_6 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,117 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.6 from doorpi.keyboard.from_piface 2020-08-14 21:22:24,118 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.6 and registered source doorpi.keyboard.from_piface 2020-08-14 21:22:24,125 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2020-08-14 21:22:24,126 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['0'] 2020-08-14 21:22:24,127 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_OutputPins (default: ) returns Tueroeffner 2020-08-14 21:22:24,129 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2020-08-14 21:22:24,130 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2020-08-14 21:22:24,132 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2020-08-14 21:22:24,133 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2020-08-14 21:22:24,164 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2020-08-14 21:22:24,165 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2020-08-14 21:22:24,167 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2020-08-14 21:22:24,178 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2020-08-14 21:22:24,181 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2020-08-14 21:22:24,182 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2020-08-14 21:22:24,183 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2020-08-14 21:22:24,184 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2020-08-14 21:22:24,185 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2020-08-14 21:22:24,187 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2020-08-14 21:22:24,187 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2020-08-14 21:22:24,188 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2020-08-14 21:22:24,188 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,188 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2020-08-14 21:22:24,189 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,189 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,189 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,189 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,189 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,190 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,190 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,190 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,190 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,190 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,191 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,191 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,191 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,191 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,191 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,192 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,192 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,192 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,192 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,192 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,193 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,193 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,193 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2020-08-14 21:22:24,193 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2020-08-14 21:22:24,194 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2020-08-14 21:22:24,194 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,194 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2020-08-14 21:22:24,194 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,194 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,195 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,195 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,195 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,195 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,196 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,196 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,196 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,196 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,196 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,197 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,197 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,197 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,197 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,198 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,198 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,198 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,198 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,198 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,199 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,199 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,199 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,199 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"0"'] 2020-08-14 21:22:24,200 [TRACE] [doorpi.action.handler] register Event OnDTMF_"0" from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,200 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"0" and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,200 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,200 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2020-08-14 21:22:24,200 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,201 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,201 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,201 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,201 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,202 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,202 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,202 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,202 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,202 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,203 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,203 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,203 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,203 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,203 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,204 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,204 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,204 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,204 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:24,299 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-08-14 21:22:24,300 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:22:24,301 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:24,302 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2020-08-14 21:22:24,302 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2020-08-14 21:22:24,303 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-08-14 21:22:24,303 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-08-14 21:22:24,304 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-08-14 21:22:24,305 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2020-08-14 21:22:24,305 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2020-08-14 21:22:24,306 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2020-08-14 21:22:24,307 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2020-08-14 21:22:24,308 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2020-08-14 21:22:24,308 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2020-08-14 21:22:24,309 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2020-08-14 21:22:24,310 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2020-08-14 21:22:24,310 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2020-08-14 21:22:24,311 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2020-08-14 21:22:24,311 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2020-08-14 21:22:24,311 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2020-08-14 21:22:24,312 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2020-08-14 21:22:24,312 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-08-14 21:22:24,312 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2020-08-14 21:22:24,312 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-08-14 21:22:24,312 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2020-08-14 21:22:24,313 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2020-08-14 21:22:24,313 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2020-08-14 21:22:24,313 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2020-08-14 21:22:24,313 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-14 21:22:24,314 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2020-08-14 21:22:24,315 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-08-14 21:22:24,316 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2020-08-14 21:22:24,316 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,316 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2020-08-14 21:22:24,317 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,317 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,317 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,317 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,318 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,318 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2020-08-14 21:22:24,318 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2020-08-14 21:22:24,318 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2020-08-14 21:22:24,319 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2020-08-14 21:22:24,320 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2020-08-14 21:22:24,320 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2020-08-14 21:22:24,320 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-08-14 21:22:24,321 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2020-08-14 21:22:24,321 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2020-08-14 21:22:24,321 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-14 21:22:24,322 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2020-08-14 21:22:24,322 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB PnP Sound Device 2020-08-14 21:22:24,322 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB PnP Sound Device 2020-08-14 21:22:24,322 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-14 21:22:24,323 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2020-08-14 21:22:24,323 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2020-08-14 21:22:24,323 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2020-08-14 21:22:24,324 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2020-08-14 21:22:24,325 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-08-14 21:22:24,325 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-08-14 21:22:24,326 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2020-08-14 21:22:24,326 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2020-08-14 21:22:24,326 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2020-08-14 21:22:24,327 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2020-08-14 21:22:24,327 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-08-14 21:22:24,328 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-08-14 21:22:24,328 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-08-14 21:22:24,328 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2020-08-14 21:22:24,329 [DEBUG] [doorpi.sipphone.from_linphone] | name 2020-08-14 21:22:24,329 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-14 21:22:24,329 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2020-08-14 21:22:24,329 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2020-08-14 21:22:24,330 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-14 21:22:24,330 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2020-08-14 21:22:24,330 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2020-08-14 21:22:24,330 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2020-08-14 21:22:24,331 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2020-08-14 21:22:24,331 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2020-08-14 21:22:24,331 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.2.1 2020-08-14 21:22:24,332 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns klingeltest 2020-08-14 21:22:24,332 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: klingeltest) returns ******* 2020-08-14 21:22:24,332 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.2.1) returns 192.168.2.1 2020-08-14 21:22:24,332 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2020-08-14 21:22:24,333 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-08-14 21:22:24,335 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2020-08-14 21:22:24,335 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnKeyPressed_rfid.0010327614', 'EVENT_OnKeyUp_0', 'EVENT_OnKeyPressed_onboardpins.1', 'EVENT_OnStartup', 'EVENT_OnKeyPressed_onboardpins.6'] 2020-08-14 21:22:24,336 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_rfid.0010327614' in configfile 2020-08-14 21:22:24,336 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_rfid.0010327614 returns ['10'] 2020-08-14 21:22:24,336 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_rfid.0010327614' 2020-08-14 21:22:24,336 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_rfid.0010327614 (default: ) returns os_execute:aplay !BASEPATH!/media/hallo_danny.wav 2020-08-14 21:22:24,337 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2020-08-14 21:22:24,338 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'aplay /usr/local/etc/DoorPi/media/hallo_danny.wav'} was added to new evententry OnKeyPressed_rfid.0010327614 2020-08-14 21:22:24,338 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_0' in configfile 2020-08-14 21:22:24,338 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_0 returns ['10', '5'] 2020-08-14 21:22:24,339 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_0' 2020-08-14 21:22:24,339 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_0 (default: ) returns call:**612 2020-08-14 21:22:24,339 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2020-08-14 21:22:24,340 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**612'} was added to new evententry OnKeyUp_0 2020-08-14 21:22:24,340 [INFO] [doorpi.doorpi] registering action '5' for event 'OnKeyUp_0' 2020-08-14 21:22:24,340 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section EVENT_OnKeyUp_0 (default: ) returns os_execute:/usr/local/etc/DoorPi/scripts/Klingelbild.sh 2020-08-14 21:22:24,341 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} was added to event OnKeyUp_0 2020-08-14 21:22:24,342 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.1' in configfile 2020-08-14 21:22:24,342 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.1 returns ['10'] 2020-08-14 21:22:24,342 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.1' 2020-08-14 21:22:24,342 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.1 (default: ) returns os_execute:/usr/local/etc/DoorPi/scripts/Sabotagekontakt.sh 2020-08-14 21:22:24,343 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Sabotagekontakt.sh'} was added to new evententry OnKeyPressed_onboardpins.1 2020-08-14 21:22:24,343 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2020-08-14 21:22:24,344 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10', '20'] 2020-08-14 21:22:24,344 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2020-08-14 21:22:24,344 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2020-08-14 21:22:24,345 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2020-08-14 21:22:24,345 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2020-08-14 21:22:24,345 [INFO] [doorpi.doorpi] registering action '20' for event 'OnStartup' 2020-08-14 21:22:24,346 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnStartup (default: ) returns os_execute:/usr/local/etc/DoorPi/scripts/Startmeldung.sh 2020-08-14 21:22:24,346 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Startmeldung.sh'} was added to event OnStartup 2020-08-14 21:22:24,347 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.6' in configfile 2020-08-14 21:22:24,347 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.6 returns ['10'] 2020-08-14 21:22:24,347 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.6' 2020-08-14 21:22:24,347 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.6 (default: ) returns out:Tueroeffner,1,0,3 2020-08-14 21:22:24,348 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2020-08-14 21:22:24,349 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2020-08-14 21:22:24,349 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnKeyPressed_onboardpins.6 2020-08-14 21:22:24,349 [TRACE] [doorpi.conf.config_object] get_string for key 0010327614 in section rfidreader_InputPins (default: ) returns out:Tueroeffner,1,0,3 2020-08-14 21:22:24,350 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnKeyPressed_rfidreader.0010327614 2020-08-14 21:22:24,350 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,350 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to event OnKeyPressed_onboardpins.1 2020-08-14 21:22:24,351 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_InputPins (default: ) returns call:**612 2020-08-14 21:22:24,351 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**612'} was added to new evententry OnKeyPressed_onboardpins.0 2020-08-14 21:22:24,351 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,351 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.3 2020-08-14 21:22:24,352 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,352 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.2 2020-08-14 21:22:24,352 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,352 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.5 2020-08-14 21:22:24,353 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,353 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.4 2020-08-14 21:22:24,353 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,353 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.7 2020-08-14 21:22:24,354 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-14 21:22:24,354 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to event OnKeyPressed_onboardpins.6 2020-08-14 21:22:24,354 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"0"'] 2020-08-14 21:22:24,354 [TRACE] [doorpi.conf.config_object] get_string for key "0" in section DTMF (default: ) returns out:Tueroeffner,1,0,3 2020-08-14 21:22:24,355 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnDTMF_"0" 2020-08-14 21:22:24,355 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2020-08-14 21:22:24,355 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2020-08-14 21:22:24,356 [DEBUG] [doorpi.action.handler] [4LEOZU] fire for event OnStartup this actions [, ] 2020-08-14 21:22:24,357 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2020-08-14 21:22:24,357 [TRACE] [doorpi.action.handler] [4LEOZU] try to fire action SleepAction with args (1.0,) and kwargs {} 2020-08-14 21:22:24,357 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2020-08-14 21:22:25,360 [TRACE] [doorpi.action.handler] [4LEOZU] try to fire action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Startmeldung.sh'} 2020-08-14 21:22:25,360 [TRACE] [doorpi.action.base] run OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Startmeldung.sh'} 2020-08-14 21:22:25,667 [TRACE] [doorpi.action.handler] [4LEOZU] finished fire_event for event_name OnStartup 2020-08-14 21:22:25,668 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2020-08-14 21:22:25,669 [INFO] [doorpi.doorpi] DoorPi started successfully 2020-08-14 21:22:25,669 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2020-08-14 21:22:25,669 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi:8081/ 2020-08-14 21:22:25,677 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2020-08-14 21:22:29,411 [TRACE] [doorpi.action.handler] fire Event OnFoundTag from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,413 [DEBUG] [doorpi.keyboard.from_hxgreader] 1597436549.41: card 0010327614 found 2020-08-14 21:22:29,414 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,415 [DEBUG] [doorpi.action.handler] no actions for event OnFoundTag - skip fire_event OnFoundTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,417 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,418 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,420 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,421 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_0010327614 - skip fire_event OnKeyDown_0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,422 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,424 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,426 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,427 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_rfidreader.0010327614 - skip fire_event OnKeyDown_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,436 [DEBUG] [doorpi.action.handler] [DDE9BT] fire for event OnKeyPressed_rfidreader.0010327614 this actions [] 2020-08-14 21:22:29,437 [TRACE] [doorpi.action.handler] [DDE9BT] try to fire action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} 2020-08-14 21:22:29,438 [TRACE] [doorpi.action.base] run OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} 2020-08-14 21:22:29,440 [DEBUG] [doorpi.keyboard.from_piface] out(pin = 0, value = True, log_output = True) 2020-08-14 21:22:29,451 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,453 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,455 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,455 [WARNING] [doorpi.action.handler] event OnKeyPressed_rfidreader.rfidreader.0010327614 unknown - skip fire_event OnKeyPressed_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,456 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,457 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_rfidreader.rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,460 [WARNING] [doorpi.action.handler] event OnKeyUp_rfidreader.rfidreader.0010327614 unknown - skip fire_event OnKeyUp_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,461 [TRACE] [doorpi.action.handler] fire Event OnFoundKnownTag from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:22:29,462 [WARNING] [doorpi.action.handler] event OnKeyUp_rfidreader.rfidreader.rfidreader.0010327614 unknown - skip fire_event OnKeyUp_rfidreader.rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:29,464 [DEBUG] [doorpi.action.handler] no actions for event OnFoundKnownTag - skip fire_event OnFoundKnownTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:22:32,451 [DEBUG] [doorpi.keyboard.from_piface] out(pin = 0, value = False, log_output = True) 2020-08-14 21:22:32,454 [TRACE] [doorpi.action.handler] [DDE9BT] finished fire_event for event_name OnKeyPressed_rfidreader.0010327614 2020-08-14 21:22:32,506 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_piface asyncron 2020-08-14 21:22:32,508 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_0 from doorpi.keyboard.from_piface asyncron 2020-08-14 21:22:32,509 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:22:32,511 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface asyncron 2020-08-14 21:22:32,512 [DEBUG] [doorpi.action.handler] [7EP7TR] fire for event OnKeyUp_0 this actions [, ] 2020-08-14 21:22:32,513 [TRACE] [doorpi.action.handler] [7EP7TR] try to fire action CallAction with args () and kwargs {'number': '**612'} 2020-08-14 21:22:32,514 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '**612'} 2020-08-14 21:22:32,514 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:22:32,515 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.0 - skip fire_event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-14 21:22:32,516 [DEBUG] [doorpi.sipphone.from_linphone] call (**612) 2020-08-14 21:22:32,518 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:22:32,518 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2020-08-14 21:22:32,519 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2020-08-14 20:22:32.519410 2020-08-14 21:22:32,528 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call) 2020-08-14 21:22:32,529 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:32,530 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:32,537 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress) 2020-08-14 21:22:32,537 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:32,538 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:32,539 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:32,541 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:22:32,543 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:32,544 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:22:32,546 [TRACE] [doorpi.action.handler] [7EP7TR] try to fire action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} 2020-08-14 21:22:32,547 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:22:32,547 [DEBUG] [doorpi.action.handler] [1UX6CR] fire for event OnSipPhoneMakeCall this actions [] 2020-08-14 21:22:32,548 [TRACE] [doorpi.action.base] run OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} 2020-08-14 21:22:32,549 [TRACE] [doorpi.action.handler] [1UX6CR] try to fire action > with args () and kwargs {} 2020-08-14 21:22:32,550 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-14 21:22:32,550 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-14 21:22:32,574 [TRACE] [doorpi.action.handler] [1UX6CR] finished fire_event for event_name OnSipPhoneMakeCall 2020-08-14 21:22:32,575 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player --2020-08-14 21:22:32-- http://192.168.2.137:8181/html/cam.jpg Connecting to 192.168.2.137:8181... connected. HTTP request sent, awaiting response... 200 OK Length: 10903 (11K) [image/jpeg] Saving to: ‘/home/pi/Klingelbild.jpg’ 0K .......... 100% 25.8M=0s 2020-08-14 21:22:32 (25.8 MB/s) - ‘/home/pi/Klingelbild.jpg’ saved [10903/10903] 2020-08-14 21:22:32,691 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (5 - Early media) 2020-08-14 21:22:32,692 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:32,693 [TRACE] [doorpi.action.handler] fire Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:32,697 [DEBUG] [doorpi.action.handler] no actions for event OnCallMediaStateChange - skip fire_event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:32,697 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:34,850 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (12 - Busy Here) 2020-08-14 21:22:34,851 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:34,861 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:34,862 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:34,864 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:34,965 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2020-08-14 21:22:34,966 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:22:34,968 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:22:35,398 [TRACE] [doorpi.action.handler] [7EP7TR] finished fire_event for event_name OnKeyUp_0 2020-08-14 21:27:01,281 [TRACE] [doorpi.action.handler] fire Event OnFoundTag from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,283 [DEBUG] [doorpi.keyboard.from_hxgreader] 1597436821.28: card 0010327614 found 2020-08-14 21:27:01,284 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,287 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,287 [DEBUG] [doorpi.action.handler] no actions for event OnFoundTag - skip fire_event OnFoundTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,290 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,292 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_0010327614 - skip fire_event OnKeyDown_0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,293 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,296 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,298 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,300 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,301 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_rfidreader.0010327614 - skip fire_event OnKeyDown_rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,302 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,304 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,306 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,309 [WARNING] [doorpi.action.handler] event OnKeyPressed_rfidreader.rfidreader.0010327614 unknown - skip fire_event OnKeyPressed_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,311 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_rfidreader.rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,311 [DEBUG] [doorpi.action.handler] [S5MO7D] fire for event OnKeyPressed_rfidreader.0010327614 this actions [] 2020-08-14 21:27:01,313 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,313 [TRACE] [doorpi.action.handler] [S5MO7D] try to fire action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} 2020-08-14 21:27:01,315 [TRACE] [doorpi.action.handler] fire Event OnFoundKnownTag from doorpi.keyboard.from_hxgreader asyncron 2020-08-14 21:27:01,317 [TRACE] [doorpi.action.base] run OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} 2020-08-14 21:27:01,321 [DEBUG] [doorpi.keyboard.from_piface] out(pin = 0, value = True, log_output = True) 2020-08-14 21:27:01,324 [WARNING] [doorpi.action.handler] event OnKeyUp_rfidreader.rfidreader.rfidreader.0010327614 unknown - skip fire_event OnKeyUp_rfidreader.rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,325 [WARNING] [doorpi.action.handler] event OnKeyUp_rfidreader.rfidreader.0010327614 unknown - skip fire_event OnKeyUp_rfidreader.rfidreader.0010327614 from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:01,327 [DEBUG] [doorpi.action.handler] no actions for event OnFoundKnownTag - skip fire_event OnFoundKnownTag from doorpi.keyboard.from_hxgreader 2020-08-14 21:27:04,333 [DEBUG] [doorpi.keyboard.from_piface] out(pin = 0, value = False, log_output = True) 2020-08-14 21:27:04,336 [TRACE] [doorpi.action.handler] [S5MO7D] finished fire_event for event_name OnKeyPressed_rfidreader.0010327614 2020-08-14 21:27:04,386 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_piface asyncron 2020-08-14 21:27:04,388 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_0 from doorpi.keyboard.from_piface asyncron 2020-08-14 21:27:04,390 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_piface 2020-08-14 21:27:04,391 [DEBUG] [doorpi.action.handler] [X09D1L] fire for event OnKeyUp_0 this actions [, ] 2020-08-14 21:27:04,391 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface asyncron 2020-08-14 21:27:04,392 [TRACE] [doorpi.action.handler] [X09D1L] try to fire action CallAction with args () and kwargs {'number': '**612'} 2020-08-14 21:27:04,393 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '**612'} 2020-08-14 21:27:04,394 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:27:04,395 [DEBUG] [doorpi.sipphone.from_linphone] call (**612) 2020-08-14 21:27:04,396 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2020-08-14 21:27:04,397 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2020-08-14 20:27:04.397428 2020-08-14 21:27:04,406 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call) 2020-08-14 21:27:04,407 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:27:04,408 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:04,410 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.0 - skip fire_event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-14 21:27:04,415 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:04,422 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress) 2020-08-14 21:27:04,424 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:04,425 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:04,428 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:27:04,429 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:04,431 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:04,433 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-14 21:27:04,434 [DEBUG] [doorpi.action.handler] [0S24D3] fire for event OnSipPhoneMakeCall this actions [] 2020-08-14 21:27:04,435 [TRACE] [doorpi.action.handler] [0S24D3] try to fire action > with args () and kwargs {} 2020-08-14 21:27:04,436 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-14 21:27:04,437 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-14 21:27:04,439 [TRACE] [doorpi.action.handler] [0S24D3] finished fire_event for event_name OnSipPhoneMakeCall 2020-08-14 21:27:04,440 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2020-08-14 21:27:04,442 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-14 21:27:04,443 [TRACE] [doorpi.action.handler] [X09D1L] try to fire action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} 2020-08-14 21:27:04,444 [TRACE] [doorpi.action.base] run OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} --2020-08-14 21:27:04-- http://192.168.2.137:8181/html/cam.jpg Connecting to 192.168.2.137:8181... connected. HTTP request sent, awaiting response... 200 OK Length: 10831 (11K) [image/jpeg] Saving to: ‘/home/pi/Klingelbild.jpg’ 0K .......... 100% 48.1M=0s 2020-08-14 21:27:04 (48.1 MB/s) - ‘/home/pi/Klingelbild.jpg’ saved [10831/10831] 2020-08-14 21:27:04,594 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (5 - Early media) 2020-08-14 21:27:04,595 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:04,596 [TRACE] [doorpi.action.handler] fire Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:04,597 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:04,600 [DEBUG] [doorpi.action.handler] no actions for event OnCallMediaStateChange - skip fire_event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:08,171 [TRACE] [doorpi.action.handler] [X09D1L] finished fire_event for event_name OnKeyUp_0 2020-08-14 21:27:09,356 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (12 - Busy Here) 2020-08-14 21:27:09,357 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:09,359 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:09,362 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:09,362 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-14 21:27:09,468 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2020-08-14 21:27:09,473 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-14 21:27:09,476 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks