pi@krebs:~ $ sudo doorpi_cli --trace 2021-12-27 12:19:05,736 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2021-12-27 12:19:05,745 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2021-12-27 12:19:05,753 [DEBUG] [doorpi.doorpi] run 2021-12-27 12:19:05,759 [DEBUG] [doorpi.doorpi] prepare 2021-12-27 12:19:05,764 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2021-12-27 12:19:05,782 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2021-12-27 12:19:05,844 [DEBUG] [doorpi.conf.config_object] __init__ 2021-12-27 12:19:05,851 [TRACE] [doorpi.conf.config_object] get_from_config 2021-12-27 12:19:05,890 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2021-12-27 12:19:05,896 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2021-12-27 12:19:05,905 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2021-12-27 12:19:05,924 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2021-12-27 12:19:05,930 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2021-12-27 12:19:05,936 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2021-12-27 12:19:05,940 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2021-12-27 12:19:05,944 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2021-12-27 12:19:05,950 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2021-12-27 12:19:05,955 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2021-12-27 12:19:05,960 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2021-12-27 12:19:05,965 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2021-12-27 12:19:05,969 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2021-12-27 12:19:05,975 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2021-12-27 12:19:05,980 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2021-12-27 12:19:05,985 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2021-12-27 12:19:05,996 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2021-12-27 12:19:06,005 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2021-12-27 12:19:06,022 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2021-12-27 12:19:06,029 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2021-12-27 12:19:06,036 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 192.168.62.230 2021-12-27 12:19:06,040 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2021-12-27 12:19:06,058 [INFO] [doorpi.status.webserver] Initiating WebService at ip 192.168.62.230 and port 80 2021-12-27 12:19:06,065 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2021-12-27 12:19:06,070 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2021-12-27 12:19:06,076 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2021-12-27 12:19:06,082 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2021-12-27 12:19:06,088 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2021-12-27 12:19:06,092 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2021-12-27 12:19:06,101 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2021-12-27 12:19:06,107 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2021-12-27 12:19:06,116 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2021-12-27 12:19:06,122 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2021-12-27 12:19:06,132 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2021-12-27 12:19:06,138 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2021-12-27 12:19:06,147 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2021-12-27 12:19:06,153 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2021-12-27 12:19:06,162 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2021-12-27 12:19:06,169 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2021-12-27 12:19:06,175 [WARNING] [root] section ReadPermission not found in configfile 2021-12-27 12:19:06,179 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2021-12-27 12:19:06,185 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2021-12-27 12:19:06,190 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2021-12-27 12:19:06,196 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2021-12-27 12:19:06,202 [TRACE] [doorpi.conf.config_object] get_sections returns ['AREA_public', 'Group', 'mykbd_InputPins', 'EVENT_OnSipPhoneMakeCall', 'keyboards', 'AdminNumbers', 'mykbd_keyboard', 'SMTP', 'mykbd_OutputPins', 'SIP-Phone', 'WritePermission', 'DTMF', 'EVENT_OnCallStateDisconnect', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'EVENT_OnCallStateConnect', 'EVENT_OnShutdown', 'DoorPi'] 2021-12-27 12:19:06,209 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2021-12-27 12:19:06,214 [ERROR] [doorpi.status.webserver] no ReadPermission found 2021-12-27 12:19:06,220 [ERROR] [doorpi.status.webserver] module installer doesn't exist but is assigned to group administrator in WritePermission 2021-12-27 12:19:06,225 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2021-12-27 12:19:06,231 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2021-12-27 12:19:06,237 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2021-12-27 12:19:06,245 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,252 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2021-12-27 12:19:06,263 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,258 [DEBUG] [doorpi.action.handler] [ODQQPJ] fire for event OnWebServerStart this actions [] 2021-12-27 12:19:06,273 [TRACE] [doorpi.action.handler] [ODQQPJ] try to fire action WebServerStartupAction with args () and kwargs {} 2021-12-27 12:19:06,276 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,287 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,281 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2021-12-27 12:19:06,302 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,309 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,314 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,320 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,324 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,329 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,335 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,340 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,345 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,350 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,354 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,360 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:06,364 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['mykbd'] 2021-12-27 12:19:06,370 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: mykbd) 2021-12-27 12:19:06,378 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'mykbd' to handler 2021-12-27 12:19:06,382 [TRACE] [doorpi.conf.config_object] get_string for key mykbd in section keyboards (default: dummy) returns piface 2021-12-27 12:19:06,388 [TRACE] [doorpi.conf.config_object] get_keys for section mykbd_InputPins returns ['1', '0', '3', '2'] 2021-12-27 12:19:06,394 [TRACE] [doorpi.conf.config_object] get_keys for section mykbd_OutputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2021-12-27 12:19:06,400 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section mykbd_keyboard (default: 2000) returns 30000.0 2021-12-27 12:19:06,406 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section mykbd_keyboard (default: 0) returns 0 2021-12-27 12:19:06,412 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section mykbd_keyboard (default: True) returns True 2021-12-27 12:19:06,420 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2021-12-27 12:19:06,569 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0', '3', '2'], output_pins = ['1', '0', '3', '2', '5', '4', '7', '6'], polarity = 0) 2021-12-27 12:19:06,988 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2021-12-27 12:19:06,995 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2021-12-27 12:19:07,002 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,007 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,013 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,018 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_mykbd.1 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,023 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_mykbd.1 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,028 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2021-12-27 12:19:07,034 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,039 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,045 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,050 [TRACE] [doorpi.action.handler] register Event OnKeyUp_mykbd.1 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,056 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_mykbd.1 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,061 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2021-12-27 12:19:07,067 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,071 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,077 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,082 [TRACE] [doorpi.action.handler] register Event OnKeyDown_mykbd.1 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,089 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_mykbd.1 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,094 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2021-12-27 12:19:07,100 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,106 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,111 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,116 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_mykbd.0 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,122 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_mykbd.0 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,129 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2021-12-27 12:19:07,135 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,140 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,145 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,150 [TRACE] [doorpi.action.handler] register Event OnKeyUp_mykbd.0 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,155 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_mykbd.0 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,160 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2021-12-27 12:19:07,165 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,171 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,176 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,182 [TRACE] [doorpi.action.handler] register Event OnKeyDown_mykbd.0 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,188 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_mykbd.0 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,193 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2021-12-27 12:19:07,199 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,204 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,209 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_3 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,215 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_mykbd.3 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,219 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_mykbd.3 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,224 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2021-12-27 12:19:07,230 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,235 [TRACE] [doorpi.action.handler] register Event OnKeyUp_3 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,240 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_3 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,246 [TRACE] [doorpi.action.handler] register Event OnKeyUp_mykbd.3 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,250 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_mykbd.3 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,256 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2021-12-27 12:19:07,261 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,267 [TRACE] [doorpi.action.handler] register Event OnKeyDown_3 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,272 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_3 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,278 [TRACE] [doorpi.action.handler] register Event OnKeyDown_mykbd.3 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,283 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_mykbd.3 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,289 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2021-12-27 12:19:07,295 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,299 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,304 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,310 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_mykbd.2 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,315 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_mykbd.2 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,319 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2021-12-27 12:19:07,327 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,332 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,337 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,342 [TRACE] [doorpi.action.handler] register Event OnKeyUp_mykbd.2 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,349 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_mykbd.2 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,354 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2021-12-27 12:19:07,358 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2021-12-27 12:19:07,364 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,369 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,374 [TRACE] [doorpi.action.handler] register Event OnKeyDown_mykbd.2 from doorpi.keyboard.from_piface 2021-12-27 12:19:07,380 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_mykbd.2 and registered source doorpi.keyboard.from_piface 2021-12-27 12:19:07,491 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2021-12-27 12:19:07,500 [TRACE] [doorpi.conf.config_object] get_keys for section mykbd_OutputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2021-12-27 12:19:07,507 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section mykbd_OutputPins (default: ) returns Relais2 2021-12-27 12:19:07,514 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section mykbd_OutputPins (default: ) returns Tueroeffner 2021-12-27 12:19:07,520 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section mykbd_OutputPins (default: ) returns LED4 2021-12-27 12:19:07,525 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section mykbd_OutputPins (default: ) returns LED3 2021-12-27 12:19:07,531 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section mykbd_OutputPins (default: ) returns LED6 2021-12-27 12:19:07,537 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section mykbd_OutputPins (default: ) returns LED5 2021-12-27 12:19:07,542 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section mykbd_OutputPins (default: ) returns LED8 2021-12-27 12:19:07,548 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section mykbd_OutputPins (default: ) returns LED7 2021-12-27 12:19:07,561 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2021-12-27 12:19:07,575 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2021-12-27 12:19:07,588 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2021-12-27 12:19:07,598 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2021-12-27 12:19:07,753 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2021-12-27 12:19:07,766 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2021-12-27 12:19:07,780 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2021-12-27 12:19:07,867 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2021-12-27 12:19:07,895 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2021-12-27 12:19:07,902 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2021-12-27 12:19:07,912 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2021-12-27 12:19:07,925 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2021-12-27 12:19:07,935 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2021-12-27 12:19:07,948 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2021-12-27 12:19:07,957 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2021-12-27 12:19:07,965 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2021-12-27 12:19:07,970 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2021-12-27 12:19:07,976 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2021-12-27 12:19:07,981 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:07,987 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2021-12-27 12:19:07,992 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:07,998 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,002 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,009 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,014 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,020 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,024 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,030 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,035 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,041 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,046 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,051 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,056 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,062 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,067 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,073 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,078 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,084 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,088 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,094 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2021-12-27 12:19:08,099 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2021-12-27 12:19:08,104 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2021-12-27 12:19:08,110 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2021-12-27 12:19:08,115 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,121 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2021-12-27 12:19:08,126 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,131 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,136 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,142 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,147 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,153 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,157 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,163 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,168 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,172 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,177 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,183 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,189 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,194 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,199 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,205 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,210 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,217 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,222 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,228 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,233 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,240 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,244 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,250 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"4444"', '"8"', '"2"', '"000"'] 2021-12-27 12:19:08,255 [TRACE] [doorpi.action.handler] register Event OnDTMF_"4444" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,260 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"4444" and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,264 [TRACE] [doorpi.action.handler] register Event OnDTMF_"8" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,268 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"8" and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,274 [TRACE] [doorpi.action.handler] register Event OnDTMF_"2" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,279 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"2" and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,284 [TRACE] [doorpi.action.handler] register Event OnDTMF_"000" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,290 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"000" and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,295 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,299 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2021-12-27 12:19:08,305 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,311 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,315 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,320 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,325 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,329 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,336 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,341 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,347 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,353 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,359 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,365 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,371 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,376 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,382 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,387 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,392 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,398 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,404 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2021-12-27 12:19:08,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2021-12-27 12:19:08,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb3dbacf8 2021-12-27 12:19:08,433 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2021-12-27 12:19:08,426 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:08,446 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb3dbacf8 2021-12-27 12:19:08,452 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb3d35780, "(null)", "(null)") 2021-12-27 12:19:08,459 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2021-12-27 12:19:08,465 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2021-12-27 12:19:08,471 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2021-12-27 12:19:08,476 [INFO] [doorpi.sipphone.from_linphone] srtp init 2021-12-27 12:19:08,510 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2021-12-27 12:19:08,878 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2021-12-27 12:19:08,929 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1715 - open '/dev/snd/pcmC0D0c' failed (-2) 2021-12-27 12:19:08,942 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 Headphones' added 2021-12-27 12:19:08,997 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2021-12-27 12:19:09,011 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1715 - open '/dev/snd/pcmC2D0c' failed (-2) 2021-12-27 12:19:09,026 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1715 - open '/dev/snd/pcmC2D0p' failed (-19) 2021-12-27 12:19:09,039 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2021-12-27 12:19:09,049 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2021-12-27 12:19:09,058 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2021-12-27 12:19:09,063 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2021-12-27 12:19:09,069 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2021-12-27 12:19:09,075 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2021-12-27 12:19:09,079 [INFO] [doorpi.sipphone.from_linphone] Vtable [0xdd4060] registered on core [0xd99c98] 2021-12-27 12:19:09,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xdd4060, 1, "Starting up") 2021-12-27 12:19:09,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2021-12-27 12:19:09,098 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xdd4060] notifying [global_state_changed] 2021-12-27 12:19:09,103 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2021-12-27 12:19:09,110 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2021-12-27 12:19:09,115 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2021-12-27 12:19:09,119 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2021-12-27 12:19:09,126 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2021-12-27 12:19:09,132 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2021-12-27 12:19:09,137 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2021-12-27 12:19:09,145 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2021-12-27 12:19:09,150 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2021-12-27 12:19:09,155 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2021-12-27 12:19:09,161 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2021-12-27 12:19:09,167 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2021-12-27 12:19:09,172 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2021-12-27 12:19:09,177 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2021-12-27 12:19:09,184 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2021-12-27 12:19:09,190 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2021-12-27 12:19:09,194 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2021-12-27 12:19:09,200 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2021-12-27 12:19:09,205 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2021-12-27 12:19:09,209 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2021-12-27 12:19:09,215 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2021-12-27 12:19:09,219 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2021-12-27 12:19:09,225 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2021-12-27 12:19:09,231 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2021-12-27 12:19:09,236 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2021-12-27 12:19:09,242 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2021-12-27 12:19:09,249 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2021-12-27 12:19:09,254 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2021-12-27 12:19:09,260 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2021-12-27 12:19:09,265 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2021-12-27 12:19:09,272 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2021-12-27 12:19:09,278 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2021-12-27 12:19:09,285 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2021-12-27 12:19:09,290 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2021-12-27 12:19:09,296 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2021-12-27 12:19:09,301 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2021-12-27 12:19:09,308 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2021-12-27 12:19:09,314 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2021-12-27 12:19:09,320 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2021-12-27 12:19:09,326 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2021-12-27 12:19:09,332 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2021-12-27 12:19:09,339 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2021-12-27 12:19:09,344 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2021-12-27 12:19:09,350 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2021-12-27 12:19:09,356 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2021-12-27 12:19:09,363 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2021-12-27 12:19:09,369 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2021-12-27 12:19:09,374 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2021-12-27 12:19:09,380 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2021-12-27 12:19:09,386 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2021-12-27 12:19:09,392 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2021-12-27 12:19:09,398 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2021-12-27 12:19:09,407 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2021-12-27 12:19:09,412 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2021-12-27 12:19:09,419 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2021-12-27 12:19:09,425 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2021-12-27 12:19:09,431 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2021-12-27 12:19:09,435 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2021-12-27 12:19:09,442 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2021-12-27 12:19:09,447 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2021-12-27 12:19:09,453 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2021-12-27 12:19:09,460 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2021-12-27 12:19:09,465 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2021-12-27 12:19:09,470 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2021-12-27 12:19:09,476 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2021-12-27 12:19:09,482 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2021-12-27 12:19:09,488 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2021-12-27 12:19:09,494 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2021-12-27 12:19:09,500 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2021-12-27 12:19:09,505 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2021-12-27 12:19:09,511 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2021-12-27 12:19:09,516 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2021-12-27 12:19:09,522 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2021-12-27 12:19:09,528 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2021-12-27 12:19:09,534 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2021-12-27 12:19:09,539 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2021-12-27 12:19:09,546 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2021-12-27 12:19:09,553 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2021-12-27 12:19:09,559 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2021-12-27 12:19:09,565 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2021-12-27 12:19:09,571 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2021-12-27 12:19:09,577 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2021-12-27 12:19:09,582 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2021-12-27 12:19:09,588 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2021-12-27 12:19:09,595 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:09,602 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:09,608 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:09,614 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0xdd4060, 2, "(null)") 2021-12-27 12:19:09,620 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2021-12-27 12:19:09,626 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xdd4060] notifying [configuring_status] 2021-12-27 12:19:09,631 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2021-12-27 12:19:09,637 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2021-12-27 12:19:09,644 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2021-12-27 12:19:09,650 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2021-12-27 12:19:09,655 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2021-12-27 12:19:09,661 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2021-12-27 12:19:09,667 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2021-12-27 12:19:09,672 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2021-12-27 12:19:09,679 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2021-12-27 12:19:09,685 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2021-12-27 12:19:09,689 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,696 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,702 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,707 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,714 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2021-12-27 12:19:09,720 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2021-12-27 12:19:09,726 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2021-12-27 12:19:09,733 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,738 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,745 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2021-12-27 12:19:09,751 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2021-12-27 12:19:09,756 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:09,763 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2021-12-27 12:19:09,769 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xd98c90] on [sip:0.0.0.0:5060;transport=UDP] 2021-12-27 12:19:09,775 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xdd80f8] on [sip:0.0.0.0:5060;transport=TCP] 2021-12-27 12:19:09,782 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xdd9c88] on [sip:0.0.0.0:-1;transport=TLS] 2021-12-27 12:19:09,789 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 33007 2021-12-27 12:19:09,795 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:09,802 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:09,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xdd4060, 2, "Ready") 2021-12-27 12:19:09,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2021-12-27 12:19:09,830 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xdd4060] notifying [global_state_changed] 2021-12-27 12:19:09,834 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb3dbacf8 2021-12-27 12:19:09,840 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns Haustuer 2021-12-27 12:19:09,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb3dbacf8 [0xdd4060], "Haustuer ") 2021-12-27 12:19:09,855 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2021-12-27 12:19:09,862 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2021-12-27 12:19:09,871 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2021-12-27 12:19:09,881 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb3dbacf8 [0xdd4060], 2) 2021-12-27 12:19:09,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2021-12-27 12:19:09,876 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2021-12-27 12:19:09,900 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2021-12-27 12:19:09,905 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb3dbacf8 [0xdd4060], 0x2a3420 [0]) 2021-12-27 12:19:09,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2021-12-27 12:19:09,919 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2021-12-27 12:19:09,925 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2021-12-27 12:19:09,930 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2021-12-27 12:19:09,936 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb3dbad10 2021-12-27 12:19:09,942 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb3dbacf8 [0xdd4060], 0xb3dbad10 [0xb3dbad18]) 2021-12-27 12:19:09,948 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xd98c90] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2021-12-27 12:19:09,955 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xdd80f8] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2021-12-27 12:19:09,961 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xdd9c88] on [sip:0.0.0.0:33007;transport=TLS] destroyed 2021-12-27 12:19:09,968 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2021-12-27 12:19:09,974 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xd98c90] on [sip:0.0.0.0:5060;transport=UDP] 2021-12-27 12:19:09,980 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xd98cd0] on [sip:0.0.0.0:5060;transport=TCP] 2021-12-27 12:19:09,987 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xdd54c0] on [sip:0.0.0.0:-1;transport=TLS] 2021-12-27 12:19:09,994 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 41393 2021-12-27 12:19:10,001 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:10,005 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2021-12-27 12:19:10,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2021-12-27 12:19:10,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb3dbad10) 2021-12-27 12:19:10,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2021-12-27 12:19:10,029 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2021-12-27 12:19:10,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb3dbacf8 [0xdd4060], 0x2a3420 [0]) 2021-12-27 12:19:10,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2021-12-27 12:19:10,047 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2021-12-27 12:19:10,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb3dbacf8 [0xdd4060], "") 2021-12-27 12:19:10,060 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2021-12-27 12:19:10,066 [INFO] [doorpi.sipphone.from_linphone] 192.168.62.1 2021-12-27 12:19:10,075 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:10,081 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2021-12-27 12:19:10,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2021-12-27 12:19:10,095 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2021-12-27 12:19:10,101 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2021-12-27 12:19:10,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb3dbacf8 [0xdd4060], 0) 2021-12-27 12:19:10,112 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2021-12-27 12:19:10,118 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2021-12-27 12:19:10,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2021-12-27 12:19:10,135 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2021-12-27 12:19:10,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb3dbacf8 [0xdd4060], 120) 2021-12-27 12:19:10,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2021-12-27 12:19:10,152 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 29 2021-12-27 12:19:10,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb3dbacf8 [0xdd4060], 29) 2021-12-27 12:19:10,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2021-12-27 12:19:10,169 [WARNING] [doorpi.conf.config_object] could not delete section DoorPi, because it's not empty. 2021-12-27 12:19:10,175 [WARNING] [doorpi.conf.config_object] found SIP-Phone - dialtone in BACKWARD_COMPATIBILITY_KEYS with DoorPi - dialtone 2021-12-27 12:19:10,179 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2021-12-27 12:19:10,189 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2021-12-27 12:19:10,195 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2021-12-27 12:19:10,207 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2021-12-27 12:19:10,213 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:10,220 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2021-12-27 12:19:10,225 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:10,231 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:10,236 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:10,242 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:10,249 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2021-12-27 12:19:10,253 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2021-12-27 12:19:10,260 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2021-12-27 12:19:10,267 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2021-12-27 12:19:10,272 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2021-12-27 12:19:10,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb3dbacf8 [0xdd4060], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2021-12-27 12:19:10,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2021-12-27 12:19:10,297 [WARNING] [doorpi.conf.config_object] could not delete section DoorPi, because it's not empty. 2021-12-27 12:19:10,285 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:10,308 [WARNING] [doorpi.conf.config_object] found SIP-Phone - records in BACKWARD_COMPATIBILITY_KEYS with DoorPi - records 2021-12-27 12:19:10,312 [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/Key-!LastKey!_%Y-%m-%d_%H-%M-%S.wav 2021-12-27 12:19:10,317 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2021-12-27 12:19:10,323 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,330 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2021-12-27 12:19:10,335 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,340 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,345 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,351 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,356 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,361 [WARNING] [doorpi.conf.config_object] could not delete section DoorPi, because it's not empty. 2021-12-27 12:19:10,368 [WARNING] [doorpi.conf.config_object] found SIP-Phone - record_while_dialing in BACKWARD_COMPATIBILITY_KEYS with DoorPi - record_while_dialing 2021-12-27 12:19:10,372 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2021-12-27 12:19:10,378 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2021-12-27 12:19:10,384 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2021-12-27 12:19:10,390 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2021-12-27 12:19:10,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb3d36ee0 2021-12-27 12:19:10,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,404 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:10,429 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb3de3ce0 2021-12-27 12:19:10,435 [DEBUG] [doorpi.conf.config_object] create new key capture_device in section SIP-Phone with value 'ALSA: default device' 2021-12-27 12:19:10,442 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2021-12-27 12:19:10,450 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb3dbacf8 [0xdd4060], "ALSA: default device") 2021-12-27 12:19:10,457 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2021-12-27 12:19:10,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,470 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb3de3d40 2021-12-27 12:19:10,475 [DEBUG] [doorpi.conf.config_object] create new key playback_device in section SIP-Phone with value 'ALSA: default device' 2021-12-27 12:19:10,480 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2021-12-27 12:19:10,485 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb3dbacf8 [0xdd4060], "ALSA: default device") 2021-12-27 12:19:10,491 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2021-12-27 12:19:10,496 [TRACE] [doorpi.conf.config_object] get_integer for key mic_gain_db in section SIP-Phone (default: 0) returns 0.0 2021-12-27 12:19:10,503 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_mic_gain_db(0xb3dbacf8 [0xdd4060], 0.000000) 2021-12-27 12:19:10,509 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2021-12-27 12:19:10,515 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_mic_gain_db -> 0 2021-12-27 12:19:10,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb3d36cd8 2021-12-27 12:19:10,533 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2021-12-27 12:19:10,541 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2021-12-27 12:19:10,548 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2021-12-27 12:19:10,552 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,557 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb3d36cd8 2021-12-27 12:19:10,563 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb3dbacf8 [0xdd4060], "ALSA: default device") 2021-12-27 12:19:10,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3414 2021-12-27 12:19:10,575 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb3dbacf8 [0xdd4060], "ALSA: default device") 2021-12-27 12:19:10,580 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3414 2021-12-27 12:19:10,586 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2021-12-27 12:19:10,593 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb3dbacf8 [0xdd4060], "ALSA: bcm2835 Headphones") 2021-12-27 12:19:10,598 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3420 2021-12-27 12:19:10,603 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb3dbacf8 [0xdd4060], "ALSA: bcm2835 Headphones") 2021-12-27 12:19:10,610 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3414 2021-12-27 12:19:10,615 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 Headphones 2021-12-27 12:19:10,621 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb3dbacf8 [0xdd4060], "ALSA: C-Media USB Headphone Set") 2021-12-27 12:19:10,626 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3414 2021-12-27 12:19:10,632 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb3dbacf8 [0xdd4060], "ALSA: C-Media USB Headphone Set") 2021-12-27 12:19:10,637 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3414 2021-12-27 12:19:10,643 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2021-12-27 12:19:10,649 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2021-12-27 12:19:10,654 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,660 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb3de3e60 2021-12-27 12:19:10,665 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2021-12-27 12:19:10,670 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,676 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb3de3e90 2021-12-27 12:19:10,682 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2021-12-27 12:19:10,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_mic_gain_db(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,695 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_mic_gain_db -> 0xd39130 2021-12-27 12:19:10,700 [DEBUG] [doorpi.sipphone.from_linphone] mic_gain_db: 0.0 2021-12-27 12:19:10,707 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2021-12-27 12:19:10,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:10,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xdd38b8) 2021-12-27 12:19:10,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94bb0 2021-12-27 12:19:10,738 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94bb0 2021-12-27 12:19:10,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94bb0 2021-12-27 12:19:10,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xd99470) 2021-12-27 12:19:10,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94bc0 2021-12-27 12:19:10,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94bc0 2021-12-27 12:19:10,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94bc0 2021-12-27 12:19:10,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xd994d8) 2021-12-27 12:19:10,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94bd0 2021-12-27 12:19:10,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94bd0 2021-12-27 12:19:10,820 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94bd0 2021-12-27 12:19:10,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xdd3df0) 2021-12-27 12:19:10,831 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,837 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94be0 2021-12-27 12:19:10,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,849 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94be0 2021-12-27 12:19:10,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94be0 2021-12-27 12:19:10,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xdd3e48) 2021-12-27 12:19:10,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,892 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94bf0 2021-12-27 12:19:10,898 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,904 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94bf0 2021-12-27 12:19:10,911 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94bf0 2021-12-27 12:19:10,917 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xdd3ea0) 2021-12-27 12:19:10,924 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,930 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94c00 2021-12-27 12:19:10,937 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94c00 2021-12-27 12:19:10,950 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94c00 2021-12-27 12:19:10,955 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xd99340) 2021-12-27 12:19:10,961 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:10,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94c10 2021-12-27 12:19:10,974 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:10,980 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94c10 2021-12-27 12:19:10,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94c10 2021-12-27 12:19:10,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xd99398) 2021-12-27 12:19:10,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:11,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94c20 2021-12-27 12:19:11,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:11,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94c20 2021-12-27 12:19:11,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94c20 2021-12-27 12:19:11,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xd99400) 2021-12-27 12:19:11,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:11,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94c30 2021-12-27 12:19:11,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:11,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94c30 2021-12-27 12:19:11,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94c30 2021-12-27 12:19:11,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xdd3f28) 2021-12-27 12:19:11,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:11,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94c40 2021-12-27 12:19:11,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:11,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94c40 2021-12-27 12:19:11,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94c40 2021-12-27 12:19:11,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb3d36e90 2021-12-27 12:19:11,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bb0 [0xdd38b8]) 2021-12-27 12:19:11,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb602fc20 2021-12-27 12:19:11,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bb0 [0xdd38b8]) 2021-12-27 12:19:11,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,126 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2021-12-27 12:19:11,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94bb0 [0xdd38b8], 0x2a3420 [0]) 2021-12-27 12:19:11,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,144 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bc0 [0xd99470]) 2021-12-27 12:19:11,150 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1fa0 2021-12-27 12:19:11,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bc0 [0xd99470]) 2021-12-27 12:19:11,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,168 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2021-12-27 12:19:11,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94bc0 [0xd99470], 0x2a3420 [0]) 2021-12-27 12:19:11,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bd0 [0xd994d8]) 2021-12-27 12:19:11,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1fa0 2021-12-27 12:19:11,200 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bd0 [0xd994d8]) 2021-12-27 12:19:11,206 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,212 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2021-12-27 12:19:11,218 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94bd0 [0xd994d8], 0x2a3420 [0]) 2021-12-27 12:19:11,224 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,232 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94be0 [0xdd3df0]) 2021-12-27 12:19:11,236 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1fa0 2021-12-27 12:19:11,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94be0 [0xdd3df0]) 2021-12-27 12:19:11,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,256 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2021-12-27 12:19:11,262 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94be0 [0xdd3df0], 0x2a3414 [1]) 2021-12-27 12:19:11,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bf0 [0xdd3e48]) 2021-12-27 12:19:11,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1fa0 2021-12-27 12:19:11,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94bf0 [0xdd3e48]) 2021-12-27 12:19:11,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,296 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2021-12-27 12:19:11,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94bf0 [0xdd3e48], 0x2a3414 [1]) 2021-12-27 12:19:11,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,315 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c00 [0xdd3ea0]) 2021-12-27 12:19:11,321 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,327 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c00 [0xdd3ea0]) 2021-12-27 12:19:11,332 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,339 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2021-12-27 12:19:11,345 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94c00 [0xdd3ea0], 0x2a3420 [0]) 2021-12-27 12:19:11,352 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c10 [0xd99340]) 2021-12-27 12:19:11,365 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1fa0 2021-12-27 12:19:11,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c10 [0xd99340]) 2021-12-27 12:19:11,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,384 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2021-12-27 12:19:11,390 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94c10 [0xd99340], 0x2a3420 [0]) 2021-12-27 12:19:11,396 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,402 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c20 [0xd99398]) 2021-12-27 12:19:11,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1fa0 2021-12-27 12:19:11,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c20 [0xd99398]) 2021-12-27 12:19:11,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3de1f80 2021-12-27 12:19:11,427 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2021-12-27 12:19:11,433 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94c20 [0xd99398], 0x2a3420 [0]) 2021-12-27 12:19:11,439 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,446 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c30 [0xd99400]) 2021-12-27 12:19:11,452 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,457 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c30 [0xd99400]) 2021-12-27 12:19:11,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,468 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2021-12-27 12:19:11,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94c30 [0xd99400], 0x2a3420 [0]) 2021-12-27 12:19:11,482 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,491 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c40 [0xdd3f28]) 2021-12-27 12:19:11,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,502 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c40 [0xdd3f28]) 2021-12-27 12:19:11,509 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,514 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2021-12-27 12:19:11,520 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94c40 [0xdd3f28], 0x2a3420 [0]) 2021-12-27 12:19:11,526 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,532 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,536 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,542 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,548 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,554 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2021-12-27 12:19:11,560 [DEBUG] [doorpi.sipphone.from_linphone] | name 2021-12-27 12:19:11,564 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2021-12-27 12:19:11,570 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,576 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,582 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2021-12-27 12:19:11,588 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2021-12-27 12:19:11,595 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,601 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,607 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns 2021-12-27 12:19:11,612 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,619 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,625 [WARNING] [doorpi.sipphone.from_linphone] camera "" from config does not exist in possible video devices. 2021-12-27 12:19:11,630 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,636 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,642 [DEBUG] [doorpi.sipphone.from_linphone] switching to first possible video device "StaticImage: Static picture" 2021-12-27 12:19:11,648 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,654 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb3d36ee0 2021-12-27 12:19:11,660 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb3dbacf8 [0xdd4060], 0x2a3414 [1]) 2021-12-27 12:19:11,665 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2021-12-27 12:19:11,671 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb3dbacf8 [0xdd4060], "StaticImage: Static picture") 2021-12-27 12:19:11,677 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2021-12-27 12:19:11,684 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns 2021-12-27 12:19:11,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb3dbacf8 [0xdd4060], "") 2021-12-27 12:19:11,695 [WARNING] [doorpi.sipphone.from_linphone] Video resolution is not supported in linphone. 2021-12-27 12:19:11,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2021-12-27 12:19:11,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb3d3b020 2021-12-27 12:19:11,719 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2021-12-27 12:19:11,725 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2021-12-27 12:19:11,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xdd3f80) 2021-12-27 12:19:11,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2021-12-27 12:19:11,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c94c50 2021-12-27 12:19:11,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2021-12-27 12:19:11,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c94c50 2021-12-27 12:19:11,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c94c50 2021-12-27 12:19:11,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb3d36d50 2021-12-27 12:19:11,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c50 [0xdd3f80]) 2021-12-27 12:19:11,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2a3414 2021-12-27 12:19:11,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c94c50 [0xdd3f80]) 2021-12-27 12:19:11,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb3dbad10 2021-12-27 12:19:11,813 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2021-12-27 12:19:11,820 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb3dbacf8 [0xdd4060], 0xb6c94c50 [0xdd3f80], 0x2a3414 [1]) 2021-12-27 12:19:11,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xac57ac 2021-12-27 12:19:11,832 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns asterisk.*** 2021-12-27 12:19:11,838 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 30 2021-12-27 12:19:11,844 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 30) returns ******* 2021-12-27 12:19:11,850 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: asterisk.***) returns 2021-12-27 12:19:11,856 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2021-12-27 12:19:11,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:11,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0xddaac0) 2021-12-27 12:19:11,873 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2021-12-27 12:19:11,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6c94c60 2021-12-27 12:19:11,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2021-12-27 12:19:11,889 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6c94c60 2021-12-27 12:19:11,895 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6c94c60 2021-12-27 12:19:11,901 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6c94c60 2021-12-27 12:19:11,907 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns Haustuer 2021-12-27 12:19:11,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("Haustuer ") 2021-12-27 12:19:11,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xdd80f8) 2021-12-27 12:19:11,932 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2021-12-27 12:19:11,938 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6c94c70 2021-12-27 12:19:11,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2021-12-27 12:19:11,950 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6c94c70 2021-12-27 12:19:11,955 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6c94c70 2021-12-27 12:19:11,962 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6c94c70 2021-12-27 12:19:11,969 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6c94c60 [0xddaac0], 0xb6c94c70 [0xdd80f8]) 2021-12-27 12:19:11,975 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2021-12-27 12:19:11,981 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6c94c60 [0xddaac0], "sip:asterisk.***") 2021-12-27 12:19:11,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2021-12-27 12:19:11,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6c94c60 [0xddaac0], 0x2a3414 [1]) 2021-12-27 12:19:12,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2021-12-27 12:19:12,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb3dbacf8 [0xdd4060], 0xb6c94c60 [0xddaac0]) 2021-12-27 12:19:12,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0xac57ac 2021-12-27 12:19:12,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb3dbacf8 [0xdd4060], 0xb6c94c60 [0xddaac0]) 2021-12-27 12:19:12,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2021-12-27 12:19:12,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb3dbacf8 [0xdd4060], "30", "(null)", "***", "(null)", "(null)", "") 2021-12-27 12:19:12,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0xda7d08) 2021-12-27 12:19:12,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2021-12-27 12:19:12,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6c94c80 2021-12-27 12:19:12,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2021-12-27 12:19:12,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6c94c80 2021-12-27 12:19:12,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6c94c80 2021-12-27 12:19:12,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6c94c80 2021-12-27 12:19:12,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb3dbacf8 [0xdd4060], 0xb6c94c80 [0xda7d08]) 2021-12-27 12:19:12,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2021-12-27 12:19:12,103 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2021-12-27 12:19:12,107 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnSipPhoneMakeCall', 'EVENT_OnCallStateDisconnect', 'EVENT_OnStartup', 'EVENT_OnCallStateConnect', 'EVENT_OnShutdown'] 2021-12-27 12:19:12,114 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnSipPhoneMakeCall' in configfile 2021-12-27 12:19:12,121 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnSipPhoneMakeCall returns [] 2021-12-27 12:19:12,126 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateDisconnect' in configfile 2021-12-27 12:19:12,131 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateDisconnect returns ['10'] 2021-12-27 12:19:12,137 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateDisconnect' 2021-12-27 12:19:12,143 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateDisconnect (default: ) returns out:LED8,0 2021-12-27 12:19:12,150 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2021-12-27 12:19:12,159 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2021-12-27 12:19:12,167 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'LED8'} was added to event OnCallStateDisconnect 2021-12-27 12:19:12,173 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2021-12-27 12:19:12,180 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10', '20'] 2021-12-27 12:19:12,185 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2021-12-27 12:19:12,191 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2021-12-27 12:19:12,197 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2021-12-27 12:19:12,204 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2021-12-27 12:19:12,211 [INFO] [doorpi.doorpi] registering action '20' for event 'OnStartup' 2021-12-27 12:19:12,218 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnStartup (default: ) returns mailto:herm@***,DoorPi:EVENT_OnStartup,DoorPi up 2021-12-27 12:19:12,224 [DEBUG] [doorpi.action.SingleActions.mailto] doorpi.action.SingleActions.mailto loaded 2021-12-27 12:19:12,271 [DEBUG] [doorpi.action.SingleActions.take_snapshot] doorpi.action.SingleActions.take_snapshot loaded 2021-12-27 12:19:12,279 [TRACE] [doorpi.action.handler] action MailtoAction with args () and kwargs {'smtp_text': 'DoorPi up', 'smtp_subject': 'DoorPi:EVENT_OnStartup', 'smtp_snapshot': False, 'smtp_to': 'herm@***'} was added to event OnStartup 2021-12-27 12:19:12,286 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateConnect' in configfile 2021-12-27 12:19:12,292 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateConnect returns ['10'] 2021-12-27 12:19:12,298 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateConnect' 2021-12-27 12:19:12,304 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateConnect (default: ) returns out:LED8,1 2021-12-27 12:19:12,310 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'LED8'} was added to event OnCallStateConnect 2021-12-27 12:19:12,317 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnShutdown' in configfile 2021-12-27 12:19:12,322 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnShutdown returns ['10', '30', '20'] 2021-12-27 12:19:12,328 [INFO] [doorpi.doorpi] registering action '10' for event 'OnShutdown' 2021-12-27 12:19:12,334 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnShutdown (default: ) returns sleep:1 2021-12-27 12:19:12,340 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnShutdown 2021-12-27 12:19:12,345 [INFO] [doorpi.doorpi] registering action '20' for event 'OnShutdown' 2021-12-27 12:19:12,351 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnShutdown (default: ) returns mailto:herm@***,DoorPi:EVENT_OnShutdown,DoorPi down 2021-12-27 12:19:12,357 [TRACE] [doorpi.action.handler] action MailtoAction with args () and kwargs {'smtp_text': 'DoorPi down', 'smtp_subject': 'DoorPi:EVENT_OnShutdown', 'smtp_snapshot': False, 'smtp_to': 'herm@***'} was added to event OnShutdown 2021-12-27 12:19:12,362 [INFO] [doorpi.doorpi] registering action '30' for event 'OnShutdown' 2021-12-27 12:19:12,368 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnShutdown (default: ) returns sleep:3 2021-12-27 12:19:12,374 [TRACE] [doorpi.action.handler] action SleepAction with args (3.0,) and kwargs {} was added to event OnShutdown 2021-12-27 12:19:12,380 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section mykbd_InputPins (default: ) returns out:Tueroeffner,1,0,2 2021-12-27 12:19:12,387 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 2.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnKeyPressed_mykbd.1 2021-12-27 12:19:12,394 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section mykbd_InputPins (default: ) returns call:311 2021-12-27 12:19:12,401 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2021-12-27 12:19:12,408 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '311'} was added to new evententry OnKeyPressed_mykbd.0 2021-12-27 12:19:12,415 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section mykbd_InputPins (default: ) returns os_execute:sudo poweroff 2021-12-27 12:19:12,423 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2021-12-27 12:19:12,435 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo poweroff'} was added to new evententry OnKeyPressed_mykbd.3 2021-12-27 12:19:12,442 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section mykbd_InputPins (default: ) returns os_execute:sudo reboot 2021-12-27 12:19:12,454 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo reboot'} was added to new evententry OnKeyPressed_mykbd.2 2021-12-27 12:19:12,460 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"4444"', '"8"', '"2"', '"000"'] 2021-12-27 12:19:12,466 [TRACE] [doorpi.conf.config_object] get_string for key "000" in section DTMF (default: ) returns os_execute:sudo poweroff 2021-12-27 12:19:12,478 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo poweroff'} was added to new evententry OnDTMF_"000" 2021-12-27 12:19:12,485 [TRACE] [doorpi.conf.config_object] get_string for key "2" in section DTMF (default: ) returns out:Tueroeffner,1,0,3 2021-12-27 12:19:12,491 [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_"2" 2021-12-27 12:19:12,498 [TRACE] [doorpi.conf.config_object] get_string for key "4444" in section DTMF (default: ) returns os_execute:sudo reboot 2021-12-27 12:19:12,510 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo reboot'} was added to new evententry OnDTMF_"4444" 2021-12-27 12:19:12,517 [TRACE] [doorpi.conf.config_object] get_string for key "8" in section DTMF (default: ) returns out:Relais2,1,0,3 2021-12-27 12:19:12,525 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Relais2', 'start_value': '1'} was added to new evententry OnDTMF_"8" 2021-12-27 12:19:12,532 [DEBUG] [doorpi.conf.config_object] create new key is_alive_led in section DoorPi with value '' 2021-12-27 12:19:12,538 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2021-12-27 12:19:12,545 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2021-12-27 12:19:12,555 [DEBUG] [doorpi.action.handler] [I8K9SV] fire for event OnStartup this actions [, ] 2021-12-27 12:19:12,565 [TRACE] [doorpi.action.handler] [I8K9SV] try to fire action SleepAction with args (1.0,) and kwargs {} 2021-12-27 12:19:12,560 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2021-12-27 12:19:12,578 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2021-12-27 12:19:13,587 [TRACE] [doorpi.action.handler] [I8K9SV] try to fire action MailtoAction with args () and kwargs {'smtp_text': 'DoorPi up', 'smtp_subject': 'DoorPi:EVENT_OnStartup', 'smtp_snapshot': False, 'smtp_to': 'herm@***'} 2021-12-27 12:19:13,593 [TRACE] [doorpi.action.base] run MailtoAction with args () and kwargs {'smtp_text': 'DoorPi up', 'smtp_subject': 'DoorPi:EVENT_OnStartup', 'smtp_snapshot': False, 'smtp_to': 'herm@***'} 2021-12-27 12:19:13,600 [TRACE] [doorpi.conf.config_object] get_string for key server in section SMTP (default: smtp.gmail.com) returns smtp.web.de 2021-12-27 12:19:13,606 [TRACE] [doorpi.conf.config_object] get_integer for key port in section SMTP (default: 465) returns 587 2021-12-27 12:19:13,612 [TRACE] [doorpi.conf.config_object] get_string for key username in section SMTP (default: ) returns ***@web.de 2021-12-27 12:19:13,618 [TRACE] [doorpi.conf.config_object] get_string for key password in section SMTP (default: ) returns ******* 2021-12-27 12:19:13,624 [TRACE] [doorpi.conf.config_object] get_string for key from in section SMTP (default: ) returns ***@web.de 2021-12-27 12:19:13,630 [TRACE] [doorpi.conf.config_object] get_boolean for key use_tls in section SMTP (default: False) returns True 2021-12-27 12:19:13,635 [TRACE] [doorpi.conf.config_object] get_boolean for key use_ssl in section SMTP (default: True) returns False 2021-12-27 12:19:13,641 [TRACE] [doorpi.conf.config_object] get_boolean for key need_login in section SMTP (default: True) returns True 2021-12-27 12:19:13,648 [DEBUG] [doorpi.conf.config_object] create new key signature in section SMTP with value '!EPILOG!' 2021-12-27 12:19:13,653 [TRACE] [doorpi.conf.config_object] get_string for key signature in section SMTP (default: !EPILOG!) returns !EPILOG! 2021-12-27 12:19:13,665 [DEBUG] [doorpi.conf.config_object] parse string "!EPILOG!" to " ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me " 2021-12-27 12:19:14,032 [ERROR] [doorpi.action.SingleActions.mailto] send not attachment for this mail: coercing to Unicode: need string or buffer, bool found Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/DoorPi-2.5.1-py2.7.egg/doorpi/action/SingleActions/mailto.py", line 62, in fire_action_mail with open(smtp_snapshot, "rb") as snapshot_file: TypeError: coercing to Unicode: need string or buffer, bool found 2021-12-27 12:19:14,558 [TRACE] [doorpi.action.handler] [I8K9SV] finished fire_event for event_name OnStartup 2021-12-27 12:19:14,566 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2021-12-27 12:19:14,574 [INFO] [doorpi.doorpi] DoorPi started successfully 2021-12-27 12:19:14,584 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2021-12-27 12:19:14,591 [INFO] [doorpi.doorpi] Weburl is http://krebs.***/ 2021-12-27 12:19:14,580 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2021-12-27 12:19:14,605 [INFO] [doorpi.sipphone.from_linphone] resolved to 79.216.72.194 2021-12-27 12:19:14,611 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2021-12-27 12:19:14,619 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.62.230 2021-12-27 12:19:14,626 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2021-12-27 12:19:14,633 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2021-12-27 12:19:14,640 [INFO] [doorpi.sipphone.from_linphone] 192.168.62.1 2021-12-27 12:19:14,648 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:14,655 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2021-12-27 12:19:14,666 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0xddaac0] about to register (LinphoneCore version: 3.9.0) 2021-12-27 12:19:14,707 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2021-12-27 12:19:14,713 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: starting resolution of asterisk.*** 2021-12-27 12:19:14,720 [INFO] [doorpi.sipphone.from_linphone] channel 0xdf7010: state RES_IN_PROGRESS 2021-12-27 12:19:14,726 [INFO] [doorpi.sipphone.from_linphone] transaction [0xde1a28] channel state changed to [RES_IN_PROGRESS] 2021-12-27 12:19:14,734 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2021-12-27 12:19:14,740 [INFO] [doorpi.sipphone.from_linphone] 192.168.62.1 2021-12-27 12:19:14,749 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:14,755 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2021-12-27 12:19:14,762 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2021-12-27 12:19:14,768 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xdd9130] takes ownership of transaction [0xde1a28] 2021-12-27 12:19:14,775 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xddaac0] for identity ["Haustuer" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2021-12-27 12:19:14,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xdd4060, 0xddaac0, 1, "Registration in progress") 2021-12-27 12:19:14,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2021-12-27 12:19:14,795 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xdd4060] notifying [registration_state_changed] 2021-12-27 12:19:14,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0xdd4060, 0x1) 2021-12-27 12:19:14,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2021-12-27 12:19:14,864 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xdd4060] notifying [network_reachable] 2021-12-27 12:19:14,870 [INFO] [doorpi.sipphone.from_linphone] resolved to 79.216.72.194 2021-12-27 12:19:14,875 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2021-12-27 12:19:14,882 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:14,941 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:15,000 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:15,058 [INFO] [doorpi.sipphone.from_linphone] No SRV result for [_sip._udp.asterisk.***], trying A/AAAA. 2021-12-27 12:19:15,066 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2021-12-27 12:19:15,073 [INFO] [doorpi.sipphone.from_linphone] 192.168.62.1 2021-12-27 12:19:15,082 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2021-12-27 12:19:15,089 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2021-12-27 12:19:15,147 [INFO] [doorpi.sipphone.from_linphone] asterisk.*** resolved to 192.168.62.2 2021-12-27 12:19:15,154 [INFO] [doorpi.sipphone.from_linphone] channel 0xdf7010: state RES_DONE 2021-12-27 12:19:15,160 [INFO] [doorpi.sipphone.from_linphone] transaction [0xde1a28] channel state changed to [RES_DONE] 2021-12-27 12:19:15,166 [INFO] [doorpi.sipphone.from_linphone] channel 0xdf7010: state CONNECTING 2021-12-27 12:19:15,172 [INFO] [doorpi.sipphone.from_linphone] transaction [0xde1a28] channel state changed to [CONNECTING] 2021-12-27 12:19:15,177 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.62.2:5060] 2021-12-27 12:19:15,182 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2021-12-27 12:19:15,188 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.62.230:5060 2021-12-27 12:19:15,195 [INFO] [doorpi.sipphone.from_linphone] channel 0xdf7010: state READY 2021-12-27 12:19:15,201 [INFO] [doorpi.sipphone.from_linphone] transaction [0xde1a28] channel state changed to [READY] 2021-12-27 12:19:15,206 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xde1a28], from state [INIT] to [TRYING] 2021-12-27 12:19:15,214 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: message sent to [UDP://asterisk.***:5060], size: [536] bytes REGISTER sip:asterisk.*** SIP/2.0 Via: SIP/2.0/UDP 192.168.62.230:5060;branch=z9hG4bK.ykhsaZj3h;rport From: "Haustuer" ;tag=dha5f702A To: "Haustuer" CSeq: 20 REGISTER Call-ID: Dq0Wues3AF Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2021-12-27 12:19:15,277 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: received [496] new bytes from [UDP://asterisk.***:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.62.230:5060;rport=5060;received=192.168.62.230;branch=z9hG4bK.ykhsaZj3h Call-ID: Dq0Wues3AF From: "Haustuer" ;tag=dha5f702A To: "Haustuer" ;tag=z9hG4bK.ykhsaZj3h CSeq: 20 REGISTER WWW-Authenticate: Digest realm="asterisk",nonce="1640603955/1b9ab26b05d29cb4f6a0fa5341683b70",opaque="534b2052415fbaa6",algorithm=md5,qop="auth" Server: FPBX-15.0.17.64(16.17.0) Content-Length: 0 2021-12-27 12:19:15,313 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010] [496] bytes parsed 2021-12-27 12:19:15,320 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: discovered public ip and port are [192.168.62.230:5060] 2021-12-27 12:19:15,327 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2021-12-27 12:19:15,332 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xde1a28], from state [TRYING] to [COMPLETED] 2021-12-27 12:19:15,338 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=30, realm= 2021-12-27 12:19:15,345 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [30] realm [asterisk] 2021-12-27 12:19:15,353 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xda2ff8], from state [INIT] to [TRYING] 2021-12-27 12:19:15,362 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: message sent to [UDP://asterisk.***:5060], size: [807] bytes REGISTER sip:asterisk.*** SIP/2.0 Via: SIP/2.0/UDP 192.168.62.230:5060;branch=z9hG4bK.sFTJ6v4hH;rport From: "Haustuer" ;tag=dha5f702A To: "Haustuer" CSeq: 21 REGISTER Call-ID: Dq0Wues3AF Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="asterisk", nonce="1640603955/1b9ab26b05d29cb4f6a0fa5341683b70", algorithm=md5, opaque="534b2052415fbaa6", username="30", uri="sip:asterisk.***", response="69fc593039a5b3765f54b151b9501ada", cnonce="65bfc7ea", nc=00000001, qop=auth 2021-12-27 12:19:15,424 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: received [444] new bytes from [UDP://asterisk.***:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.62.230:5060;rport=5060;received=192.168.62.230;branch=z9hG4bK.sFTJ6v4hH Call-ID: Dq0Wues3AF From: "Haustuer" ;tag=dha5f702A To: "Haustuer" ;tag=z9hG4bK.sFTJ6v4hH CSeq: 21 REGISTER Date: Mon, 27 Dec 2021 11:19:15 GMT Contact: ;expires=3599 Expires: 3600 Server: FPBX-15.0.17.64(16.17.0) Content-Length: 0 2021-12-27 12:19:15,456 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010] [444] bytes parsed 2021-12-27 12:19:15,463 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2021-12-27 12:19:15,469 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xda2ff8], from state [TRYING] to [COMPLETED] 2021-12-27 12:19:15,476 [INFO] [doorpi.sipphone.from_linphone] No matching contact neither for [Contact: ;+sip.instance=""] nor [Contact: ;+sip.instance=""] 2021-12-27 12:19:15,483 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xdd9130]: has no contact for request [0xdf5aa0]. 2021-12-27 12:19:15,489 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 3240000 ms 2021-12-27 12:19:15,496 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:asterisk.***] 2021-12-27 12:19:15,503 [INFO] [doorpi.sipphone.from_linphone] No matching contact neither for [Contact: ;+sip.instance=""] nor [Contact: ;+sip.instance=""] 2021-12-27 12:19:15,510 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xddaac0] for identity ["Haustuer" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2021-12-27 12:19:15,515 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xdd4060, 0xddaac0, 2, "Registration successful") 2021-12-27 12:19:15,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2021-12-27 12:19:15,527 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xdd4060] notifying [registration_state_changed] 2021-12-27 12:19:20,380 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xde1a28], from state [COMPLETED] to [TERMINATED] 2021-12-27 12:19:20,387 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xde1a28] terminated 2021-12-27 12:19:20,547 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xda2ff8], from state [COMPLETED] to [TERMINATED] 2021-12-27 12:19:20,554 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xda2ff8] terminated 2021-12-27 12:19:35,454 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: received [420] new bytes from [UDP://asterisk.***:5060]: OPTIONS sip:30@192.168.62.230:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.62.2:5060;rport;branch=z9hG4bKPj3dc2fdc1-c9d1-4842-b97b-df689c34ffa2 From: ;tag=3a5173d8-a464-4bc1-ac51-575a39267172 To: Contact: Call-ID: 9cbc86d3-3add-41a6-a751-5ea90dfcc0de CSeq: 8743 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.64(16.17.0) Content-Length: 0 2021-12-27 12:19:35,489 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010] [420] bytes parsed 2021-12-27 12:19:35,496 [INFO] [doorpi.sipphone.from_linphone] channel [0xdf7010]: message sent to [UDP://asterisk.***:5060], size: [289] bytes SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.62.2:5060;rport;branch=z9hG4bKPj3dc2fdc1-c9d1-4842-b97b-df689c34ffa2 From: ;tag=3a5173d8-a464-4bc1-ac51-575a39267172 To: ;tag=TTYCN Call-ID: 9cbc86d3-3add-41a6-a751-5ea90dfcc0de CSeq: 8743 OPTIONS ^C2021-12-27 12:19:36,354 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2021-12-27 12:19:36,367 [DEBUG] [doorpi.doorpi] destroy doorpi 2021-12-27 12:19:36,374 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started -1225736128)>, , ] 2021-12-27 12:19:36,381 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2021-12-27 12:19:36,391 [DEBUG] [doorpi.action.handler] [1MG6R1] fire for event OnShutdown this actions [, , , , , , ] 2021-12-27 12:19:36,400 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action TimeTickDestroyAction with args () and kwargs {} 2021-12-27 12:19:36,407 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2021-12-27 12:19:36,396 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2021-12-27 12:19:36,418 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2021-12-27 12:19:36,423 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,428 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2021-12-27 12:19:36,432 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2021-12-27 12:19:36,439 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,446 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2021-12-27 12:19:36,452 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2021-12-27 12:19:36,457 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,463 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2021-12-27 12:19:36,468 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2021-12-27 12:19:36,474 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,478 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2021-12-27 12:19:36,484 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2021-12-27 12:19:36,490 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,495 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2021-12-27 12:19:36,500 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2021-12-27 12:19:36,505 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,511 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2021-12-27 12:19:36,516 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2021-12-27 12:19:36,520 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,524 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2021-12-27 12:19:36,529 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2021-12-27 12:19:36,535 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,540 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2021-12-27 12:19:36,547 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2021-12-27 12:19:36,552 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,558 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2021-12-27 12:19:36,563 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2021-12-27 12:19:36,568 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,575 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2021-12-27 12:19:36,579 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2021-12-27 12:19:36,583 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,588 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2021-12-27 12:19:36,594 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2021-12-27 12:19:36,599 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,605 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2021-12-27 12:19:36,610 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2021-12-27 12:19:36,615 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,619 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2021-12-27 12:19:36,626 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2021-12-27 12:19:36,630 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,635 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2021-12-27 12:19:36,641 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2021-12-27 12:19:36,646 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,651 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2021-12-27 12:19:36,657 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2021-12-27 12:19:36,662 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,668 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2021-12-27 12:19:36,673 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2021-12-27 12:19:36,678 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,682 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2021-12-27 12:19:36,688 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2021-12-27 12:19:36,695 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,699 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2021-12-27 12:19:36,706 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2021-12-27 12:19:36,711 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,716 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2021-12-27 12:19:36,721 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2021-12-27 12:19:36,725 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,730 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2021-12-27 12:19:36,736 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2021-12-27 12:19:36,740 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,747 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2021-12-27 12:19:36,752 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2021-12-27 12:19:36,758 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,764 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2021-12-27 12:19:36,769 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2021-12-27 12:19:36,774 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,780 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2021-12-27 12:19:36,786 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2021-12-27 12:19:36,792 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,797 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2021-12-27 12:19:36,804 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2021-12-27 12:19:36,808 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,812 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2021-12-27 12:19:36,819 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2021-12-27 12:19:36,825 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,830 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2021-12-27 12:19:36,837 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2021-12-27 12:19:36,841 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,846 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2021-12-27 12:19:36,851 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2021-12-27 12:19:36,857 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,862 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2021-12-27 12:19:36,868 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2021-12-27 12:19:36,872 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,878 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2021-12-27 12:19:36,884 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2021-12-27 12:19:36,889 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,894 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2021-12-27 12:19:36,899 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2021-12-27 12:19:36,905 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,910 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2021-12-27 12:19:36,916 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2021-12-27 12:19:36,920 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,926 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2021-12-27 12:19:36,931 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2021-12-27 12:19:36,937 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,941 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2021-12-27 12:19:36,947 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2021-12-27 12:19:36,952 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,957 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2021-12-27 12:19:36,962 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2021-12-27 12:19:36,968 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,973 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2021-12-27 12:19:36,980 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2021-12-27 12:19:36,984 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:36,989 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2021-12-27 12:19:36,995 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2021-12-27 12:19:37,000 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,005 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2021-12-27 12:19:37,010 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2021-12-27 12:19:37,016 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,022 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2021-12-27 12:19:37,027 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2021-12-27 12:19:37,032 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,038 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2021-12-27 12:19:37,044 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2021-12-27 12:19:37,050 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,054 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2021-12-27 12:19:37,060 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2021-12-27 12:19:37,065 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,070 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2021-12-27 12:19:37,075 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2021-12-27 12:19:37,082 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,087 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2021-12-27 12:19:37,093 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2021-12-27 12:19:37,098 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,104 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2021-12-27 12:19:37,109 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2021-12-27 12:19:37,115 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,120 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2021-12-27 12:19:37,125 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2021-12-27 12:19:37,130 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,136 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2021-12-27 12:19:37,140 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2021-12-27 12:19:37,146 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,151 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2021-12-27 12:19:37,157 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2021-12-27 12:19:37,162 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,168 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2021-12-27 12:19:37,174 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2021-12-27 12:19:37,178 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,184 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2021-12-27 12:19:37,189 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2021-12-27 12:19:37,194 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,199 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2021-12-27 12:19:37,205 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2021-12-27 12:19:37,210 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,216 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2021-12-27 12:19:37,221 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2021-12-27 12:19:37,227 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,232 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2021-12-27 12:19:37,238 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2021-12-27 12:19:37,243 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,249 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2021-12-27 12:19:37,255 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2021-12-27 12:19:37,260 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,264 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2021-12-27 12:19:37,271 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2021-12-27 12:19:37,277 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,282 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2021-12-27 12:19:37,289 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2021-12-27 12:19:37,294 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,300 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2021-12-27 12:19:37,306 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2021-12-27 12:19:37,311 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,315 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2021-12-27 12:19:37,322 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2021-12-27 12:19:37,328 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,332 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2021-12-27 12:19:37,338 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2021-12-27 12:19:37,342 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,348 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2021-12-27 12:19:37,352 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2021-12-27 12:19:37,359 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,364 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2021-12-27 12:19:37,371 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2021-12-27 12:19:37,375 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,381 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2021-12-27 12:19:37,386 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2021-12-27 12:19:37,392 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,397 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2021-12-27 12:19:37,403 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2021-12-27 12:19:37,408 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,414 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2021-12-27 12:19:37,418 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2021-12-27 12:19:37,425 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,430 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2021-12-27 12:19:37,434 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2021-12-27 12:19:37,441 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,446 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2021-12-27 12:19:37,452 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2021-12-27 12:19:37,456 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,461 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2021-12-27 12:19:37,467 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2021-12-27 12:19:37,472 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,477 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2021-12-27 12:19:37,485 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2021-12-27 12:19:37,491 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,497 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2021-12-27 12:19:37,502 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2021-12-27 12:19:37,507 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,513 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2021-12-27 12:19:37,518 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2021-12-27 12:19:37,524 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,529 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2021-12-27 12:19:37,535 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2021-12-27 12:19:37,540 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,545 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2021-12-27 12:19:37,551 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2021-12-27 12:19:37,555 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,560 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2021-12-27 12:19:37,565 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2021-12-27 12:19:37,570 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,575 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2021-12-27 12:19:37,581 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2021-12-27 12:19:37,586 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,592 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2021-12-27 12:19:37,597 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2021-12-27 12:19:37,602 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,607 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2021-12-27 12:19:37,613 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2021-12-27 12:19:37,618 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,624 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2021-12-27 12:19:37,628 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2021-12-27 12:19:37,634 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,638 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2021-12-27 12:19:37,644 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2021-12-27 12:19:37,649 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,654 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2021-12-27 12:19:37,659 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2021-12-27 12:19:37,665 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,670 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2021-12-27 12:19:37,675 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2021-12-27 12:19:37,680 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,686 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2021-12-27 12:19:37,692 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2021-12-27 12:19:37,697 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,702 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2021-12-27 12:19:37,707 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2021-12-27 12:19:37,712 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,717 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2021-12-27 12:19:37,724 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2021-12-27 12:19:37,729 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,734 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2021-12-27 12:19:37,740 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2021-12-27 12:19:37,744 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,748 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2021-12-27 12:19:37,753 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2021-12-27 12:19:37,759 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,764 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2021-12-27 12:19:37,769 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2021-12-27 12:19:37,773 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,779 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2021-12-27 12:19:37,784 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2021-12-27 12:19:37,790 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,795 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2021-12-27 12:19:37,800 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2021-12-27 12:19:37,806 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,811 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2021-12-27 12:19:37,817 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2021-12-27 12:19:37,822 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,828 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2021-12-27 12:19:37,834 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2021-12-27 12:19:37,838 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,844 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2021-12-27 12:19:37,849 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2021-12-27 12:19:37,855 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,860 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2021-12-27 12:19:37,867 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2021-12-27 12:19:37,871 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,877 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2021-12-27 12:19:37,882 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2021-12-27 12:19:37,888 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,894 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2021-12-27 12:19:37,899 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2021-12-27 12:19:37,905 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,909 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2021-12-27 12:19:37,914 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2021-12-27 12:19:37,919 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,925 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2021-12-27 12:19:37,930 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2021-12-27 12:19:37,935 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,940 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2021-12-27 12:19:37,946 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2021-12-27 12:19:37,951 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,956 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2021-12-27 12:19:37,961 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2021-12-27 12:19:37,968 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,973 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2021-12-27 12:19:37,979 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2021-12-27 12:19:37,984 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:37,989 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2021-12-27 12:19:37,995 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2021-12-27 12:19:38,001 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,005 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2021-12-27 12:19:38,011 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2021-12-27 12:19:38,015 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,020 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2021-12-27 12:19:38,025 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2021-12-27 12:19:38,030 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,035 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2021-12-27 12:19:38,042 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2021-12-27 12:19:38,046 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,052 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2021-12-27 12:19:38,058 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2021-12-27 12:19:38,064 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,068 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2021-12-27 12:19:38,074 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2021-12-27 12:19:38,079 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,084 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2021-12-27 12:19:38,090 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2021-12-27 12:19:38,096 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2021-12-27 12:19:38,101 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2021-12-27 12:19:38,105 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2021-12-27 12:19:38,111 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2021-12-27 12:19:38,119 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action WebServerShutdownAction with args () and kwargs {} 2021-12-27 12:19:38,125 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2021-12-27 12:19:38,130 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2021-12-27 12:19:38,140 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2021-12-27 12:19:38,149 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,156 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2021-12-27 12:19:38,148 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2021-12-27 12:19:38,169 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2021-12-27 12:19:38,176 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,180 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2021-12-27 12:19:38,187 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2021-12-27 12:19:38,192 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,198 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2021-12-27 12:19:38,202 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2021-12-27 12:19:38,208 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,213 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2021-12-27 12:19:38,219 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2021-12-27 12:19:38,224 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,229 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2021-12-27 12:19:38,234 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2021-12-27 12:19:38,240 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,244 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2021-12-27 12:19:38,250 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2021-12-27 12:19:38,254 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,259 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2021-12-27 12:19:38,266 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2021-12-27 12:19:38,272 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2021-12-27 12:19:38,277 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2021-12-27 12:19:38,283 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2021-12-27 12:19:38,289 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2021-12-27 12:19:38,344 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2021-12-27 12:19:38,348 [TRACE] [doorpi.action.handler] [ODQQPJ] finished fire_event for event_name OnWebServerStart 2021-12-27 12:19:38,363 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2021-12-27 12:19:38,370 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2021-12-27 12:19:38,375 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2021-12-27 12:19:38,382 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2021-12-27 12:19:38,388 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2021-12-27 12:19:38,393 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2021-12-27 12:19:38,399 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2021-12-27 12:19:38,406 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action KeyboardDestroyAction with args () and kwargs {} 2021-12-27 12:19:38,413 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2021-12-27 12:19:38,419 [DEBUG] [doorpi.keyboard.from_piface] destroy 2021-12-27 12:19:38,522 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_piface and force_unregister is True 2021-12-27 12:19:38,530 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,537 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_2 - remove event too 2021-12-27 12:19:38,543 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_2 2021-12-27 12:19:38,556 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_mykbd.1 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,561 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_mykbd.1 - remove event too 2021-12-27 12:19:38,574 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_mykbd.1 2021-12-27 12:19:38,579 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_mykbd.0 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,585 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_mykbd.0 - remove event too 2021-12-27 12:19:38,596 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_mykbd.0 2021-12-27 12:19:38,601 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_mykbd.3 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,615 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_mykbd.3 - remove event too 2021-12-27 12:19:38,620 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_mykbd.3 2021-12-27 12:19:38,625 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_mykbd.2 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,632 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_mykbd.2 - remove event too 2021-12-27 12:19:38,646 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_mykbd.2 2021-12-27 12:19:38,650 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_piface 2021-12-27 12:19:38,656 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2021-12-27 12:19:38,662 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed 2021-12-27 12:19:38,676 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_piface 2021-12-27 12:19:38,680 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2021-12-27 12:19:38,686 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp 2021-12-27 12:19:38,695 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_mykbd.3 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,700 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_mykbd.3 - remove event too 2021-12-27 12:19:38,709 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_mykbd.3 2021-12-27 12:19:38,715 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_mykbd.3 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,723 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_mykbd.3 - remove event too 2021-12-27 12:19:38,729 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_mykbd.3 2021-12-27 12:19:38,734 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_mykbd.2 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,739 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_mykbd.2 - remove event too 2021-12-27 12:19:38,746 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_mykbd.2 2021-12-27 12:19:38,751 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_3 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,757 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_3 - remove event too 2021-12-27 12:19:38,762 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_3 2021-12-27 12:19:38,769 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_2 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,775 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_2 - remove event too 2021-12-27 12:19:38,780 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_2 2021-12-27 12:19:38,786 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_1 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,791 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_1 - remove event too 2021-12-27 12:19:38,797 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_1 2021-12-27 12:19:38,802 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_0 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,809 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_0 - remove event too 2021-12-27 12:19:38,813 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_0 2021-12-27 12:19:38,819 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_mykbd.2 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,824 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_mykbd.2 - remove event too 2021-12-27 12:19:38,830 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_mykbd.2 2021-12-27 12:19:38,835 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_piface 2021-12-27 12:19:38,841 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2021-12-27 12:19:38,846 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown 2021-12-27 12:19:38,852 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_2 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,857 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_2 - remove event too 2021-12-27 12:19:38,863 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_2 2021-12-27 12:19:38,869 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_3 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,874 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_3 - remove event too 2021-12-27 12:19:38,880 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_3 2021-12-27 12:19:38,884 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_0 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,890 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_0 - remove event too 2021-12-27 12:19:38,895 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_0 2021-12-27 12:19:38,900 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_1 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,904 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_1 - remove event too 2021-12-27 12:19:38,909 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_1 2021-12-27 12:19:38,914 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,920 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_1 - remove event too 2021-12-27 12:19:38,925 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_1 2021-12-27 12:19:38,931 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,936 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_0 - remove event too 2021-12-27 12:19:38,941 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_0 2021-12-27 12:19:38,946 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,952 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_3 - remove event too 2021-12-27 12:19:38,956 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_3 2021-12-27 12:19:38,962 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_mykbd.0 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,967 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_mykbd.0 - remove event too 2021-12-27 12:19:38,973 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_mykbd.0 2021-12-27 12:19:38,979 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_mykbd.1 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,984 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_mykbd.1 - remove event too 2021-12-27 12:19:38,989 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_mykbd.1 2021-12-27 12:19:38,994 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_mykbd.0 from doorpi.keyboard.from_piface 2021-12-27 12:19:38,998 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_mykbd.0 - remove event too 2021-12-27 12:19:39,004 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_mykbd.0 2021-12-27 12:19:39,009 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_mykbd.1 from doorpi.keyboard.from_piface 2021-12-27 12:19:39,014 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_mykbd.1 - remove event too 2021-12-27 12:19:39,021 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_mykbd.1 2021-12-27 12:19:39,025 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed 2021-12-27 12:19:39,032 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action > with args () and kwargs {} 2021-12-27 12:19:39,038 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2021-12-27 12:19:39,044 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2021-12-27 12:19:39,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0xb3dbacf8 [0xdd4060]) 2021-12-27 12:19:39,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0xac57ac 2021-12-27 12:19:39,066 [DEBUG] [doorpi.action.handler] [A0W6YG] fire for event OnSipPhoneDestroy this actions [, , ] 2021-12-27 12:19:39,072 [TRACE] [doorpi.action.handler] [A0W6YG] try to fire action > with args () and kwargs {} 2021-12-27 12:19:39,079 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2021-12-27 12:19:39,085 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2021-12-27 12:19:39,090 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2021-12-27 12:19:39,096 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,101 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2021-12-27 12:19:39,107 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2021-12-27 12:19:39,112 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,117 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2021-12-27 12:19:39,123 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2021-12-27 12:19:39,128 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"8" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,134 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"8" - remove event too 2021-12-27 12:19:39,139 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"8" 2021-12-27 12:19:39,145 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,150 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2021-12-27 12:19:39,156 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2021-12-27 12:19:39,161 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,167 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2021-12-27 12:19:39,172 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2021-12-27 12:19:39,178 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,182 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2021-12-27 12:19:39,188 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2021-12-27 12:19:39,192 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,198 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2021-12-27 12:19:39,203 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2021-12-27 12:19:39,207 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,212 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2021-12-27 12:19:39,218 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2021-12-27 12:19:39,223 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,229 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2021-12-27 12:19:39,233 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2021-12-27 12:19:39,238 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"000" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,244 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"000" - remove event too 2021-12-27 12:19:39,248 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"000" 2021-12-27 12:19:39,254 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,257 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2021-12-27 12:19:39,264 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2021-12-27 12:19:39,268 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,272 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2021-12-27 12:19:39,278 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2021-12-27 12:19:39,282 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,289 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2021-12-27 12:19:39,295 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2021-12-27 12:19:39,300 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,305 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2021-12-27 12:19:39,309 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2021-12-27 12:19:39,315 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,320 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2021-12-27 12:19:39,326 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2021-12-27 12:19:39,331 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"2" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,336 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"2" - remove event too 2021-12-27 12:19:39,340 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"2" 2021-12-27 12:19:39,346 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,351 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2021-12-27 12:19:39,357 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2021-12-27 12:19:39,362 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,367 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2021-12-27 12:19:39,372 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2021-12-27 12:19:39,378 [TRACE] [doorpi.action.handler] unregister Event OnDTMF_"4444" from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,383 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF_"4444" - remove event too 2021-12-27 12:19:39,390 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF_"4444" 2021-12-27 12:19:39,394 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,398 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2021-12-27 12:19:39,403 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2021-12-27 12:19:39,409 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,414 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2021-12-27 12:19:39,419 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2021-12-27 12:19:39,424 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,429 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2021-12-27 12:19:39,435 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2021-12-27 12:19:39,439 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,444 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2021-12-27 12:19:39,449 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2021-12-27 12:19:39,455 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,459 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2021-12-27 12:19:39,464 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2021-12-27 12:19:39,469 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2021-12-27 12:19:39,475 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2021-12-27 12:19:39,481 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2021-12-27 12:19:39,487 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2021-12-27 12:19:39,493 [TRACE] [doorpi.action.handler] [A0W6YG] try to fire action > with args () and kwargs {} 2021-12-27 12:19:39,500 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2021-12-27 12:19:39,506 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2021-12-27 12:19:39,515 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2021-12-27 12:19:39,524 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:39,530 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2021-12-27 12:19:39,521 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:39,543 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:39,549 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2021-12-27 12:19:39,554 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2021-12-27 12:19:39,560 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2021-12-27 12:19:39,566 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2021-12-27 12:19:39,572 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2021-12-27 12:19:39,578 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2021-12-27 12:19:39,585 [TRACE] [doorpi.action.handler] [A0W6YG] try to fire action > with args () and kwargs {} 2021-12-27 12:19:39,592 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2021-12-27 12:19:39,599 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2021-12-27 12:19:39,606 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:39,610 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2021-12-27 12:19:39,616 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2021-12-27 12:19:39,621 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:39,626 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2021-12-27 12:19:39,631 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2021-12-27 12:19:39,636 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2021-12-27 12:19:39,641 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2021-12-27 12:19:39,647 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2021-12-27 12:19:39,652 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2021-12-27 12:19:39,660 [TRACE] [doorpi.action.handler] [A0W6YG] finished fire_event for event_name OnSipPhoneDestroy 2021-12-27 12:19:39,667 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2021-12-27 12:19:39,673 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,680 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2021-12-27 12:19:39,685 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2021-12-27 12:19:39,691 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,696 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2021-12-27 12:19:39,701 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2021-12-27 12:19:39,707 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,711 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2021-12-27 12:19:39,716 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2021-12-27 12:19:39,721 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,726 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2021-12-27 12:19:39,731 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2021-12-27 12:19:39,735 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,740 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2021-12-27 12:19:39,745 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2021-12-27 12:19:39,749 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,754 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2021-12-27 12:19:39,760 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2021-12-27 12:19:39,765 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,771 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2021-12-27 12:19:39,776 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2021-12-27 12:19:39,781 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,785 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2021-12-27 12:19:39,792 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2021-12-27 12:19:39,796 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,801 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2021-12-27 12:19:39,806 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2021-12-27 12:19:39,810 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,816 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2021-12-27 12:19:39,821 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2021-12-27 12:19:39,826 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,831 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2021-12-27 12:19:39,837 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2021-12-27 12:19:39,842 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2021-12-27 12:19:39,847 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2021-12-27 12:19:39,854 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2021-12-27 12:19:39,859 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2021-12-27 12:19:39,866 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action SleepAction with args (1.0,) and kwargs {} 2021-12-27 12:19:39,873 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2021-12-27 12:19:40,880 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action MailtoAction with args () and kwargs {'smtp_text': 'DoorPi down', 'smtp_subject': 'DoorPi:EVENT_OnShutdown', 'smtp_snapshot': False, 'smtp_to': 'herm@***'} 2021-12-27 12:19:40,887 [TRACE] [doorpi.action.base] run MailtoAction with args () and kwargs {'smtp_text': 'DoorPi down', 'smtp_subject': 'DoorPi:EVENT_OnShutdown', 'smtp_snapshot': False, 'smtp_to': 'herm@***'} 2021-12-27 12:19:40,894 [TRACE] [doorpi.conf.config_object] get_string for key server in section SMTP (default: smtp.gmail.com) returns smtp.web.de 2021-12-27 12:19:40,900 [TRACE] [doorpi.conf.config_object] get_integer for key port in section SMTP (default: 465) returns 587 2021-12-27 12:19:40,906 [TRACE] [doorpi.conf.config_object] get_string for key username in section SMTP (default: ) returns ***@web.de 2021-12-27 12:19:40,911 [TRACE] [doorpi.conf.config_object] get_string for key password in section SMTP (default: ) returns ******* 2021-12-27 12:19:40,917 [TRACE] [doorpi.conf.config_object] get_string for key from in section SMTP (default: ) returns ***@web.de 2021-12-27 12:19:40,923 [TRACE] [doorpi.conf.config_object] get_boolean for key use_tls in section SMTP (default: False) returns True 2021-12-27 12:19:40,930 [TRACE] [doorpi.conf.config_object] get_boolean for key use_ssl in section SMTP (default: True) returns False 2021-12-27 12:19:40,936 [TRACE] [doorpi.conf.config_object] get_boolean for key need_login in section SMTP (default: True) returns True 2021-12-27 12:19:40,941 [TRACE] [doorpi.conf.config_object] get_string for key signature in section SMTP (default: !EPILOG!) returns !EPILOG! 2021-12-27 12:19:40,955 [DEBUG] [doorpi.conf.config_object] parse string "!EPILOG!" to " ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me " 2021-12-27 12:19:41,272 [ERROR] [doorpi.action.SingleActions.mailto] send not attachment for this mail: coercing to Unicode: need string or buffer, bool found Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/DoorPi-2.5.1-py2.7.egg/doorpi/action/SingleActions/mailto.py", line 62, in fire_action_mail with open(smtp_snapshot, "rb") as snapshot_file: TypeError: coercing to Unicode: need string or buffer, bool found 2021-12-27 12:19:41,750 [TRACE] [doorpi.action.handler] [1MG6R1] try to fire action SleepAction with args (3.0,) and kwargs {} 2021-12-27 12:19:41,757 [TRACE] [doorpi.action.base] run SleepAction with args (3.0,) and kwargs {} 2021-12-27 12:19:44,764 [TRACE] [doorpi.action.handler] [1MG6R1] finished fire_event for event_name OnShutdown 2021-12-27 12:19:44,770 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2021-12-27 12:19:44,782 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2021-12-27 12:19:45,281 [DEBUG] [doorpi.doorpi] wait 5 seconds for threads 1 and 1 event 2021-12-27 12:19:45,288 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:45,295 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:45,802 [DEBUG] [doorpi.doorpi] wait 4.5 seconds for threads 1 and 1 event 2021-12-27 12:19:45,810 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:45,816 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:46,323 [DEBUG] [doorpi.doorpi] wait 4.0 seconds for threads 1 and 1 event 2021-12-27 12:19:46,328 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:46,334 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:46,841 [DEBUG] [doorpi.doorpi] wait 3.5 seconds for threads 1 and 1 event 2021-12-27 12:19:46,848 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:46,854 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:47,361 [DEBUG] [doorpi.doorpi] wait 3.0 seconds for threads 1 and 1 event 2021-12-27 12:19:47,365 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:47,372 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:47,878 [DEBUG] [doorpi.doorpi] wait 2.5 seconds for threads 1 and 1 event 2021-12-27 12:19:47,886 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:47,891 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:48,399 [DEBUG] [doorpi.doorpi] wait 2.0 seconds for threads 1 and 1 event 2021-12-27 12:19:48,404 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:48,410 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:48,917 [DEBUG] [doorpi.doorpi] wait 1.5 seconds for threads 1 and 1 event 2021-12-27 12:19:48,922 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:48,929 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:49,436 [DEBUG] [doorpi.doorpi] wait 1.0 seconds for threads 1 and 1 event 2021-12-27 12:19:49,441 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:49,448 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:49,955 [DEBUG] [doorpi.doorpi] wait 0.5 seconds for threads 1 and 1 event 2021-12-27 12:19:49,960 [TRACE] [doorpi.doorpi] still existing threads: [] 2021-12-27 12:19:49,967 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2021-12-27 12:19:50,473 [WARNING] [doorpi.doorpi] waiting for threads to time out - there are still threads: [] 2021-12-27 12:19:50,478 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ======== pi@krebs:~ $