2020-04-02 17:27:39,129 [DEBUG] [doorpi.doorpi] run 2020-04-02 17:27:39,130 [DEBUG] [doorpi.doorpi] prepare 2020-04-02 17:27:39,130 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile='/usr/local/etc/DoorPi/conf/doorpi.ini', debug=False, test=False, trace=True) 2020-04-02 17:27:39,133 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2020-04-02 17:27:39,136 [DEBUG] [doorpi.conf.config_object] __init__ 2020-04-02 17:27:39,137 [TRACE] [doorpi.conf.config_object] get_from_config 2020-04-02 17:27:39,139 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2020-04-02 17:27:39,140 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2020-04-02 17:27:39,141 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2020-04-02 17:27:39,143 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2020-04-02 17:27:39,143 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2020-04-02 17:27:39,144 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2020-04-02 17:27:39,144 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2020-04-02 17:27:39,145 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2020-04-02 17:27:39,145 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2020-04-02 17:27:39,145 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2020-04-02 17:27:39,146 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2020-04-02 17:27:39,146 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2020-04-02 17:27:39,146 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2020-04-02 17:27:39,146 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2020-04-02 17:27:39,147 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2020-04-02 17:27:39,147 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2020-04-02 17:27:39,149 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2020-04-02 17:27:39,149 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2020-04-02 17:27:39,151 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2020-04-02 17:27:39,151 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2020-04-02 17:27:39,152 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2020-04-02 17:27:39,152 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2020-04-02 17:27:39,154 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2020-04-02 17:27:39,154 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2020-04-02 17:27:39,154 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2020-04-02 17:27:39,155 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2020-04-02 17:27:39,155 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2020-04-02 17:27:39,155 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2020-04-02 17:27:39,156 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2020-04-02 17:27:39,157 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2020-04-02 17:27:39,157 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2020-04-02 17:27:39,158 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2020-04-02 17:27:39,158 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2020-04-02 17:27:39,159 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2020-04-02 17:27:39,159 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2020-04-02 17:27:39,160 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2020-04-02 17:27:39,160 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2020-04-02 17:27:39,161 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2020-04-02 17:27:39,161 [WARNING] [root] section WritePermission not found in configfile 2020-04-02 17:27:39,162 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2020-04-02 17:27:39,162 [WARNING] [root] section ReadPermission not found in configfile 2020-04-02 17:27:39,162 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2020-04-02 17:27:39,163 [WARNING] [root] section Group not found in configfile 2020-04-02 17:27:39,163 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2020-04-02 17:27:39,163 [WARNING] [root] section User not found in configfile 2020-04-02 17:27:39,164 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2020-04-02 17:27:39,164 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'AREA_public', 'onboardpins_keyboard', 'EVENT_OnSipPhoneMakeCall', 'keyboards', 'AdminNumbers', 'meinleser_keyboard', 'SIP-Phone', 'EVENT_OnCallStateDisconnect', 'meinleser_InputPins', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2020-04-02 17:27:39,165 [ERROR] [doorpi.status.webserver] no ReadPermission found 2020-04-02 17:27:39,165 [ERROR] [doorpi.status.webserver] no groups in configfile! 2020-04-02 17:27:39,165 [ERROR] [doorpi.status.webserver] no WritePermission found 2020-04-02 17:27:39,166 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2020-04-02 17:27:39,166 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2020-04-02 17:27:39,166 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2020-04-02 17:27:39,168 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,168 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2020-04-02 17:27:39,169 [DEBUG] [doorpi.action.handler] [UKQR5R] fire for event OnWebServerStart this actions [] 2020-04-02 17:27:39,169 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,170 [TRACE] [doorpi.action.handler] [UKQR5R] try to fire action WebServerStartupAction with args () and kwargs {} 2020-04-02 17:27:39,170 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,171 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2020-04-02 17:27:39,171 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,172 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,172 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,172 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,173 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,173 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,174 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,174 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,174 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,175 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,175 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,175 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,176 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2020-04-02 17:27:39,176 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['meinleser', 'onboardpins'] 2020-04-02 17:27:39,176 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: meinleser, onboardpins) 2020-04-02 17:27:39,177 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'meinleser' to handler 2020-04-02 17:27:39,177 [TRACE] [doorpi.conf.config_object] get_string for key meinleser in section keyboards (default: dummy) returns wiegand 2020-04-02 17:27:39,178 [TRACE] [doorpi.conf.config_object] get_keys for section meinleser_InputPins returns ['123456'] 2020-04-02 17:27:39,178 [WARNING] [root] section meinleser_OutputPins not found in configfile 2020-04-02 17:27:39,178 [TRACE] [doorpi.conf.config_object] get_keys for section meinleser_OutputPins returns [] 2020-04-02 17:27:39,179 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section meinleser_keyboard (default: 2000) returns 2000.0 2020-04-02 17:27:39,179 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section meinleser_keyboard (default: 0) returns 0 2020-04-02 17:27:39,179 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section meinleser_keyboard (default: True) returns True 2020-04-02 17:27:39,183 [DEBUG] [doorpi.keyboard.from_wiegand] doorpi.keyboard.from_wiegand loaded 2020-04-02 17:27:39,183 [TRACE] [doorpi.conf.config_object] get_integer for key data0 in section meinleser_keyboard (default: -1) returns 22 2020-04-02 17:27:39,184 [TRACE] [doorpi.conf.config_object] get_integer for key data1 in section meinleser_keyboard (default: -1) returns 23 2020-04-02 17:27:39,184 [TRACE] [doorpi.conf.config_object] get_integer for key timeout in section meinleser_keyboard (default: 0.25) returns 0.25 2020-04-02 17:27:39,185 [TRACE] [doorpi.conf.config_object] get_string for key mode in section meinleser_keyboard (default: BOARD) returns board 2020-04-02 17:27:39,186 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,187 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_wiegand was added 2020-04-02 17:27:39,188 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,188 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_123456 from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,189 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_123456 and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,190 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_meinleser.123456 from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,190 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_meinleser.123456 and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,191 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,191 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,193 [TRACE] [doorpi.action.handler] register Event OnKeyUp_123456 from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,194 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_123456 and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,195 [TRACE] [doorpi.action.handler] register Event OnKeyUp_meinleser.123456 from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,195 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_meinleser.123456 and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,196 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,197 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,198 [TRACE] [doorpi.action.handler] register Event OnKeyDown_123456 from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,199 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_123456 and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,199 [TRACE] [doorpi.action.handler] register Event OnKeyDown_meinleser.123456 from doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,200 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_meinleser.123456 and registered source doorpi.keyboard.from_wiegand 2020-04-02 17:27:39,201 [WARNING] [root] section meinleser_OutputPins not found in configfile 2020-04-02 17:27:39,202 [TRACE] [doorpi.conf.config_object] get_keys for section meinleser_OutputPins returns [] 2020-04-02 17:27:39,202 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2020-04-02 17:27:39,203 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2020-04-02 17:27:39,204 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['36'] 2020-04-02 17:27:39,205 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['31'] 2020-04-02 17:27:39,205 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2020-04-02 17:27:39,206 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2020-04-02 17:27:39,208 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2020-04-02 17:27:39,209 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2020-04-02 17:27:39,211 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['36'], output_pins = ['31'], bouncetime = 200.0, polarity = 0) 2020-04-02 17:27:39,212 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2020-04-02 17:27:39,213 [DEBUG] [doorpi.conf.config_object] create new key pull_up_down in section onboardpins_keyboard with value 'PUD_OFF' 2020-04-02 17:27:39,213 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2020-04-02 17:27:39,214 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,215 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2020-04-02 17:27:39,216 [TRACE] [doorpi.action.handler] added event_source doorpi.keyboard.from_gpio to existing event OnKeyPressed 2020-04-02 17:27:39,217 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_36 from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,217 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_36 and registered source doorpi.keyboard.from_gpio 2020-04-02 17:27:39,218 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.36 from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,218 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.36 and registered source doorpi.keyboard.from_gpio 2020-04-02 17:27:39,219 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,219 [TRACE] [doorpi.action.handler] added event_source doorpi.keyboard.from_gpio to existing event OnKeyUp 2020-04-02 17:27:39,220 [TRACE] [doorpi.action.handler] register Event OnKeyUp_36 from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,220 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_36 and registered source doorpi.keyboard.from_gpio 2020-04-02 17:27:39,221 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.36 from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,221 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.36 and registered source doorpi.keyboard.from_gpio 2020-04-02 17:27:39,222 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,223 [TRACE] [doorpi.action.handler] added event_source doorpi.keyboard.from_gpio to existing event OnKeyDown 2020-04-02 17:27:39,223 [TRACE] [doorpi.action.handler] register Event OnKeyDown_36 from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,224 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_36 and registered source doorpi.keyboard.from_gpio 2020-04-02 17:27:39,224 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.36 from doorpi.keyboard.from_gpio 2020-04-02 17:27:39,225 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.36 and registered source doorpi.keyboard.from_gpio 2020-04-02 17:27:39,226 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2020-04-02 17:27:39,227 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['31'] 2020-04-02 17:27:39,227 [TRACE] [doorpi.conf.config_object] get_string for key 31 in section onboardpins_OutputPins (default: ) returns klingel_g 2020-04-02 17:27:39,229 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2020-04-02 17:27:39,231 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2020-04-02 17:27:39,233 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2020-04-02 17:27:39,234 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2020-04-02 17:27:39,265 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2020-04-02 17:27:39,267 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2020-04-02 17:27:39,268 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2020-04-02 17:27:39,308 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2020-04-02 17:27:39,313 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2020-04-02 17:27:39,313 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2020-04-02 17:27:39,314 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2020-04-02 17:27:39,316 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2020-04-02 17:27:39,317 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2020-04-02 17:27:39,318 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2020-04-02 17:27:39,319 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2020-04-02 17:27:39,320 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2020-04-02 17:27:39,320 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,320 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2020-04-02 17:27:39,321 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,321 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,321 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,322 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,322 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,322 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,323 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,323 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,323 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,324 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,324 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,324 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,325 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,325 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,325 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,326 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,326 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,326 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,327 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,327 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,327 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,328 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,328 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2020-04-02 17:27:39,328 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2020-04-02 17:27:39,329 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2020-04-02 17:27:39,329 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,330 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2020-04-02 17:27:39,330 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,330 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,331 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,331 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,331 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,332 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,332 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,332 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,333 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,333 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,333 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,334 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,334 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,335 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,335 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,335 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,336 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,336 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,336 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,337 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,337 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,337 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,338 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,338 [WARNING] [root] section DTMF not found in configfile 2020-04-02 17:27:39,338 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2020-04-02 17:27:39,339 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,339 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2020-04-02 17:27:39,339 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,340 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,340 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,340 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,341 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,341 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,341 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,342 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,342 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,343 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,343 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,343 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,344 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,344 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,344 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,345 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,345 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,345 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,346 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-04-02 17:27:39,347 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2020-04-02 17:27:39,347 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:27:39,348 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x741017d0 2020-04-02 17:27:39,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2020-04-02 17:27:39,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x741017d0 2020-04-02 17:27:39,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x7404d420, "(null)", "(null)") 2020-04-02 17:27:39,350 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2020-04-02 17:27:39,351 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2020-04-02 17:27:39,351 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2020-04-02 17:27:39,351 [INFO] [doorpi.sipphone.from_linphone] srtp init 2020-04-02 17:27:39,357 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2020-04-02 17:27:39,418 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2020-04-02 17:27:39,429 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1711 - open '/dev/snd/pcmC0D0c' failed (-2) 2020-04-02 17:27:39,429 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_dsnoop.c:638 - unable to open slave 2020-04-02 17:27:39,435 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB2.0 Device' added 2020-04-02 17:27:39,436 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1711 - open '/dev/snd/pcmC1D0c' failed (-2) 2020-04-02 17:27:39,438 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2020-04-02 17:27:39,448 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1711 - open '/dev/snd/pcmC2D0p' failed (-2) 2020-04-02 17:27:39,449 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_dmix.c:1108 - unable to open slave 2020-04-02 17:27:39,450 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: AK5371' added 2020-04-02 17:27:39,450 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2020-04-02 17:27:39,451 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2020-04-02 17:27:39,451 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2020-04-02 17:27:39,452 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2020-04-02 17:27:39,452 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2020-04-02 17:27:39,452 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2020-04-02 17:27:39,453 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2020-04-02 17:27:39,453 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x189d860] registered on core [0xfc25b0] 2020-04-02 17:27:39,453 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x189d860, 1, "Starting up") 2020-04-02 17:27:39,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2020-04-02 17:27:39,454 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x189d860] notifying [global_state_changed] 2020-04-02 17:27:39,455 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2020-04-02 17:27:39,455 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2020-04-02 17:27:39,455 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2020-04-02 17:27:39,456 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2020-04-02 17:27:39,456 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2020-04-02 17:27:39,456 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-02 17:27:39,457 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-02 17:27:39,457 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2020-04-02 17:27:39,457 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2020-04-02 17:27:39,458 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2020-04-02 17:27:39,458 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2020-04-02 17:27:39,459 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2020-04-02 17:27:39,459 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2020-04-02 17:27:39,459 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2020-04-02 17:27:39,460 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2020-04-02 17:27:39,460 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2020-04-02 17:27:39,460 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2020-04-02 17:27:39,461 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2020-04-02 17:27:39,461 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2020-04-02 17:27:39,462 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2020-04-02 17:27:39,462 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2020-04-02 17:27:39,462 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2020-04-02 17:27:39,463 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2020-04-02 17:27:39,463 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-02 17:27:39,463 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-02 17:27:39,464 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-02 17:27:39,464 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-02 17:27:39,464 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-02 17:27:39,465 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-02 17:27:39,465 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-02 17:27:39,465 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-02 17:27:39,466 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2020-04-02 17:27:39,466 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2020-04-02 17:27:39,466 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2020-04-02 17:27:39,467 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2020-04-02 17:27:39,467 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2020-04-02 17:27:39,468 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-02 17:27:39,468 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-02 17:27:39,468 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-02 17:27:39,469 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-02 17:27:39,469 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2020-04-02 17:27:39,469 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2020-04-02 17:27:39,470 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2020-04-02 17:27:39,471 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2020-04-02 17:27:39,471 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2020-04-02 17:27:39,471 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2020-04-02 17:27:39,472 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2020-04-02 17:27:39,472 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2020-04-02 17:27:39,473 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2020-04-02 17:27:39,473 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2020-04-02 17:27:39,473 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2020-04-02 17:27:39,474 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2020-04-02 17:27:39,474 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2020-04-02 17:27:39,474 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2020-04-02 17:27:39,475 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2020-04-02 17:27:39,475 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2020-04-02 17:27:39,476 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2020-04-02 17:27:39,476 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2020-04-02 17:27:39,476 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2020-04-02 17:27:39,477 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2020-04-02 17:27:39,477 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2020-04-02 17:27:39,477 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2020-04-02 17:27:39,478 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2020-04-02 17:27:39,478 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2020-04-02 17:27:39,478 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2020-04-02 17:27:39,479 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2020-04-02 17:27:39,479 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2020-04-02 17:27:39,479 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2020-04-02 17:27:39,480 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2020-04-02 17:27:39,480 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2020-04-02 17:27:39,481 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2020-04-02 17:27:39,481 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2020-04-02 17:27:39,481 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2020-04-02 17:27:39,482 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2020-04-02 17:27:39,482 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2020-04-02 17:27:39,482 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2020-04-02 17:27:39,483 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2020-04-02 17:27:39,483 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2020-04-02 17:27:39,483 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2020-04-02 17:27:39,484 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2020-04-02 17:27:39,484 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2020-04-02 17:27:39,484 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2020-04-02 17:27:39,485 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2020-04-02 17:27:39,485 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2020-04-02 17:27:39,486 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,486 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,486 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,487 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x189d860, 2, "(null)") 2020-04-02 17:27:39,487 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2020-04-02 17:27:39,487 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x189d860] notifying [configuring_status] 2020-04-02 17:27:39,488 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2020-04-02 17:27:39,488 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2020-04-02 17:27:39,489 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2020-04-02 17:27:39,489 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2020-04-02 17:27:39,489 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2020-04-02 17:27:39,490 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2020-04-02 17:27:39,490 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2020-04-02 17:27:39,490 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2020-04-02 17:27:39,491 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2020-04-02 17:27:39,491 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2020-04-02 17:27:39,491 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,492 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,492 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,493 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,493 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2020-04-02 17:27:39,493 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2020-04-02 17:27:39,494 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2020-04-02 17:27:39,494 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,494 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,495 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2020-04-02 17:27:39,495 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2020-04-02 17:27:39,495 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,496 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2020-04-02 17:27:39,496 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1892b98] on [sip:0.0.0.0:5060;transport=UDP] 2020-04-02 17:27:39,497 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x189de60] on [sip:0.0.0.0:5060;transport=TCP] 2020-04-02 17:27:39,497 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18cd3f0] on [sip:0.0.0.0:-1;transport=TLS] 2020-04-02 17:27:39,498 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 34275 2020-04-02 17:27:39,498 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,498 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x189d860, 2, "Ready") 2020-04-02 17:27:39,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2020-04-02 17:27:39,502 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x189d860] notifying [global_state_changed] 2020-04-02 17:27:39,502 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x741017d0 2020-04-02 17:27:39,503 [DEBUG] [doorpi.conf.config_object] create new key identity in section SIP-Phone with value 'DoorPi' 2020-04-02 17:27:39,503 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-04-02 17:27:39,503 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x741017d0 [0x189d860], "DoorPi ") 2020-04-02 17:27:39,504 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2020-04-02 17:27:39,505 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2020-04-02 17:27:39,506 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2020-04-02 17:27:39,506 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-04-02 17:27:39,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x741017d0 [0x189d860], 2) 2020-04-02 17:27:39,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2020-04-02 17:27:39,508 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns True 2020-04-02 17:27:39,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x741017d0 [0x189d860], 0x2a3128 [1]) 2020-04-02 17:27:39,509 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2020-04-02 17:27:39,509 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-04-02 17:27:39,509 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-04-02 17:27:39,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2020-04-02 17:27:39,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x741017e8 2020-04-02 17:27:39,511 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x741017d0 [0x189d860], 0x741017e8 [0x741017f0]) 2020-04-02 17:27:39,511 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1892b98] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2020-04-02 17:27:39,512 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x189de60] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2020-04-02 17:27:39,512 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18cd3f0] on [sip:0.0.0.0:34275;transport=TLS] destroyed 2020-04-02 17:27:39,512 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2020-04-02 17:27:39,513 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18ccf28] on [sip:0.0.0.0:5060;transport=UDP] 2020-04-02 17:27:39,513 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1892d48] on [sip:0.0.0.0:5060;transport=TCP] 2020-04-02 17:27:39,514 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x189a7b8] on [sip:0.0.0.0:-1;transport=TLS] 2020-04-02 17:27:39,514 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 39095 2020-04-02 17:27:39,515 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,515 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2020-04-02 17:27:39,515 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2020-04-02 17:27:39,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x741017e8) 2020-04-02 17:27:39,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2020-04-02 17:27:39,516 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2020-04-02 17:27:39,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x741017d0 [0x189d860], 0x2a311c [0]) 2020-04-02 17:27:39,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2020-04-02 17:27:39,518 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2020-04-02 17:27:39,518 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x741017d0 [0x189d860], "") 2020-04-02 17:27:39,519 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2020-04-02 17:27:39,519 [INFO] [doorpi.sipphone.from_linphone] 10.0.0.1 2020-04-02 17:27:39,519 [INFO] [doorpi.sipphone.from_linphone] 10.0.0.10 2020-04-02 17:27:39,520 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2020-04-02 17:27:39,521 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2020-04-02 17:27:39,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2020-04-02 17:27:39,521 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2020-04-02 17:27:39,522 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2020-04-02 17:27:39,522 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x741017d0 [0x189d860], 0) 2020-04-02 17:27:39,523 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2020-04-02 17:27:39,523 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2020-04-02 17:27:39,524 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2020-04-02 17:27:39,524 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 60 2020-04-02 17:27:39,525 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x741017d0 [0x189d860], 60) 2020-04-02 17:27:39,525 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2020-04-02 17:27:39,525 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 20 2020-04-02 17:27:39,526 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x741017d0 [0x189d860], 20) 2020-04-02 17:27:39,526 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2020-04-02 17:27:39,527 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2020-04-02 17:27:39,528 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2020-04-02 17:27:39,528 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2020-04-02 17:27:39,529 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns True 2020-04-02 17:27:39,529 [INFO] [doorpi.sipphone.linphone_lib.Player] DialTone /usr/local/etc/DoorPi/media/ShortDialTone.wav does not exist - creating it now 2020-04-02 17:27:39,530 [TRACE] [doorpi.conf.config_object] get_integer for key dialtone_volume in section SIP-Phone (default: 35) returns 99 2020-04-02 17:27:39,973 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:27:39,974 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2020-04-02 17:27:39,974 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2020-04-02 17:27:39,974 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:27:39,975 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2020-04-02 17:27:39,975 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:27:39,975 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2020-04-02 17:27:39,976 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2020-04-02 17:27:39,976 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2020-04-02 17:27:39,977 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2020-04-02 17:27:39,977 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2020-04-02 17:27:39,978 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x741017d0 [0x189d860], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2020-04-02 17:27:39,979 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:27:39,979 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2020-04-02 17:27:39,980 [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/Klingel%Y-%m-%d_%H-%M-%S.wav 2020-04-02 17:27:39,980 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2020-04-02 17:27:39,981 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,981 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2020-04-02 17:27:39,981 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,982 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,982 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,982 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,983 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,983 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns True 2020-04-02 17:27:39,984 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneMakeCall 2020-04-02 17:27:39,984 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2020-04-02 17:27:39,984 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2020-04-02 17:27:39,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:39,986 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74052940 2020-04-02 17:27:39,986 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:27:39,987 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x741017d0 [0x189d860]) 2020-04-02 17:27:39,987 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7404c410 2020-04-02 17:27:39,988 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: AK5371 2020-04-02 17:27:39,988 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x741017d0 [0x189d860], "ALSA: AK5371") 2020-04-02 17:27:39,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2020-04-02 17:27:39,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x741017d0 [0x189d860]) 2020-04-02 17:27:39,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7404c410 2020-04-02 17:27:39,990 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB2.0 Device 2020-04-02 17:27:39,990 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x741017d0 [0x189d860], "ALSA: USB2.0 Device") 2020-04-02 17:27:39,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2020-04-02 17:27:39,991 [TRACE] [doorpi.conf.config_object] get_integer for key mic_gain_db in section SIP-Phone (default: 0) returns 0.0 2020-04-02 17:27:39,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_mic_gain_db(0x741017d0 [0x189d860], 0.000000) 2020-04-02 17:27:39,992 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2020-04-02 17:27:39,992 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_mic_gain_db -> 0 2020-04-02 17:27:39,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:39,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74052800 2020-04-02 17:27:39,993 [INFO] [doorpi.sipphone.from_linphone] found 4 possible sounddevices: 2020-04-02 17:27:39,994 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2020-04-02 17:27:39,994 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-02 17:27:39,994 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:39,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74052800 2020-04-02 17:27:39,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x741017d0 [0x189d860], "ALSA: default device") 2020-04-02 17:27:39,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3128 2020-04-02 17:27:39,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x741017d0 [0x189d860], "ALSA: default device") 2020-04-02 17:27:39,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2020-04-02 17:27:39,997 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2020-04-02 17:27:39,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x741017d0 [0x189d860], "ALSA: USB2.0 Device") 2020-04-02 17:27:39,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a311c 2020-04-02 17:27:39,998 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x741017d0 [0x189d860], "ALSA: USB2.0 Device") 2020-04-02 17:27:39,998 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2020-04-02 17:27:39,998 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: USB2.0 Device 2020-04-02 17:27:39,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x741017d0 [0x189d860], "ALSA: bcm2835 ALSA") 2020-04-02 17:27:39,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a311c 2020-04-02 17:27:40,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x741017d0 [0x189d860], "ALSA: bcm2835 ALSA") 2020-04-02 17:27:40,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2020-04-02 17:27:40,001 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2020-04-02 17:27:40,001 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x741017d0 [0x189d860], "ALSA: AK5371") 2020-04-02 17:27:40,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3128 2020-04-02 17:27:40,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x741017d0 [0x189d860], "ALSA: AK5371") 2020-04-02 17:27:40,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a311c 2020-04-02 17:27:40,003 [DEBUG] [doorpi.sipphone.from_linphone] | X | O | ALSA: AK5371 2020-04-02 17:27:40,003 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-02 17:27:40,003 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74052b38 2020-04-02 17:27:40,004 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: AK5371 2020-04-02 17:27:40,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74052b38 2020-04-02 17:27:40,005 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: USB2.0 Device 2020-04-02 17:27:40,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_mic_gain_db(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_mic_gain_db -> 0x18890d0 2020-04-02 17:27:40,006 [DEBUG] [doorpi.sipphone.from_linphone] mic_gain_db: 0.0 2020-04-02 17:27:40,007 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2020-04-02 17:27:40,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1893440) 2020-04-02 17:27:40,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38f20 2020-04-02 17:27:40,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38f20 2020-04-02 17:27:40,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38f20 2020-04-02 17:27:40,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18934b0) 2020-04-02 17:27:40,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38ad0 2020-04-02 17:27:40,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38ad0 2020-04-02 17:27:40,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38ad0 2020-04-02 17:27:40,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1892aa0) 2020-04-02 17:27:40,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38fd0 2020-04-02 17:27:40,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38fd0 2020-04-02 17:27:40,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38fd0 2020-04-02 17:27:40,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1892b08) 2020-04-02 17:27:40,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38ec0 2020-04-02 17:27:40,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38ec0 2020-04-02 17:27:40,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38ec0 2020-04-02 17:27:40,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1892b60) 2020-04-02 17:27:40,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38c80 2020-04-02 17:27:40,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38c80 2020-04-02 17:27:40,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38c80 2020-04-02 17:27:40,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1887508) 2020-04-02 17:27:40,019 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,019 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38d50 2020-04-02 17:27:40,019 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38d50 2020-04-02 17:27:40,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38d50 2020-04-02 17:27:40,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1887560) 2020-04-02 17:27:40,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38c30 2020-04-02 17:27:40,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38c30 2020-04-02 17:27:40,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38c30 2020-04-02 17:27:40,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x18875b8) 2020-04-02 17:27:40,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38ab0 2020-04-02 17:27:40,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38ab0 2020-04-02 17:27:40,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38ab0 2020-04-02 17:27:40,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x189dd30) 2020-04-02 17:27:40,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38f40 2020-04-02 17:27:40,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38f40 2020-04-02 17:27:40,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38f40 2020-04-02 17:27:40,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x189dd88) 2020-04-02 17:27:40,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38da0 2020-04-02 17:27:40,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38da0 2020-04-02 17:27:40,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38da0 2020-04-02 17:27:40,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x740529b8 2020-04-02 17:27:40,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38f20 [0x1893440]) 2020-04-02 17:27:40,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38f20 [0x1893440]) 2020-04-02 17:27:40,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,031 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2020-04-02 17:27:40,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38f20 [0x1893440], 0x2a311c [0]) 2020-04-02 17:27:40,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38ad0 [0x18934b0]) 2020-04-02 17:27:40,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74047900 2020-04-02 17:27:40,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38ad0 [0x18934b0]) 2020-04-02 17:27:40,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74047900 2020-04-02 17:27:40,033 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-04-02 17:27:40,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38ad0 [0x18934b0], 0x2a311c [0]) 2020-04-02 17:27:40,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38fd0 [0x1892aa0]) 2020-04-02 17:27:40,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38fd0 [0x1892aa0]) 2020-04-02 17:27:40,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,036 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-04-02 17:27:40,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38fd0 [0x1892aa0], 0x2a311c [0]) 2020-04-02 17:27:40,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38ec0 [0x1892b08]) 2020-04-02 17:27:40,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74047900 2020-04-02 17:27:40,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38ec0 [0x1892b08]) 2020-04-02 17:27:40,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74047900 2020-04-02 17:27:40,039 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2020-04-02 17:27:40,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38ec0 [0x1892b08], 0x2a3128 [1]) 2020-04-02 17:27:40,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38c80 [0x1892b60]) 2020-04-02 17:27:40,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38c80 [0x1892b60]) 2020-04-02 17:27:40,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,042 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2020-04-02 17:27:40,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38c80 [0x1892b60], 0x2a3128 [1]) 2020-04-02 17:27:40,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38d50 [0x1887508]) 2020-04-02 17:27:40,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38d50 [0x1887508]) 2020-04-02 17:27:40,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,044 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2020-04-02 17:27:40,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38d50 [0x1887508], 0x2a311c [0]) 2020-04-02 17:27:40,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38c30 [0x1887560]) 2020-04-02 17:27:40,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38c30 [0x1887560]) 2020-04-02 17:27:40,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x740478c0 2020-04-02 17:27:40,047 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2020-04-02 17:27:40,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38c30 [0x1887560], 0x2a311c [0]) 2020-04-02 17:27:40,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38ab0 [0x18875b8]) 2020-04-02 17:27:40,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74047900 2020-04-02 17:27:40,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38ab0 [0x18875b8]) 2020-04-02 17:27:40,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74047900 2020-04-02 17:27:40,049 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-04-02 17:27:40,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38ab0 [0x18875b8], 0x2a311c [0]) 2020-04-02 17:27:40,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38f40 [0x189dd30]) 2020-04-02 17:27:40,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38f40 [0x189dd30]) 2020-04-02 17:27:40,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,052 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-04-02 17:27:40,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38f40 [0x189dd30], 0x2a311c [0]) 2020-04-02 17:27:40,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38da0 [0x189dd88]) 2020-04-02 17:27:40,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38da0 [0x189dd88]) 2020-04-02 17:27:40,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,055 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-04-02 17:27:40,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38da0 [0x189dd88], 0x2a311c [0]) 2020-04-02 17:27:40,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74052940 2020-04-02 17:27:40,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74052940 2020-04-02 17:27:40,057 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2020-04-02 17:27:40,057 [DEBUG] [doorpi.sipphone.from_linphone] | name 2020-04-02 17:27:40,058 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-02 17:27:40,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74052940 2020-04-02 17:27:40,059 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2020-04-02 17:27:40,059 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2020-04-02 17:27:40,059 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-04-02 17:27:40,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74052940 2020-04-02 17:27:40,061 [DEBUG] [doorpi.conf.config_object] create new key video_device in section SIP-Phone with value 'V4L2: /dev/video0' 2020-04-02 17:27:40,061 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2020-04-02 17:27:40,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74052940 2020-04-02 17:27:40,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x741017d0 [0x189d860], 0x2a3128 [1]) 2020-04-02 17:27:40,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2020-04-02 17:27:40,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x741017d0 [0x189d860], "V4L2: /dev/video0") 2020-04-02 17:27:40,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2020-04-02 17:27:40,064 [DEBUG] [doorpi.conf.config_object] create new key video_size in section SIP-Phone with value 'vga' 2020-04-02 17:27:40,064 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2020-04-02 17:27:40,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x741017d0 [0x189d860], "vga") 2020-04-02 17:27:40,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2020-04-02 17:27:40,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x74052ca0 2020-04-02 17:27:40,066 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2020-04-02 17:27:40,066 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2020-04-02 17:27:40,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1893360) 2020-04-02 17:27:40,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2020-04-02 17:27:40,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76d38f80 2020-04-02 17:27:40,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2020-04-02 17:27:40,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76d38f80 2020-04-02 17:27:40,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76d38f80 2020-04-02 17:27:40,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x74052a08 2020-04-02 17:27:40,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38f80 [0x1893360]) 2020-04-02 17:27:40,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2a3128 2020-04-02 17:27:40,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76d38f80 [0x1893360]) 2020-04-02 17:27:40,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x741017e8 2020-04-02 17:27:40,071 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2020-04-02 17:27:40,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x741017d0 [0x189d860], 0x76d38f80 [0x1893360], 0x2a3128 [1]) 2020-04-02 17:27:40,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xf8976c 2020-04-02 17:27:40,073 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2020-04-02 17:27:40,073 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns doorpi01 2020-04-02 17:27:40,074 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: doorpi01) returns ******* 2020-04-02 17:27:40,074 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2020-04-02 17:27:40,074 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2020-04-02 17:27:40,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x741017d0 [0x189d860]) 2020-04-02 17:27:40,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x184b990) 2020-04-02 17:27:40,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2020-04-02 17:27:40,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76d38d20 2020-04-02 17:27:40,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2020-04-02 17:27:40,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76d38d20 2020-04-02 17:27:40,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76d38d20 2020-04-02 17:27:40,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76d38d20 2020-04-02 17:27:40,078 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-04-02 17:27:40,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2020-04-02 17:27:40,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1892b98) 2020-04-02 17:27:40,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2020-04-02 17:27:40,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76d38dd0 2020-04-02 17:27:40,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2020-04-02 17:27:40,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76d38dd0 2020-04-02 17:27:40,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76d38dd0 2020-04-02 17:27:40,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76d38dd0 2020-04-02 17:27:40,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76d38d20 [0x184b990], 0x76d38dd0 [0x1892b98]) 2020-04-02 17:27:40,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2020-04-02 17:27:40,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76d38d20 [0x184b990], "sip:192.168.178.1") 2020-04-02 17:27:40,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2020-04-02 17:27:40,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76d38d20 [0x184b990], 0x2a3128 [1]) 2020-04-02 17:27:40,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2020-04-02 17:27:40,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x741017d0 [0x189d860], 0x76d38d20 [0x184b990]) 2020-04-02 17:27:40,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0xf8976c 2020-04-02 17:27:40,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x741017d0 [0x189d860], 0x76d38d20 [0x184b990]) 2020-04-02 17:27:40,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2020-04-02 17:27:40,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x741017d0 [0x189d860], "doorpi01", "(null)", "geheim01", "(null)", "(null)", "fritz.box") 2020-04-02 17:27:40,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x189dbc8) 2020-04-02 17:27:40,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2020-04-02 17:27:40,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76d38b40 2020-04-02 17:27:40,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2020-04-02 17:27:40,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76d38b40 2020-04-02 17:27:40,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76d38b40 2020-04-02 17:27:40,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76d38b40 2020-04-02 17:27:40,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x741017d0 [0x189d860], 0x76d38b40 [0x189dbc8]) 2020-04-02 17:27:40,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2020-04-02 17:27:40,090 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2020-04-02 17:27:40,090 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnSipPhoneMakeCall', 'EVENT_OnCallStateDisconnect'] 2020-04-02 17:27:40,091 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnSipPhoneMakeCall' in configfile 2020-04-02 17:27:40,091 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnSipPhoneMakeCall returns ['10'] 2020-04-02 17:27:40,091 [INFO] [doorpi.doorpi] registering action '10' for event 'OnSipPhoneMakeCall' 2020-04-02 17:27:40,092 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnSipPhoneMakeCall (default: ) returns out:klingel_g,1 2020-04-02 17:27:40,092 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2020-04-02 17:27:40,093 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2020-04-02 17:27:40,094 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'klingel_g'} was added to event OnSipPhoneMakeCall 2020-04-02 17:27:40,094 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateDisconnect' in configfile 2020-04-02 17:27:40,094 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateDisconnect returns ['11'] 2020-04-02 17:27:40,095 [INFO] [doorpi.doorpi] registering action '11' for event 'OnCallStateDisconnect' 2020-04-02 17:27:40,095 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnCallStateDisconnect (default: ) returns out:klingel_g,0 2020-04-02 17:27:40,096 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'klingel_g'} was added to event OnCallStateDisconnect 2020-04-02 17:27:40,096 [TRACE] [doorpi.conf.config_object] get_string for key 123456 in section meinleser_InputPins (default: ) returns call:0151123456789 2020-04-02 17:27:40,097 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2020-04-02 17:27:40,097 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '0151123456789'} was added to new evententry OnKeyPressed_meinleser.123456 2020-04-02 17:27:40,098 [TRACE] [doorpi.conf.config_object] get_string for key 36 in section onboardpins_InputPins (default: ) returns call:0151123456789 2020-04-02 17:27:40,098 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '0151123456789'} was added to new evententry OnKeyPressed_onboardpins.36 2020-04-02 17:27:40,098 [WARNING] [root] section DTMF not found in configfile 2020-04-02 17:27:40,099 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2020-04-02 17:27:40,099 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2020-04-02 17:27:40,100 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2020-04-02 17:27:40,101 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2020-04-02 17:27:40,102 [DEBUG] [doorpi.action.handler] no actions for event OnStartup - skip fire_event OnStartup from doorpi.doorpi 2020-04-02 17:27:40,103 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2020-04-02 17:27:40,103 [INFO] [doorpi.doorpi] DoorPi started successfully 2020-04-02 17:27:40,104 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2020-04-02 17:27:40,104 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2020-04-02 17:27:40,105 [INFO] [doorpi.doorpi] Weburl is http://turkinkel/ 2020-04-02 17:27:40,106 [INFO] [doorpi.sipphone.from_linphone] resolved to 174.129.25.170 2020-04-02 17:27:40,106 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2020-04-02 17:27:40,107 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 10.0.0.41 2020-04-02 17:27:40,107 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2020-04-02 17:27:40,108 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2020-04-02 17:27:40,108 [INFO] [doorpi.sipphone.from_linphone] 10.0.0.1 2020-04-02 17:27:40,108 [INFO] [doorpi.sipphone.from_linphone] 10.0.0.10 2020-04-02 17:27:40,109 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2020-04-02 17:27:40,110 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2020-04-02 17:27:40,111 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x184b990] about to register (LinphoneCore version: 3.9.0) 2020-04-02 17:27:40,115 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2020-04-02 17:27:40,115 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538]: starting resolution of 192.168.178.1 2020-04-02 17:27:40,116 [INFO] [doorpi.sipphone.from_linphone] channel 0x18b7538: state RES_IN_PROGRESS 2020-04-02 17:27:40,116 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18cd158] channel state changed to [RES_IN_PROGRESS] 2020-04-02 17:27:40,116 [INFO] [doorpi.sipphone.from_linphone] channel 0x18b7538: state RES_DONE 2020-04-02 17:27:40,117 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18cd158] channel state changed to [RES_DONE] 2020-04-02 17:27:40,117 [INFO] [doorpi.sipphone.from_linphone] channel 0x18b7538: state CONNECTING 2020-04-02 17:27:40,118 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18cd158] channel state changed to [CONNECTING] 2020-04-02 17:27:40,118 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2020-04-02 17:27:40,118 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2020-04-02 17:27:40,119 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 10.0.0.41:5060 2020-04-02 17:27:40,119 [INFO] [doorpi.sipphone.from_linphone] channel 0x18b7538: state READY 2020-04-02 17:27:40,119 [INFO] [doorpi.sipphone.from_linphone] transaction [0x18cd158] channel state changed to [READY] 2020-04-02 17:27:40,120 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18cd158], from state [INIT] to [TRYING] 2020-04-02 17:27:40,121 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538]: message sent to [UDP://192.168.178.1:5060], size: [519] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.41:5060;branch=z9hG4bK.MbejVnX-2;rport From: "DoorPi" ;tag=apNDcnNDP To: "DoorPi" CSeq: 20 REGISTER Call-ID: rwTqt9YMaQ 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) 2020-04-02 17:27:40,121 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2020-04-02 17:27:40,122 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x18b30a8] takes ownership of transaction [0x18cd158] 2020-04-02 17:27:40,122 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x184b990] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2020-04-02 17:27:40,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x189d860, 0x184b990, 1, "Registration in progress") 2020-04-02 17:27:40,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2020-04-02 17:27:40,123 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x189d860] notifying [registration_state_changed] 2020-04-02 17:27:40,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x189d860, 0x1) 2020-04-02 17:27:40,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2020-04-02 17:27:40,175 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x189d860] notifying [network_reachable] 2020-04-02 17:27:40,175 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538]: received [395] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.0.0.41:5060;branch=z9hG4bK.MbejVnX-2;rport=5060;received=192.168.178.20 From: "DoorPi" ;tag=apNDcnNDP To: "DoorPi" ;tag=F3B2D063D23154DA Call-ID: rwTqt9YMaQ CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="CF424F9477EB16FC" User-Agent: FRITZ!OS Content-Length: 0 2020-04-02 17:27:40,180 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538] [395] bytes parsed 2020-04-02 17:27:40,181 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538]: discovered public ip and port are [192.168.178.20:5060] 2020-04-02 17:27:40,181 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2020-04-02 17:27:40,181 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18cd158], from state [TRYING] to [COMPLETED] 2020-04-02 17:27:40,182 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpi01, realm= 2020-04-02 17:27:40,182 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [doorpi01] realm [fritz.box] 2020-04-02 17:27:40,183 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf8f740], from state [INIT] to [TRYING] 2020-04-02 17:27:40,184 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538]: message sent to [UDP://192.168.178.1:5060], size: [684] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 10.0.0.41:5060;branch=z9hG4bK.MK9teyM2V;rport From: "DoorPi" ;tag=apNDcnNDP To: "DoorPi" CSeq: 21 REGISTER Call-ID: rwTqt9YMaQ 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="fritz.box", nonce="CF424F9477EB16FC", username="doorpi01", uri="sip:192.168.178.1", response="1bffbda1814a6606820447f6f5f9d478" 2020-04-02 17:27:40,184 [INFO] [doorpi.sipphone.from_linphone] resolved to 174.129.25.170 2020-04-02 17:27:40,185 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2020-04-02 17:27:40,235 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538]: received [720] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.41:5060;branch=z9hG4bK.MK9teyM2V;rport=5060;received=192.168.178.20 From: "DoorPi" ;tag=apNDcnNDP To: "DoorPi" ;tag=7CEE1CF4237E4A68 Call-ID: rwTqt9YMaQ CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 6490 Cable (lgi) 141.07.10 TAL (May 16 2019) Supported: 100rel,replaces,timer Allow-Events: telephone-event,refer,reg Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH Accept: application/sdp, multipart/mixed Accept-Encoding: identity Content-Length: 0 2020-04-02 17:27:40,243 [INFO] [doorpi.sipphone.from_linphone] channel [0x18b7538] [720] bytes parsed 2020-04-02 17:27:40,243 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2020-04-02 17:27:40,244 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf8f740], from state [TRYING] to [COMPLETED] 2020-04-02 17:27:40,244 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x18b30a8]: has no contact for request [0x18f50d0]. 2020-04-02 17:27:40,245 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2020-04-02 17:27:40,245 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.178.1] 2020-04-02 17:27:40,245 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x184b990] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2020-04-02 17:27:40,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x189d860, 0x184b990, 2, "Registration successful") 2020-04-02 17:27:40,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2020-04-02 17:27:40,246 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x189d860] notifying [registration_state_changed] 2020-04-02 17:27:45,210 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x18cd158], from state [COMPLETED] to [TERMINATED] 2020-04-02 17:27:45,211 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x18cd158] terminated 2020-04-02 17:27:45,263 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xf8f740], from state [COMPLETED] to [TERMINATED] 2020-04-02 17:27:45,264 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xf8f740] terminated 2020-04-02 17:28:13,368 [DEBUG] [doorpi.doorpi] destroy doorpi 2020-04-02 17:28:13,369 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1996337168)>, ] 2020-04-02 17:28:13,370 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2020-04-02 17:28:13,377 [DEBUG] [doorpi.action.handler] [VDQZBF] fire for event OnShutdown this actions [, , , ] 2020-04-02 17:28:13,378 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2020-04-02 17:28:13,379 [TRACE] [doorpi.action.handler] [VDQZBF] try to fire action TimeTickDestroyAction with args () and kwargs {} 2020-04-02 17:28:13,381 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2020-04-02 17:28:13,383 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2020-04-02 17:28:13,384 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,385 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2020-04-02 17:28:13,386 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2020-04-02 17:28:13,388 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,390 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2020-04-02 17:28:13,391 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2020-04-02 17:28:13,392 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,393 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2020-04-02 17:28:13,394 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2020-04-02 17:28:13,395 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,396 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2020-04-02 17:28:13,397 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2020-04-02 17:28:13,398 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,399 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2020-04-02 17:28:13,401 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2020-04-02 17:28:13,402 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,403 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2020-04-02 17:28:13,405 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2020-04-02 17:28:13,406 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,407 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2020-04-02 17:28:13,408 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2020-04-02 17:28:13,409 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,410 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2020-04-02 17:28:13,411 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2020-04-02 17:28:13,413 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,414 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2020-04-02 17:28:13,415 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2020-04-02 17:28:13,416 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,417 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2020-04-02 17:28:13,419 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2020-04-02 17:28:13,421 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,422 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2020-04-02 17:28:13,423 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2020-04-02 17:28:13,424 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,426 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2020-04-02 17:28:13,428 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2020-04-02 17:28:13,429 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,430 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2020-04-02 17:28:13,431 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2020-04-02 17:28:13,432 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,433 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2020-04-02 17:28:13,434 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2020-04-02 17:28:13,435 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,437 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2020-04-02 17:28:13,438 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2020-04-02 17:28:13,441 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,442 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2020-04-02 17:28:13,442 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2020-04-02 17:28:13,443 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,443 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2020-04-02 17:28:13,444 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2020-04-02 17:28:13,444 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,445 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2020-04-02 17:28:13,445 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2020-04-02 17:28:13,446 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,446 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2020-04-02 17:28:13,447 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2020-04-02 17:28:13,448 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,448 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2020-04-02 17:28:13,449 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2020-04-02 17:28:13,450 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,451 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2020-04-02 17:28:13,452 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2020-04-02 17:28:13,453 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,454 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2020-04-02 17:28:13,455 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2020-04-02 17:28:13,456 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,457 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2020-04-02 17:28:13,458 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2020-04-02 17:28:13,458 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,459 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2020-04-02 17:28:13,460 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2020-04-02 17:28:13,460 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,461 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2020-04-02 17:28:13,462 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2020-04-02 17:28:13,463 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,464 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2020-04-02 17:28:13,464 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2020-04-02 17:28:13,465 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,466 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2020-04-02 17:28:13,466 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2020-04-02 17:28:13,467 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,468 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2020-04-02 17:28:13,468 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2020-04-02 17:28:13,469 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,470 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2020-04-02 17:28:13,471 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2020-04-02 17:28:13,472 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,473 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2020-04-02 17:28:13,474 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2020-04-02 17:28:13,475 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,475 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2020-04-02 17:28:13,476 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2020-04-02 17:28:13,477 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,478 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2020-04-02 17:28:13,478 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2020-04-02 17:28:13,479 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,480 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2020-04-02 17:28:13,481 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2020-04-02 17:28:13,482 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,482 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2020-04-02 17:28:13,483 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2020-04-02 17:28:13,483 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,484 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2020-04-02 17:28:13,484 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2020-04-02 17:28:13,485 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,485 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2020-04-02 17:28:13,486 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2020-04-02 17:28:13,486 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,487 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2020-04-02 17:28:13,487 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2020-04-02 17:28:13,488 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,488 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2020-04-02 17:28:13,489 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2020-04-02 17:28:13,489 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,490 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2020-04-02 17:28:13,490 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2020-04-02 17:28:13,491 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,491 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2020-04-02 17:28:13,492 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2020-04-02 17:28:13,492 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,493 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2020-04-02 17:28:13,493 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2020-04-02 17:28:13,494 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,494 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2020-04-02 17:28:13,495 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2020-04-02 17:28:13,495 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,495 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2020-04-02 17:28:13,496 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2020-04-02 17:28:13,496 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,497 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2020-04-02 17:28:13,497 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2020-04-02 17:28:13,498 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,498 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2020-04-02 17:28:13,499 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2020-04-02 17:28:13,499 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,500 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2020-04-02 17:28:13,500 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2020-04-02 17:28:13,501 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,501 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2020-04-02 17:28:13,502 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2020-04-02 17:28:13,502 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,503 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2020-04-02 17:28:13,504 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2020-04-02 17:28:13,504 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,505 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2020-04-02 17:28:13,505 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2020-04-02 17:28:13,506 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,506 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2020-04-02 17:28:13,507 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2020-04-02 17:28:13,507 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,508 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2020-04-02 17:28:13,508 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2020-04-02 17:28:13,509 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,509 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2020-04-02 17:28:13,510 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2020-04-02 17:28:13,511 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,511 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2020-04-02 17:28:13,512 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2020-04-02 17:28:13,512 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,513 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2020-04-02 17:28:13,513 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2020-04-02 17:28:13,514 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,514 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2020-04-02 17:28:13,515 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2020-04-02 17:28:13,515 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,516 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2020-04-02 17:28:13,516 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2020-04-02 17:28:13,517 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,517 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2020-04-02 17:28:13,518 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2020-04-02 17:28:13,518 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,519 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2020-04-02 17:28:13,519 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2020-04-02 17:28:13,520 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,521 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2020-04-02 17:28:13,521 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2020-04-02 17:28:13,522 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,522 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2020-04-02 17:28:13,523 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2020-04-02 17:28:13,523 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,524 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2020-04-02 17:28:13,524 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2020-04-02 17:28:13,525 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,525 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2020-04-02 17:28:13,526 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2020-04-02 17:28:13,526 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,527 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2020-04-02 17:28:13,527 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2020-04-02 17:28:13,528 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,528 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2020-04-02 17:28:13,529 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2020-04-02 17:28:13,529 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,530 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2020-04-02 17:28:13,531 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2020-04-02 17:28:13,531 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,532 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2020-04-02 17:28:13,532 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2020-04-02 17:28:13,533 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,533 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2020-04-02 17:28:13,534 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2020-04-02 17:28:13,534 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,535 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2020-04-02 17:28:13,535 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2020-04-02 17:28:13,536 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,536 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2020-04-02 17:28:13,537 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2020-04-02 17:28:13,537 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,538 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2020-04-02 17:28:13,538 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2020-04-02 17:28:13,539 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,539 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2020-04-02 17:28:13,540 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2020-04-02 17:28:13,541 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,541 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2020-04-02 17:28:13,542 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2020-04-02 17:28:13,542 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,543 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2020-04-02 17:28:13,543 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2020-04-02 17:28:13,544 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,544 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2020-04-02 17:28:13,545 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2020-04-02 17:28:13,545 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,546 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2020-04-02 17:28:13,546 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2020-04-02 17:28:13,547 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,547 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2020-04-02 17:28:13,548 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2020-04-02 17:28:13,548 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,549 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2020-04-02 17:28:13,549 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2020-04-02 17:28:13,550 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,551 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2020-04-02 17:28:13,551 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2020-04-02 17:28:13,552 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,553 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2020-04-02 17:28:13,553 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2020-04-02 17:28:13,554 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,554 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2020-04-02 17:28:13,555 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2020-04-02 17:28:13,555 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,556 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2020-04-02 17:28:13,556 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2020-04-02 17:28:13,557 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,557 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2020-04-02 17:28:13,558 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2020-04-02 17:28:13,558 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,559 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2020-04-02 17:28:13,559 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2020-04-02 17:28:13,560 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,561 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2020-04-02 17:28:13,561 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2020-04-02 17:28:13,561 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,562 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2020-04-02 17:28:13,562 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2020-04-02 17:28:13,563 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,563 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2020-04-02 17:28:13,564 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2020-04-02 17:28:13,564 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,565 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2020-04-02 17:28:13,565 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2020-04-02 17:28:13,566 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,567 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2020-04-02 17:28:13,567 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2020-04-02 17:28:13,568 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,568 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2020-04-02 17:28:13,569 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2020-04-02 17:28:13,569 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,570 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2020-04-02 17:28:13,570 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2020-04-02 17:28:13,571 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,571 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2020-04-02 17:28:13,572 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2020-04-02 17:28:13,573 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,573 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2020-04-02 17:28:13,574 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2020-04-02 17:28:13,574 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,575 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2020-04-02 17:28:13,575 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2020-04-02 17:28:13,576 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,576 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2020-04-02 17:28:13,577 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2020-04-02 17:28:13,577 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,578 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2020-04-02 17:28:13,578 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2020-04-02 17:28:13,579 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,579 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2020-04-02 17:28:13,580 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2020-04-02 17:28:13,580 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,581 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2020-04-02 17:28:13,582 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2020-04-02 17:28:13,582 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,583 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2020-04-02 17:28:13,583 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2020-04-02 17:28:13,584 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,584 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2020-04-02 17:28:13,585 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2020-04-02 17:28:13,585 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,586 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2020-04-02 17:28:13,587 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2020-04-02 17:28:13,587 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,588 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2020-04-02 17:28:13,588 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2020-04-02 17:28:13,589 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,589 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2020-04-02 17:28:13,590 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2020-04-02 17:28:13,591 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,591 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2020-04-02 17:28:13,592 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2020-04-02 17:28:13,592 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,593 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2020-04-02 17:28:13,593 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2020-04-02 17:28:13,594 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2020-04-02 17:28:13,594 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2020-04-02 17:28:13,595 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2020-04-02 17:28:13,595 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2020-04-02 17:28:13,596 [TRACE] [doorpi.action.handler] [VDQZBF] try to fire action WebServerShutdownAction with args () and kwargs {} 2020-04-02 17:28:13,597 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2020-04-02 17:28:13,597 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2020-04-02 17:28:13,598 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2020-04-02 17:28:13,599 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2020-04-02 17:28:13,599 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,601 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2020-04-02 17:28:13,601 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2020-04-02 17:28:13,602 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,603 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2020-04-02 17:28:13,603 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2020-04-02 17:28:13,604 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,604 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2020-04-02 17:28:13,605 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2020-04-02 17:28:13,606 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,606 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2020-04-02 17:28:13,607 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2020-04-02 17:28:13,607 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,608 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2020-04-02 17:28:13,608 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2020-04-02 17:28:13,609 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,609 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2020-04-02 17:28:13,610 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2020-04-02 17:28:13,611 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,611 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2020-04-02 17:28:13,612 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2020-04-02 17:28:13,613 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2020-04-02 17:28:13,613 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2020-04-02 17:28:13,614 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2020-04-02 17:28:13,614 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2020-04-02 17:28:13,619 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2020-04-02 17:28:13,621 [TRACE] [doorpi.action.handler] [UKQR5R] finished fire_event for event_name OnWebServerStart 2020-04-02 17:28:13,621 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2020-04-02 17:28:13,623 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2020-04-02 17:28:13,623 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2020-04-02 17:28:13,624 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2020-04-02 17:28:13,625 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2020-04-02 17:28:13,625 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2020-04-02 17:28:13,626 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2020-04-02 17:28:13,627 [TRACE] [doorpi.action.handler] [VDQZBF] try to fire action KeyboardDestroyAction with args () and kwargs {} 2020-04-02 17:28:13,627 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2020-04-02 17:28:13,628 [DEBUG] [doorpi.keyboard.from_gpio] destroy 2020-04-02 17:28:13,631 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_gpio and force_unregister is True 2020-04-02 17:28:13,632 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,632 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed 2020-04-02 17:28:13,634 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,635 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp 2020-04-02 17:28:13,635 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_36 from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,636 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_36 - remove event too 2020-04-02 17:28:13,637 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_36 2020-04-02 17:28:13,638 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_36 from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,639 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_36 - remove event too 2020-04-02 17:28:13,639 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_36 2020-04-02 17:28:13,642 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,643 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown 2020-04-02 17:28:13,645 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.36 from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,646 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.36 - remove event too 2020-04-02 17:28:13,647 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.36 2020-04-02 17:28:13,648 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_36 from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,649 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_36 - remove event too 2020-04-02 17:28:13,651 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_36 2020-04-02 17:28:13,652 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.36 from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,654 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.36 - remove event too 2020-04-02 17:28:13,655 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.36 2020-04-02 17:28:13,656 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.36 from doorpi.keyboard.from_gpio 2020-04-02 17:28:13,658 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.36 - remove event too 2020-04-02 17:28:13,659 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.36 2020-04-02 17:28:13,660 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed 2020-04-02 17:28:13,662 [TRACE] [doorpi.action.handler] [VDQZBF] try to fire action > with args () and kwargs {} 2020-04-02 17:28:13,663 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-04-02 17:28:13,664 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2020-04-02 17:28:13,665 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x741017d0 [0x189d860]) 2020-04-02 17:28:13,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0xf8976c 2020-04-02 17:28:13,668 [DEBUG] [doorpi.action.handler] [REVAUR] fire for event OnSipPhoneDestroy this actions [, , ] 2020-04-02 17:28:13,669 [TRACE] [doorpi.action.handler] [REVAUR] try to fire action > with args () and kwargs {} 2020-04-02 17:28:13,670 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-04-02 17:28:13,671 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2020-04-02 17:28:13,672 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2020-04-02 17:28:13,673 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,674 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2020-04-02 17:28:13,675 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2020-04-02 17:28:13,676 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,677 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2020-04-02 17:28:13,678 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2020-04-02 17:28:13,679 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,679 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2020-04-02 17:28:13,680 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2020-04-02 17:28:13,681 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,681 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2020-04-02 17:28:13,682 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2020-04-02 17:28:13,682 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,683 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2020-04-02 17:28:13,683 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2020-04-02 17:28:13,684 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,684 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2020-04-02 17:28:13,685 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2020-04-02 17:28:13,686 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,686 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2020-04-02 17:28:13,687 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2020-04-02 17:28:13,687 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,688 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2020-04-02 17:28:13,688 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2020-04-02 17:28:13,689 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,689 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2020-04-02 17:28:13,690 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2020-04-02 17:28:13,691 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,691 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2020-04-02 17:28:13,692 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2020-04-02 17:28:13,692 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,693 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2020-04-02 17:28:13,693 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2020-04-02 17:28:13,694 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,694 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2020-04-02 17:28:13,695 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2020-04-02 17:28:13,695 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,696 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2020-04-02 17:28:13,696 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2020-04-02 17:28:13,697 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,697 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2020-04-02 17:28:13,698 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2020-04-02 17:28:13,698 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,699 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2020-04-02 17:28:13,699 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2020-04-02 17:28:13,700 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,700 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2020-04-02 17:28:13,701 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2020-04-02 17:28:13,701 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,702 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2020-04-02 17:28:13,703 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2020-04-02 17:28:13,703 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,704 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2020-04-02 17:28:13,704 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2020-04-02 17:28:13,705 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,705 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2020-04-02 17:28:13,706 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2020-04-02 17:28:13,706 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,707 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2020-04-02 17:28:13,707 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2020-04-02 17:28:13,708 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-04-02 17:28:13,708 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2020-04-02 17:28:13,709 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2020-04-02 17:28:13,709 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2020-04-02 17:28:13,710 [TRACE] [doorpi.action.handler] [REVAUR] try to fire action > with args () and kwargs {} 2020-04-02 17:28:13,711 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-04-02 17:28:13,712 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2020-04-02 17:28:13,713 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2020-04-02 17:28:13,713 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:28:13,714 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:28:13,715 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2020-04-02 17:28:13,716 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:28:13,716 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2020-04-02 17:28:13,717 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2020-04-02 17:28:13,717 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-04-02 17:28:13,718 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2020-04-02 17:28:13,718 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2020-04-02 17:28:13,719 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2020-04-02 17:28:13,720 [TRACE] [doorpi.action.handler] [REVAUR] try to fire action > with args () and kwargs {} 2020-04-02 17:28:13,721 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-04-02 17:28:13,721 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2020-04-02 17:28:13,722 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:28:13,723 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2020-04-02 17:28:13,723 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2020-04-02 17:28:13,724 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:28:13,724 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2020-04-02 17:28:13,725 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2020-04-02 17:28:13,725 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2020-04-02 17:28:13,726 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2020-04-02 17:28:13,726 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2020-04-02 17:28:13,727 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2020-04-02 17:28:13,727 [TRACE] [doorpi.action.handler] [REVAUR] finished fire_event for event_name OnSipPhoneDestroy 2020-04-02 17:28:13,728 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2020-04-02 17:28:13,729 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,729 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2020-04-02 17:28:13,730 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2020-04-02 17:28:13,730 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,731 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2020-04-02 17:28:13,731 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2020-04-02 17:28:13,732 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,732 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2020-04-02 17:28:13,733 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2020-04-02 17:28:13,733 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,734 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2020-04-02 17:28:13,734 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2020-04-02 17:28:13,735 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,735 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2020-04-02 17:28:13,736 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2020-04-02 17:28:13,736 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,737 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2020-04-02 17:28:13,738 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2020-04-02 17:28:13,738 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,739 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2020-04-02 17:28:13,739 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2020-04-02 17:28:13,740 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,740 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2020-04-02 17:28:13,741 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2020-04-02 17:28:13,741 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,742 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2020-04-02 17:28:13,742 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2020-04-02 17:28:13,743 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,743 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2020-04-02 17:28:13,744 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2020-04-02 17:28:13,744 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,745 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2020-04-02 17:28:13,745 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2020-04-02 17:28:13,746 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-04-02 17:28:13,746 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2020-04-02 17:28:13,747 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2020-04-02 17:28:13,748 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2020-04-02 17:28:13,748 [TRACE] [doorpi.action.handler] [VDQZBF] finished fire_event for event_name OnShutdown 2020-04-02 17:28:13,749 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2020-04-02 17:28:13,751 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2020-04-02 17:28:14,251 [DEBUG] [doorpi.doorpi] wait 5 seconds for threads 0 and 2 event 2020-04-02 17:28:14,252 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:14,253 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:14,754 [DEBUG] [doorpi.doorpi] wait 4.5 seconds for threads 0 and 2 event 2020-04-02 17:28:14,756 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:14,757 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:15,259 [DEBUG] [doorpi.doorpi] wait 4.0 seconds for threads 0 and 2 event 2020-04-02 17:28:15,260 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:15,261 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:15,763 [DEBUG] [doorpi.doorpi] wait 3.5 seconds for threads 0 and 2 event 2020-04-02 17:28:15,764 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:15,765 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:16,267 [DEBUG] [doorpi.doorpi] wait 3.0 seconds for threads 0 and 2 event 2020-04-02 17:28:16,269 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:16,273 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:16,776 [DEBUG] [doorpi.doorpi] wait 2.5 seconds for threads 0 and 2 event 2020-04-02 17:28:16,777 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:16,778 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:17,280 [DEBUG] [doorpi.doorpi] wait 2.0 seconds for threads 0 and 2 event 2020-04-02 17:28:17,281 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:17,282 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:17,784 [DEBUG] [doorpi.doorpi] wait 1.5 seconds for threads 0 and 2 event 2020-04-02 17:28:17,785 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:17,786 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:18,288 [DEBUG] [doorpi.doorpi] wait 1.0 seconds for threads 0 and 2 event 2020-04-02 17:28:18,290 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:18,291 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:18,793 [DEBUG] [doorpi.doorpi] wait 0.5 seconds for threads 0 and 2 event 2020-04-02 17:28:18,794 [TRACE] [doorpi.doorpi] still existing threads: [] 2020-04-02 17:28:18,795 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi', 'doorpi.keyboard.from_wiegand'] 2020-04-02 17:28:19,297 [WARNING] [doorpi.doorpi] waiting for threads to time out - there are still threads: [] 2020-04-02 17:28:19,298 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ========