pi@raspberrypi:~ $ sudo service doorpi stop pi@raspberrypi:~ $ sudo doorpi_cli --trace 2019-06-13 13:25:42,143 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2019-06-13 13:25:42,143 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2019-06-13 13:25:42,144 [DEBUG] [doorpi.doorpi] run 2019-06-13 13:25:42,144 [DEBUG] [doorpi.doorpi] prepare 2019-06-13 13:25:42,145 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2019-06-13 13:25:42,147 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2019-06-13 13:25:42,152 [DEBUG] [doorpi.conf.config_object] __init__ 2019-06-13 13:25:42,153 [TRACE] [doorpi.conf.config_object] get_from_config 2019-06-13 13:25:42,157 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2019-06-13 13:25:42,158 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2019-06-13 13:25:42,158 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2019-06-13 13:25:42,161 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2019-06-13 13:25:42,162 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2019-06-13 13:25:42,162 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2019-06-13 13:25:42,162 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2019-06-13 13:25:42,162 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2019-06-13 13:25:42,163 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2019-06-13 13:25:42,163 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2019-06-13 13:25:42,163 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2019-06-13 13:25:42,163 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2019-06-13 13:25:42,163 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2019-06-13 13:25:42,164 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2019-06-13 13:25:42,164 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2019-06-13 13:25:42,164 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2019-06-13 13:25:42,165 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2019-06-13 13:25:42,166 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2019-06-13 13:25:42,167 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2019-06-13 13:25:42,168 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2019-06-13 13:25:42,168 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 192.168.188.23 2019-06-13 13:25:42,169 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2019-06-13 13:25:42,174 [INFO] [doorpi.status.webserver] Initiating WebService at ip 192.168.188.23 and port 80 2019-06-13 13:25:42,175 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2019-06-13 13:25:42,175 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2019-06-13 13:25:42,175 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2019-06-13 13:25:42,176 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2019-06-13 13:25:42,176 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2019-06-13 13:25:42,176 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2019-06-13 13:25:42,177 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2019-06-13 13:25:42,177 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2019-06-13 13:25:42,179 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2019-06-13 13:25:42,181 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2019-06-13 13:25:42,183 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2019-06-13 13:25:42,184 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2019-06-13 13:25:42,187 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2019-06-13 13:25:42,188 [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 2019-06-13 13:25:42,190 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2019-06-13 13:25:42,190 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2019-06-13 13:25:42,191 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['guests'] 2019-06-13 13:25:42,192 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators', 'guests'] 2019-06-13 13:25:42,192 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['visitor', 'door'] 2019-06-13 13:25:42,193 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['door'] 2019-06-13 13:25:42,194 [TRACE] [doorpi.conf.config_object] get_list for key guests in section Group (default: []) returns ['visitor'] 2019-06-13 13:25:42,194 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'AREA_public', 'onboardpins_keyboard', 'Group', 'ReadPermission', 'AREA_dashboard', 'keyboards', 'DTMF', 'SIP-Phone', 'AREA_config', 'onboardpins_InputPins', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'DoorPi', 'WritePermission', 'AREA_status'] 2019-06-13 13:25:42,195 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['dashboard', 'status', 'config'] 2019-06-13 13:25:42,196 [TRACE] [doorpi.conf.config_object] get_list for key guests in section ReadPermission (default: []) returns ['dashboard'] 2019-06-13 13:25:42,197 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2019-06-13 13:25:42,198 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2019-06-13 13:25:42,199 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2019-06-13 13:25:42,200 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,201 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2019-06-13 13:25:42,202 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,202 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,203 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,203 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,203 [DEBUG] [doorpi.action.handler] [5B0TCR] fire for event OnWebServerStart this actions [] 2019-06-13 13:25:42,204 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,205 [TRACE] [doorpi.action.handler] [5B0TCR] try to fire action WebServerStartupAction with args () and kwargs {} 2019-06-13 13:25:42,205 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,205 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2019-06-13 13:25:42,206 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,207 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,208 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,209 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,209 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,210 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,210 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,210 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,210 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2019-06-13 13:25:42,211 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2019-06-13 13:25:42,211 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2019-06-13 13:25:42,211 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2019-06-13 13:25:42,211 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2019-06-13 13:25:42,212 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['7'] 2019-06-13 13:25:42,212 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['3'] 2019-06-13 13:25:42,212 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2019-06-13 13:25:42,212 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2019-06-13 13:25:42,213 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2019-06-13 13:25:42,213 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2019-06-13 13:25:42,215 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['7'], output_pins = ['3'], bouncetime = 200.0, polarity = 0) 2019-06-13 13:25:42,216 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2019-06-13 13:25:42,216 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2019-06-13 13:25:42,217 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,218 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2019-06-13 13:25:42,219 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,219 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,219 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,220 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,220 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,220 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,221 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,221 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,222 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,223 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,223 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,223 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,224 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,224 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,224 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,225 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_gpio 2019-06-13 13:25:42,225 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2019-06-13 13:25:42,226 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2019-06-13 13:25:42,227 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['3'] 2019-06-13 13:25:42,227 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_OutputPins (default: ) returns Tueroeffner 2019-06-13 13:25:42,229 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2019-06-13 13:25:42,231 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2019-06-13 13:25:42,232 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2019-06-13 13:25:42,234 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2019-06-13 13:25:42,286 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2019-06-13 13:25:42,288 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2019-06-13 13:25:42,290 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2019-06-13 13:25:42,352 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2019-06-13 13:25:42,359 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2019-06-13 13:25:42,360 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2019-06-13 13:25:42,362 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2019-06-13 13:25:42,365 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2019-06-13 13:25:42,368 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2019-06-13 13:25:42,371 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2019-06-13 13:25:42,372 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2019-06-13 13:25:42,372 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2019-06-13 13:25:42,373 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,374 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2019-06-13 13:25:42,375 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,376 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,377 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,378 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,379 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,381 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,381 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,382 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,383 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,384 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,385 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,385 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,386 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,386 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,386 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,387 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,387 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,387 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,388 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,388 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,389 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,389 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,389 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2019-06-13 13:25:42,390 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2019-06-13 13:25:42,390 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2019-06-13 13:25:42,391 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,391 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2019-06-13 13:25:42,392 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,393 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,394 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,394 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,395 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,395 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,396 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,397 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,397 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,398 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,399 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,399 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,400 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,400 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,401 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,402 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,403 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,403 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,404 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,405 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,405 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,405 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,406 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,406 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['0'] 2019-06-13 13:25:42,407 [TRACE] [doorpi.action.handler] register Event OnDTMF_0 from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,407 [TRACE] [doorpi.action.handler] added event_name OnDTMF_0 and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,408 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,409 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2019-06-13 13:25:42,409 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,410 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,410 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,411 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,411 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,412 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,412 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,413 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,413 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,413 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,414 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,414 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,414 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,415 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,415 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,416 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,416 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,417 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,417 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2019-06-13 13:25:42,419 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2019-06-13 13:25:42,420 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x744ede78 2019-06-13 13:25:42,421 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-06-13 13:25:42,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2019-06-13 13:25:42,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x744ede78 2019-06-13 13:25:42,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74453ae0, "(null)", "(null)") 2019-06-13 13:25:42,426 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2019-06-13 13:25:42,427 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2019-06-13 13:25:42,428 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2019-06-13 13:25:42,429 [INFO] [doorpi.sipphone.from_linphone] srtp init 2019-06-13 13:25:42,437 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2019-06-13 13:25:42,483 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2019-06-13 13:25:42,493 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:767 - cannot find card '0' 2019-06-13 13:25:42,494 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4528 - function snd_func_card_driver returned error: No such file or directory 2019-06-13 13:25:42,494 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:392 - error evaluating strings 2019-06-13 13:25:42,494 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4528 - function snd_func_concat returned error: No such file or directory 2019-06-13 13:25:42,495 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:1246 - error evaluating name 2019-06-13 13:25:42,495 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4528 - function snd_func_refer returned error: No such file or directory 2019-06-13 13:25:42,497 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:5007 - Evaluate error: No such file or directory 2019-06-13 13:25:42,498 [INFO] [doorpi.sipphone.from_linphone] also error in pcm.c:2495 - Unknown PCM sysdefault 2019-06-13 13:25:42,499 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:767 - cannot find card '0' 2019-06-13 13:25:42,501 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4528 - function snd_func_card_driver returned error: No such file or directory 2019-06-13 13:25:42,502 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:392 - error evaluating strings 2019-06-13 13:25:42,503 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4528 - function snd_func_concat returned error: No such file or directory 2019-06-13 13:25:42,504 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:1246 - error evaluating name 2019-06-13 13:25:42,505 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4528 - function snd_func_refer returned error: No such file or directory 2019-06-13 13:25:42,506 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:5007 - Evaluate error: No such file or directory 2019-06-13 13:25:42,507 [INFO] [doorpi.sipphone.from_linphone] also error in pcm.c:2495 - Unknown PCM sysdefault 2019-06-13 13:25:42,509 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2019-06-13 13:25:42,511 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2019-06-13 13:25:42,515 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2019-06-13 13:25:42,516 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2019-06-13 13:25:42,517 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2019-06-13 13:25:42,518 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2019-06-13 13:25:42,519 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2019-06-13 13:25:42,520 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x1a1dc60] registered on core [0x1a1df30] 2019-06-13 13:25:42,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1a1dc60, 1, "Starting up") 2019-06-13 13:25:42,522 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2019-06-13 13:25:42,523 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1a1dc60] notifying [global_state_changed] 2019-06-13 13:25:42,524 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2019-06-13 13:25:42,525 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2019-06-13 13:25:42,526 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2019-06-13 13:25:42,527 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2019-06-13 13:25:42,527 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2019-06-13 13:25:42,528 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-06-13 13:25:42,529 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-06-13 13:25:42,530 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2019-06-13 13:25:42,531 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2019-06-13 13:25:42,531 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2019-06-13 13:25:42,532 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2019-06-13 13:25:42,533 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2019-06-13 13:25:42,534 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2019-06-13 13:25:42,535 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2019-06-13 13:25:42,535 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2019-06-13 13:25:42,536 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2019-06-13 13:25:42,536 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2019-06-13 13:25:42,537 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2019-06-13 13:25:42,538 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2019-06-13 13:25:42,539 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2019-06-13 13:25:42,539 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2019-06-13 13:25:42,540 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2019-06-13 13:25:42,541 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2019-06-13 13:25:42,541 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-06-13 13:25:42,542 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-06-13 13:25:42,543 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-06-13 13:25:42,543 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-06-13 13:25:42,544 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-06-13 13:25:42,544 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-06-13 13:25:42,545 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-06-13 13:25:42,545 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-06-13 13:25:42,546 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-06-13 13:25:42,546 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-06-13 13:25:42,547 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2019-06-13 13:25:42,547 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2019-06-13 13:25:42,548 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2019-06-13 13:25:42,549 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-06-13 13:25:42,549 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-06-13 13:25:42,550 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-06-13 13:25:42,550 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-06-13 13:25:42,551 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-06-13 13:25:42,551 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-06-13 13:25:42,552 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2019-06-13 13:25:42,552 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2019-06-13 13:25:42,553 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2019-06-13 13:25:42,553 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2019-06-13 13:25:42,554 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2019-06-13 13:25:42,554 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2019-06-13 13:25:42,555 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2019-06-13 13:25:42,555 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2019-06-13 13:25:42,556 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2019-06-13 13:25:42,557 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2019-06-13 13:25:42,557 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2019-06-13 13:25:42,558 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2019-06-13 13:25:42,559 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2019-06-13 13:25:42,560 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2019-06-13 13:25:42,561 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2019-06-13 13:25:42,562 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2019-06-13 13:25:42,563 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2019-06-13 13:25:42,563 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2019-06-13 13:25:42,564 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2019-06-13 13:25:42,564 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2019-06-13 13:25:42,564 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2019-06-13 13:25:42,565 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2019-06-13 13:25:42,565 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2019-06-13 13:25:42,565 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2019-06-13 13:25:42,566 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2019-06-13 13:25:42,567 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2019-06-13 13:25:42,568 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2019-06-13 13:25:42,569 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2019-06-13 13:25:42,571 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2019-06-13 13:25:42,572 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2019-06-13 13:25:42,573 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2019-06-13 13:25:42,573 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2019-06-13 13:25:42,574 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2019-06-13 13:25:42,575 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2019-06-13 13:25:42,576 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2019-06-13 13:25:42,577 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2019-06-13 13:25:42,579 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2019-06-13 13:25:42,581 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2019-06-13 13:25:42,583 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2019-06-13 13:25:42,585 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2019-06-13 13:25:42,586 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2019-06-13 13:25:42,587 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2019-06-13 13:25:42,588 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,588 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,589 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,590 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x1a1dc60, 2, "(null)") 2019-06-13 13:25:42,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2019-06-13 13:25:42,591 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1a1dc60] notifying [configuring_status] 2019-06-13 13:25:42,592 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2019-06-13 13:25:42,592 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2019-06-13 13:25:42,593 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2019-06-13 13:25:42,594 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2019-06-13 13:25:42,594 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2019-06-13 13:25:42,595 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2019-06-13 13:25:42,595 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2019-06-13 13:25:42,596 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2019-06-13 13:25:42,596 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2019-06-13 13:25:42,597 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2019-06-13 13:25:42,597 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,598 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,599 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,599 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,600 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2019-06-13 13:25:42,600 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2019-06-13 13:25:42,600 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2019-06-13 13:25:42,601 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,602 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,602 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2019-06-13 13:25:42,602 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2019-06-13 13:25:42,603 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,604 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2019-06-13 13:25:42,604 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x19fd3b8] on [sip:0.0.0.0:5060;transport=UDP] 2019-06-13 13:25:42,605 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1a17ea0] on [sip:0.0.0.0:5060;transport=TCP] 2019-06-13 13:25:42,606 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1a18040] on [sip:0.0.0.0:-1;transport=TLS] 2019-06-13 13:25:42,607 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 41367 2019-06-13 13:25:42,607 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,608 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,610 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1a1dc60, 2, "Ready") 2019-06-13 13:25:42,611 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2019-06-13 13:25:42,612 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1a1dc60] notifying [global_state_changed] 2019-06-13 13:25:42,612 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x744ede78 2019-06-13 13:25:42,613 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns Tuer 2019-06-13 13:25:42,614 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x744ede78 [0x1a1dc60], "Tuer ") 2019-06-13 13:25:42,615 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2019-06-13 13:25:42,616 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2019-06-13 13:25:42,618 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2019-06-13 13:25:42,619 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x744ede78 [0x1a1dc60], 2) 2019-06-13 13:25:42,619 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2019-06-13 13:25:42,620 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2019-06-13 13:25:42,621 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2019-06-13 13:25:42,622 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x744ede78 [0x1a1dc60], 0x2d7690 [0]) 2019-06-13 13:25:42,623 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2019-06-13 13:25:42,623 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2019-06-13 13:25:42,624 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2019-06-13 13:25:42,625 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2019-06-13 13:25:42,626 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x744ede90 2019-06-13 13:25:42,627 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x744ede78 [0x1a1dc60], 0x744ede90 [0x744ede98]) 2019-06-13 13:25:42,628 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x19fd3b8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2019-06-13 13:25:42,629 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1a17ea0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2019-06-13 13:25:42,631 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1a18040] on [sip:0.0.0.0:41367;transport=TLS] destroyed 2019-06-13 13:25:42,631 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2019-06-13 13:25:42,632 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1a1a3e8] on [sip:0.0.0.0:5060;transport=UDP] 2019-06-13 13:25:42,633 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x19fd2c8] on [sip:0.0.0.0:5060;transport=TCP] 2019-06-13 13:25:42,634 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x19fd430] on [sip:0.0.0.0:-1;transport=TLS] 2019-06-13 13:25:42,634 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 41081 2019-06-13 13:25:42,635 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,635 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-06-13 13:25:42,636 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2019-06-13 13:25:42,637 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x744ede90) 2019-06-13 13:25:42,638 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2019-06-13 13:25:42,639 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2019-06-13 13:25:42,640 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x744ede78 [0x1a1dc60], 0x2d7690 [0]) 2019-06-13 13:25:42,641 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2019-06-13 13:25:42,642 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2019-06-13 13:25:42,643 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x744ede78 [0x1a1dc60], "") 2019-06-13 13:25:42,645 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2019-06-13 13:25:42,646 [INFO] [doorpi.sipphone.from_linphone] 192.168.188.1 2019-06-13 13:25:42,650 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2019-06-13 13:25:42,653 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2019-06-13 13:25:42,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2019-06-13 13:25:42,656 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2019-06-13 13:25:42,657 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2019-06-13 13:25:42,658 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x744ede78 [0x1a1dc60], 0) 2019-06-13 13:25:42,659 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2019-06-13 13:25:42,660 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2019-06-13 13:25:42,662 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2019-06-13 13:25:42,663 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 90 2019-06-13 13:25:42,664 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x744ede78 [0x1a1dc60], 90) 2019-06-13 13:25:42,665 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2019-06-13 13:25:42,665 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 20 2019-06-13 13:25:42,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x744ede78 [0x1a1dc60], 20) 2019-06-13 13:25:42,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2019-06-13 13:25:42,668 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2019-06-13 13:25:42,669 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2019-06-13 13:25:42,670 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2019-06-13 13:25:42,672 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2019-06-13 13:25:42,673 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2019-06-13 13:25:42,673 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2019-06-13 13:25:42,674 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2019-06-13 13:25:42,674 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2019-06-13 13:25:42,675 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2019-06-13 13:25:42,675 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2019-06-13 13:25:42,676 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2019-06-13 13:25:42,677 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2019-06-13 13:25:42,677 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2019-06-13 13:25:42,678 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2019-06-13 13:25:42,679 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2019-06-13 13:25:42,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x744ede78 [0x1a1dc60], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2019-06-13 13:25:42,682 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2019-06-13 13:25:42,682 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2019-06-13 13:25:42,683 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2019-06-13 13:25:42,684 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2019-06-13 13:25:42,685 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,685 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2019-06-13 13:25:42,686 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,687 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,687 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,688 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,689 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,689 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2019-06-13 13:25:42,690 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2019-06-13 13:25:42,691 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2019-06-13 13:25:42,691 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2019-06-13 13:25:42,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x744586c0 2019-06-13 13:25:42,694 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2019-06-13 13:25:42,695 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,696 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74451dd0 2019-06-13 13:25:42,697 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2019-06-13 13:25:42,698 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x744ede78 [0x1a1dc60], "ALSA: default device") 2019-06-13 13:25:42,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2019-06-13 13:25:42,700 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,701 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74451da0 2019-06-13 13:25:42,701 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2019-06-13 13:25:42,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x744ede78 [0x1a1dc60], "ALSA: default device") 2019-06-13 13:25:42,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2019-06-13 13:25:42,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74458670 2019-06-13 13:25:42,703 [INFO] [doorpi.sipphone.from_linphone] found 1 possible sounddevices: 2019-06-13 13:25:42,704 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2019-06-13 13:25:42,704 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-06-13 13:25:42,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74458670 2019-06-13 13:25:42,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x744ede78 [0x1a1dc60], "ALSA: default device") 2019-06-13 13:25:42,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d769c 2019-06-13 13:25:42,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x744ede78 [0x1a1dc60], "ALSA: default device") 2019-06-13 13:25:42,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2019-06-13 13:25:42,709 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2019-06-13 13:25:42,710 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-06-13 13:25:42,711 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74451e60 2019-06-13 13:25:42,714 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2019-06-13 13:25:42,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74451e90 2019-06-13 13:25:42,716 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2019-06-13 13:25:42,719 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2019-06-13 13:25:42,721 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,723 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fce28) 2019-06-13 13:25:42,725 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c410 2019-06-13 13:25:42,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,727 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c410 2019-06-13 13:25:42,727 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c410 2019-06-13 13:25:42,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fce98) 2019-06-13 13:25:42,729 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c420 2019-06-13 13:25:42,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c420 2019-06-13 13:25:42,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c420 2019-06-13 13:25:42,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fcf00) 2019-06-13 13:25:42,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c430 2019-06-13 13:25:42,733 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c430 2019-06-13 13:25:42,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c430 2019-06-13 13:25:42,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fcf68) 2019-06-13 13:25:42,735 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c440 2019-06-13 13:25:42,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c440 2019-06-13 13:25:42,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c440 2019-06-13 13:25:42,738 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fcfc0) 2019-06-13 13:25:42,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c450 2019-06-13 13:25:42,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c450 2019-06-13 13:25:42,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c450 2019-06-13 13:25:42,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fd018) 2019-06-13 13:25:42,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c460 2019-06-13 13:25:42,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c460 2019-06-13 13:25:42,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c460 2019-06-13 13:25:42,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fd070) 2019-06-13 13:25:42,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c470 2019-06-13 13:25:42,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c470 2019-06-13 13:25:42,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c470 2019-06-13 13:25:42,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fd0c8) 2019-06-13 13:25:42,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c480 2019-06-13 13:25:42,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c480 2019-06-13 13:25:42,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c480 2019-06-13 13:25:42,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fd130) 2019-06-13 13:25:42,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c490 2019-06-13 13:25:42,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c490 2019-06-13 13:25:42,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c490 2019-06-13 13:25:42,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fd188) 2019-06-13 13:25:42,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c4a0 2019-06-13 13:25:42,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c4a0 2019-06-13 13:25:42,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c4a0 2019-06-13 13:25:42,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x74458760 2019-06-13 13:25:42,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c410 [0x19fce28]) 2019-06-13 13:25:42,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c410 [0x19fce28]) 2019-06-13 13:25:42,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,767 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2019-06-13 13:25:42,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c410 [0x19fce28], 0x2d7690 [0]) 2019-06-13 13:25:42,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c420 [0x19fce98]) 2019-06-13 13:25:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457560 2019-06-13 13:25:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c420 [0x19fce98]) 2019-06-13 13:25:42,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457560 2019-06-13 13:25:42,770 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2019-06-13 13:25:42,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c420 [0x19fce98], 0x2d7690 [0]) 2019-06-13 13:25:42,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c430 [0x19fcf00]) 2019-06-13 13:25:42,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c430 [0x19fcf00]) 2019-06-13 13:25:42,776 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,777 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2019-06-13 13:25:42,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c430 [0x19fcf00], 0x2d7690 [0]) 2019-06-13 13:25:42,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,781 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c440 [0x19fcf68]) 2019-06-13 13:25:42,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457560 2019-06-13 13:25:42,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c440 [0x19fcf68]) 2019-06-13 13:25:42,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457560 2019-06-13 13:25:42,788 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2019-06-13 13:25:42,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c440 [0x19fcf68], 0x2d769c [1]) 2019-06-13 13:25:42,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c450 [0x19fcfc0]) 2019-06-13 13:25:42,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c450 [0x19fcfc0]) 2019-06-13 13:25:42,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,794 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2019-06-13 13:25:42,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c450 [0x19fcfc0], 0x2d769c [1]) 2019-06-13 13:25:42,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c460 [0x19fd018]) 2019-06-13 13:25:42,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c460 [0x19fd018]) 2019-06-13 13:25:42,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,799 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2019-06-13 13:25:42,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c460 [0x19fd018], 0x2d7690 [0]) 2019-06-13 13:25:42,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c470 [0x19fd070]) 2019-06-13 13:25:42,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c470 [0x19fd070]) 2019-06-13 13:25:42,803 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457280 2019-06-13 13:25:42,803 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2019-06-13 13:25:42,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c470 [0x19fd070], 0x2d7690 [0]) 2019-06-13 13:25:42,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c480 [0x19fd0c8]) 2019-06-13 13:25:42,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457560 2019-06-13 13:25:42,806 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c480 [0x19fd0c8]) 2019-06-13 13:25:42,806 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74457560 2019-06-13 13:25:42,807 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2019-06-13 13:25:42,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c480 [0x19fd0c8], 0x2d7690 [0]) 2019-06-13 13:25:42,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c490 [0x19fd130]) 2019-06-13 13:25:42,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,810 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c490 [0x19fd130]) 2019-06-13 13:25:42,810 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,811 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2019-06-13 13:25:42,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c490 [0x19fd130], 0x2d7690 [0]) 2019-06-13 13:25:42,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c4a0 [0x19fd188]) 2019-06-13 13:25:42,813 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,813 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c4a0 [0x19fd188]) 2019-06-13 13:25:42,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,815 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2019-06-13 13:25:42,815 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c4a0 [0x19fd188], 0x2d7690 [0]) 2019-06-13 13:25:42,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x744586c0 2019-06-13 13:25:42,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,819 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x744586c0 2019-06-13 13:25:42,819 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2019-06-13 13:25:42,820 [DEBUG] [doorpi.sipphone.from_linphone] | name 2019-06-13 13:25:42,821 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-06-13 13:25:42,821 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,822 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x744586c0 2019-06-13 13:25:42,822 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2019-06-13 13:25:42,823 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2019-06-13 13:25:42,823 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-06-13 13:25:42,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x744586c0 2019-06-13 13:25:42,824 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2019-06-13 13:25:42,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x744586c0 2019-06-13 13:25:42,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x744ede78 [0x1a1dc60], 0x2d769c [1]) 2019-06-13 13:25:42,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2019-06-13 13:25:42,827 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x744ede78 [0x1a1dc60], "V4L2: /dev/video0") 2019-06-13 13:25:42,828 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2019-06-13 13:25:42,829 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2019-06-13 13:25:42,830 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x744ede78 [0x1a1dc60], "vga") 2019-06-13 13:25:42,831 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2019-06-13 13:25:42,831 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,832 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x74458818 2019-06-13 13:25:42,833 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2019-06-13 13:25:42,834 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2019-06-13 13:25:42,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,836 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x19fcd88) 2019-06-13 13:25:42,838 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-06-13 13:25:42,840 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c7c4b0 2019-06-13 13:25:42,842 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-06-13 13:25:42,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c7c4b0 2019-06-13 13:25:42,845 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c7c4b0 2019-06-13 13:25:42,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x74458710 2019-06-13 13:25:42,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c4b0 [0x19fcd88]) 2019-06-13 13:25:42,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2d769c 2019-06-13 13:25:42,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c7c4b0 [0x19fcd88]) 2019-06-13 13:25:42,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x744ede90 2019-06-13 13:25:42,860 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2019-06-13 13:25:42,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x744ede78 [0x1a1dc60], 0x76c7c4b0 [0x19fcd88], 0x2d769c [1]) 2019-06-13 13:25:42,862 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x18848cc 2019-06-13 13:25:42,862 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.188.1 2019-06-13 13:25:42,863 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns doordoor 2019-06-13 13:25:42,864 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: doordoor) returns ******* 2019-06-13 13:25:42,865 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.188.1) returns 192.168.188.1 2019-06-13 13:25:42,866 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2019-06-13 13:25:42,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x744ede78 [0x1a1dc60]) 2019-06-13 13:25:42,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x1a32e70) 2019-06-13 13:25:42,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2019-06-13 13:25:42,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76c7c4c0 2019-06-13 13:25:42,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2019-06-13 13:25:42,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76c7c4c0 2019-06-13 13:25:42,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76c7c4c0 2019-06-13 13:25:42,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76c7c4c0 2019-06-13 13:25:42,872 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns Tuer 2019-06-13 13:25:42,873 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("Tuer ") 2019-06-13 13:25:42,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1a31470) 2019-06-13 13:25:42,876 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2019-06-13 13:25:42,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76c7c4d0 2019-06-13 13:25:42,878 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2019-06-13 13:25:42,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76c7c4d0 2019-06-13 13:25:42,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76c7c4d0 2019-06-13 13:25:42,881 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76c7c4d0 2019-06-13 13:25:42,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76c7c4c0 [0x1a32e70], 0x76c7c4d0 [0x1a31470]) 2019-06-13 13:25:42,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2019-06-13 13:25:42,883 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76c7c4c0 [0x1a32e70], "sip:192.168.188.1") 2019-06-13 13:25:42,884 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2019-06-13 13:25:42,884 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76c7c4c0 [0x1a32e70], 0x2d769c [1]) 2019-06-13 13:25:42,884 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2019-06-13 13:25:42,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x744ede78 [0x1a1dc60], 0x76c7c4c0 [0x1a32e70]) 2019-06-13 13:25:42,887 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x18848cc 2019-06-13 13:25:42,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x744ede78 [0x1a1dc60], 0x76c7c4c0 [0x1a32e70]) 2019-06-13 13:25:42,889 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2019-06-13 13:25:42,890 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x744ede78 [0x1a1dc60], "doordoor", "(null)", "pidoorpi1212", "(null)", "(null)", "192.168.188.1") 2019-06-13 13:25:42,891 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x1a2e180) 2019-06-13 13:25:42,892 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2019-06-13 13:25:42,893 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76c7c4e0 2019-06-13 13:25:42,894 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2019-06-13 13:25:42,895 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76c7c4e0 2019-06-13 13:25:42,897 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76c7c4e0 2019-06-13 13:25:42,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76c7c4e0 2019-06-13 13:25:42,902 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x744ede78 [0x1a1dc60], 0x76c7c4e0 [0x1a2e180]) 2019-06-13 13:25:42,904 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2019-06-13 13:25:42,904 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2019-06-13 13:25:42,905 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2019-06-13 13:25:42,906 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2019-06-13 13:25:42,907 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2019-06-13 13:25:42,907 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2019-06-13 13:25:42,908 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2019-06-13 13:25:42,909 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2019-06-13 13:25:42,910 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2019-06-13 13:25:42,911 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_InputPins (default: ) returns call:**9 2019-06-13 13:25:42,913 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2019-06-13 13:25:42,914 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**9'} was added to new evententry OnKeyPressed_onboardpins.7 2019-06-13 13:25:42,915 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['0'] 2019-06-13 13:25:42,916 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section DTMF (default: ) returns out:Tueroeffner,1,0,3 2019-06-13 13:25:42,917 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2019-06-13 13:25:42,919 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2019-06-13 13:25:42,920 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnDTMF_0 2019-06-13 13:25:42,921 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2019-06-13 13:25:42,922 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2019-06-13 13:25:42,924 [DEBUG] [doorpi.action.handler] [BFI07P] fire for event OnStartup this actions [] 2019-06-13 13:25:42,925 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2019-06-13 13:25:42,925 [TRACE] [doorpi.action.handler] [BFI07P] try to fire action SleepAction with args (1.0,) and kwargs {} 2019-06-13 13:25:42,926 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2019-06-13 13:25:43,929 [TRACE] [doorpi.action.handler] [BFI07P] finished fire_event for event_name OnStartup 2019-06-13 13:25:43,929 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2019-06-13 13:25:43,930 [INFO] [doorpi.doorpi] DoorPi started successfully 2019-06-13 13:25:43,931 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2019-06-13 13:25:43,931 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2019-06-13 13:25:43,932 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi.fritz.box/ 2019-06-13 13:25:43,932 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.188.1 2019-06-13 13:25:43,933 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2019-06-13 13:25:43,933 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.188.23 2019-06-13 13:25:43,933 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2019-06-13 13:25:43,934 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2019-06-13 13:25:43,934 [INFO] [doorpi.sipphone.from_linphone] 192.168.188.1 2019-06-13 13:25:43,935 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2019-06-13 13:25:43,935 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2019-06-13 13:25:43,936 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x1a32e70] about to register (LinphoneCore version: 3.9.0) 2019-06-13 13:25:43,940 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2019-06-13 13:25:43,941 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0]: starting resolution of 192.168.188.1 2019-06-13 13:25:43,941 [INFO] [doorpi.sipphone.from_linphone] channel 0x1a394c0: state RES_IN_PROGRESS 2019-06-13 13:25:43,941 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1a2e9e8] channel state changed to [RES_IN_PROGRESS] 2019-06-13 13:25:43,941 [INFO] [doorpi.sipphone.from_linphone] channel 0x1a394c0: state RES_DONE 2019-06-13 13:25:43,942 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1a2e9e8] channel state changed to [RES_DONE] 2019-06-13 13:25:43,942 [INFO] [doorpi.sipphone.from_linphone] channel 0x1a394c0: state CONNECTING 2019-06-13 13:25:43,942 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1a2e9e8] channel state changed to [CONNECTING] 2019-06-13 13:25:43,943 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.188.1:5060] 2019-06-13 13:25:43,943 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2019-06-13 13:25:43,944 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.188.23:5060 2019-06-13 13:25:43,944 [INFO] [doorpi.sipphone.from_linphone] channel 0x1a394c0: state READY 2019-06-13 13:25:43,944 [INFO] [doorpi.sipphone.from_linphone] transaction [0x1a2e9e8] channel state changed to [READY] 2019-06-13 13:25:43,945 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1a2e9e8], from state [INIT] to [TRYING] 2019-06-13 13:25:43,945 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0]: message sent to [UDP://192.168.188.1:5060], size: [525] bytes REGISTER sip:192.168.188.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.188.23:5060;branch=z9hG4bK.HNbvBmbrb;rport From: "Tuer" ;tag=4aX5ffwAb To: "Tuer" CSeq: 20 REGISTER Call-ID: ry5mySIblc 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) 2019-06-13 13:25:43,946 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2019-06-13 13:25:43,946 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1a31948] takes ownership of transaction [0x1a2e9e8] 2019-06-13 13:25:43,946 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1a32e70] for identity ["Tuer" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2019-06-13 13:25:43,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1a1dc60, 0x1a32e70, 1, "Registration in progress") 2019-06-13 13:25:43,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2019-06-13 13:25:43,947 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1a1dc60] notifying [registration_state_changed] 2019-06-13 13:25:43,998 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x1a1dc60, 0x1) 2019-06-13 13:25:43,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2019-06-13 13:25:44,000 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1a1dc60] notifying [network_reachable] 2019-06-13 13:25:44,001 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0]: received [372] new bytes from [UDP://192.168.188.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.188.23:5060;branch=z9hG4bK.HNbvBmbrb;rport=5060 From: "Tuer" ;tag=4aX5ffwAb To: "Tuer" ;tag=386B2EB7CFEBD958 Call-ID: ry5mySIblc CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="15AF917538BB63BD" User-Agent: FRITZ!OS Content-Length: 0 2019-06-13 13:25:44,006 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0] [372] bytes parsed 2019-06-13 13:25:44,007 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0]: discovered public ip and port are [192.168.188.23:5060] 2019-06-13 13:25:44,008 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2019-06-13 13:25:44,009 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1a2e9e8], from state [TRYING] to [COMPLETED] 2019-06-13 13:25:44,010 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doordoor, realm= 2019-06-13 13:25:44,011 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [doordoor] realm [fritz.box] 2019-06-13 13:25:44,012 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1a2e500], from state [INIT] to [TRYING] 2019-06-13 13:25:44,015 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0]: message sent to [UDP://192.168.188.1:5060], size: [685] bytes REGISTER sip:192.168.188.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.188.23:5060;branch=z9hG4bK.rzJfMo0KW;rport From: "Tuer" ;tag=4aX5ffwAb To: "Tuer" CSeq: 21 REGISTER Call-ID: ry5mySIblc 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="15AF917538BB63BD", username="doordoor", uri="sip:192.168.188.1", response="8d0ca1a2eb6d3ec3ee74d31d26259600" 2019-06-13 13:25:44,016 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.188.1 2019-06-13 13:25:44,017 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2019-06-13 13:25:44,069 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0]: received [689] new bytes from [UDP://192.168.188.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.188.23:5060;branch=z9hG4bK.rzJfMo0KW;rport=5060 From: "Tuer" ;tag=4aX5ffwAb To: "Tuer" ;tag=8DF74A92552C9870 Call-ID: ry5mySIblc CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box Fon WLAN 7390 84.06.83 (Mar 8 2017) 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 2019-06-13 13:25:44,085 [INFO] [doorpi.sipphone.from_linphone] channel [0x1a394c0] [689] bytes parsed 2019-06-13 13:25:44,087 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2019-06-13 13:25:44,088 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1a2e500], from state [TRYING] to [COMPLETED] 2019-06-13 13:25:44,090 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x1a31948]: has no contact for request [0x1aa61a0]. 2019-06-13 13:25:44,091 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2019-06-13 13:25:44,092 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.188.1] 2019-06-13 13:25:44,093 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1a32e70] for identity ["Tuer" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2019-06-13 13:25:44,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1a1dc60, 0x1a32e70, 2, "Registration successful") 2019-06-13 13:25:44,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2019-06-13 13:25:44,096 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1a1dc60] notifying [registration_state_changed] 2019-06-13 13:25:49,021 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1a2e9e8], from state [COMPLETED] to [TERMINATED] 2019-06-13 13:25:49,022 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1a2e9e8] terminated 2019-06-13 13:25:49,125 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1a2e500], from state [COMPLETED] to [TERMINATED] 2019-06-13 13:25:49,126 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1a2e500] terminated 2019-06-13 13:27:31,048 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] no header Authorization object ('authorization') 2019-06-13 13:27:31,053 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /favicon.ico 2019-06-13 13:27:32,369 [INFO] [doorpi.status.webserver_lib.request_handler_static_functions] Missing login file: login.html 2019-06-13 13:27:32,369 [TRACE] [doorpi.status.webserver_lib.request_handler_static_functions] use onlinefallback - local file /usr/local/etc/DoorPi/../DoorPiWeb/favicon.ico not found 2019-06-13 13:27:32,370 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] ('GET /dashboard/pages/index.html HTTP/1.1', '401', '-') 2019-06-13 13:27:32,390 [ERROR] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] (404, "[Errno 2] No such file or directory: '/usr/local/etc/DoorPi/../DoorPiWeb/favicon.ico' - HTTP Error 404: Not Found") 2019-06-13 13:27:32,390 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] ('GET /favicon.ico HTTP/1.1', '404', '-') 2019-06-13 13:27:32,392 [ERROR] [doorpi.status.webserver_lib.request_handler_static_functions] 'NoneType' object is not iterable Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/status/webserver_lib/request_handler.py", line 195, in real_resource return self.return_file_content(path) File "/usr/local/lib/python2.7/dist-packages/doorpi/status/webserver_lib/request_handler.py", line 280, in return_file_content content, mime = self.get_file_content(path) TypeError: 'NoneType' object is not iterable 2019-06-13 13:27:32,421 [ERROR] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] (500, "'NoneType' object is not iterable") 2019-06-13 13:27:32,423 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] ('GET /favicon.ico HTTP/1.1', '500', '-') 2019-06-13 13:27:47,431 [DEBUG] [doorpi.status.webserver] no session handler - creating it now 2019-06-13 13:27:47,432 [TRACE] [doorpi.action.handler] register Event WebServerCreateNewSession from doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,433 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.session_handler was added 2019-06-13 13:27:47,434 [TRACE] [doorpi.action.handler] added event_name WebServerCreateNewSession and registered source doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,434 [TRACE] [doorpi.action.handler] register Event WebServerAuthUnknownUser from doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,435 [TRACE] [doorpi.action.handler] added event_name WebServerAuthUnknownUser and registered source doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,435 [TRACE] [doorpi.action.handler] register Event WebServerAuthWrongPassword from doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,436 [TRACE] [doorpi.action.handler] added event_name WebServerAuthWrongPassword and registered source doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,436 [TRACE] [doorpi.status.webserver_lib.session_handler] no session with session id door found 2019-06-13 13:27:47,436 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['visitor', 'door'] 2019-06-13 13:27:47,437 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2019-06-13 13:27:47,437 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['guests'] 2019-06-13 13:27:47,438 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators', 'guests'] 2019-06-13 13:27:47,438 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['visitor', 'door'] 2019-06-13 13:27:47,438 [TRACE] [doorpi.conf.config_object] get_string for key door in section User (default: ) returns ******* 2019-06-13 13:27:47,439 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['door'] 2019-06-13 13:27:47,439 [TRACE] [doorpi.conf.config_object] get_list for key guests in section Group (default: []) returns ['visitor'] 2019-06-13 13:27:47,440 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['dashboard', 'status', 'config'] 2019-06-13 13:27:47,440 [TRACE] [doorpi.conf.config_object] get_keys for section AREA_dashboard returns ['/dashboard/pages/.*html'] 2019-06-13 13:27:47,441 [TRACE] [doorpi.conf.config_object] get_keys for section AREA_dashboard returns ['/dashboard/pages/.*html'] 2019-06-13 13:27:47,442 [TRACE] [doorpi.conf.config_object] get_keys for section AREA_status returns ['/mirror', '/status'] 2019-06-13 13:27:47,442 [TRACE] [doorpi.conf.config_object] get_keys for section AREA_status returns ['/mirror', '/status'] 2019-06-13 13:27:47,443 [TRACE] [doorpi.conf.config_object] get_keys for section AREA_config returns ['/control/config_value_get', '/control/config_save', '/control/config_value_set', '/control/config_get_configfile', '/control/config_value_delete'] 2019-06-13 13:27:47,443 [TRACE] [doorpi.conf.config_object] get_keys for section AREA_config returns ['/control/config_value_get', '/control/config_save', '/control/config_value_set', '/control/config_get_configfile', '/control/config_value_delete'] 2019-06-13 13:27:47,444 [TRACE] [doorpi.action.handler] fire Event WebServerCreateNewSession from doorpi.status.webserver_lib.session_handler asyncron 2019-06-13 13:27:47,456 [INFO] [doorpi.status.webserver_lib.request_handler_static_functions] user door has write permissions: /dashboard/pages/index.html 2019-06-13 13:27:47,458 [DEBUG] [doorpi.action.handler] no actions for event WebServerCreateNewSession - skip fire_event WebServerCreateNewSession from doorpi.status.webserver_lib.session_handler 2019-06-13 13:27:47,459 [TRACE] [doorpi.status.webserver_lib.request_handler_static_functions] use onlinefallback - local file /usr/local/etc/DoorPi/../DoorPiWeb/dashboard/pages/index.html not found 2019-06-13 13:27:47,474 [ERROR] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] (404, "[Errno 2] No such file or directory: '/usr/local/etc/DoorPi/../DoorPiWeb/dashboard/pages/index.html' - HTTP Error 404: Not Found") 2019-06-13 13:27:47,475 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] ('GET /dashboard/pages/index.html HTTP/1.1', '404', '-') 2019-06-13 13:27:47,476 [ERROR] [doorpi.status.webserver_lib.request_handler_static_functions] 'NoneType' object is not iterable Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/status/webserver_lib/request_handler.py", line 195, in real_resource return self.return_file_content(path) File "/usr/local/lib/python2.7/dist-packages/doorpi/status/webserver_lib/request_handler.py", line 280, in return_file_content content, mime = self.get_file_content(path) TypeError: 'NoneType' object is not iterable 2019-06-13 13:27:47,477 [ERROR] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] (500, "'NoneType' object is not iterable") 2019-06-13 13:27:47,477 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.188.23] ('GET /dashboard/pages/index.html HTTP/1.1', '500', '-')