2017-05-19 20:13:23,603 [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 2017-05-19 20:13:23,606 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2017-05-19 20:13:23,609 [DEBUG] [doorpi.doorpi] run 2017-05-19 20:13:23,611 [DEBUG] [doorpi.doorpi] prepare 2017-05-19 20:13:23,612 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2017-05-19 20:13:23,627 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2017-05-19 20:13:23,656 [DEBUG] [doorpi.conf.config_object] __init__ 2017-05-19 20:13:23,658 [TRACE] [doorpi.conf.config_object] get_from_config 2017-05-19 20:13:23,676 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2017-05-19 20:13:23,678 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2017-05-19 20:13:23,682 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2017-05-19 20:13:23,691 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2017-05-19 20:13:23,693 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2017-05-19 20:13:23,695 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2017-05-19 20:13:23,697 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2017-05-19 20:13:23,699 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2017-05-19 20:13:23,700 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2017-05-19 20:13:23,702 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2017-05-19 20:13:23,704 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2017-05-19 20:13:23,706 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2017-05-19 20:13:23,707 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2017-05-19 20:13:23,709 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2017-05-19 20:13:23,711 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2017-05-19 20:13:23,713 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2017-05-19 20:13:23,718 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2017-05-19 20:13:23,722 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2017-05-19 20:13:23,736 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2017-05-19 20:13:23,738 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2017-05-19 20:13:23,740 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2017-05-19 20:13:23,742 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2017-05-19 20:13:23,750 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2017-05-19 20:13:23,752 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2017-05-19 20:13:23,754 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2017-05-19 20:13:23,756 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2017-05-19 20:13:23,757 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2017-05-19 20:13:23,759 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2017-05-19 20:13:23,761 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2017-05-19 20:13:23,766 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2017-05-19 20:13:23,768 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2017-05-19 20:13:23,772 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2017-05-19 20:13:23,774 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2017-05-19 20:13:23,778 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2017-05-19 20:13:23,780 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2017-05-19 20:13:23,785 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2017-05-19 20:13:23,787 [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 2017-05-19 20:13:23,791 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2017-05-19 20:13:23,793 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2017-05-19 20:13:23,795 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2017-05-19 20:13:23,797 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2017-05-19 20:13:23,798 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['pi'] 2017-05-19 20:13:23,800 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnTimeMinute59', 'AREA_public', 'Group', 'ReadPermission', 'AdminNumbers', 'DTMF', 'SIP-Phone', 'WritePermission', 'User', 'DoorPiWeb', 'DoorPi'] 2017-05-19 20:13:23,803 [ERROR] [doorpi.status.webserver] no ReadPermission found 2017-05-19 20:13:23,805 [ERROR] [doorpi.status.webserver] no groups in configfile! 2017-05-19 20:13:23,807 [ERROR] [doorpi.status.webserver] no WritePermission found 2017-05-19 20:13:23,809 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2017-05-19 20:13:23,811 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2017-05-19 20:13:23,813 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2017-05-19 20:13:23,817 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,819 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2017-05-19 20:13:23,821 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,823 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,829 [DEBUG] [doorpi.action.handler] [54HQVR] fire for event OnWebServerStart this actions [] 2017-05-19 20:13:23,831 [TRACE] [doorpi.action.handler] [54HQVR] try to fire action WebServerStartupAction with args () and kwargs {} 2017-05-19 20:13:23,833 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2017-05-19 20:13:23,825 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,837 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,839 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,841 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,842 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,844 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,846 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,848 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,850 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,851 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,853 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,855 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,857 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 20:13:23,859 [WARNING] [root] section keyboards not found in configfile 2017-05-19 20:13:23,861 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns [] 2017-05-19 20:13:23,863 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode with dummy keyboard 2017-05-19 20:13:23,864 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'dummy' to handler 2017-05-19 20:13:23,867 [DEBUG] [doorpi.conf.config_object] create new key dummy in section keyboards with value 'dummy' 2017-05-19 20:13:23,869 [TRACE] [doorpi.conf.config_object] get_string for key dummy in section keyboards (default: dummy) returns dummy 2017-05-19 20:13:23,870 [WARNING] [root] section dummy_InputPins not found in configfile 2017-05-19 20:13:23,872 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2017-05-19 20:13:23,874 [WARNING] [root] section dummy_OutputPins not found in configfile 2017-05-19 20:13:23,876 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2017-05-19 20:13:23,878 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section dummy_keyboard (default: 2000) returns 2000.0 2017-05-19 20:13:23,881 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section dummy_keyboard (default: 0) returns 0 2017-05-19 20:13:23,883 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section dummy_keyboard (default: True) returns True 2017-05-19 20:13:23,887 [DEBUG] [doorpi.keyboard.from_dummy] doorpi.keyboard.from_dummy loaded 2017-05-19 20:13:23,890 [DEBUG] [doorpi.keyboard.from_dummy] __init__(input_pins = [], output_pins = [], bouncetime = 2000.0, polarity = 0) 2017-05-19 20:13:23,892 [WARNING] [root] section dummy_InputPins not found in configfile 2017-05-19 20:13:23,894 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2017-05-19 20:13:23,896 [WARNING] [root] section dummy_OutputPins not found in configfile 2017-05-19 20:13:23,898 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2017-05-19 20:13:23,900 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2017-05-19 20:13:23,902 [WARNING] [root] section dummy_OutputPins not found in configfile 2017-05-19 20:13:23,903 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2017-05-19 20:13:23,909 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2017-05-19 20:13:23,915 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2017-05-19 20:13:23,920 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2017-05-19 20:13:23,925 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2017-05-19 20:13:24,038 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2017-05-19 20:13:24,045 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2017-05-19 20:13:24,053 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2017-05-19 20:13:24,225 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2017-05-19 20:13:24,245 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2017-05-19 20:13:24,247 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2017-05-19 20:13:24,252 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2017-05-19 20:13:24,259 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2017-05-19 20:13:24,265 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2017-05-19 20:13:24,272 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2017-05-19 20:13:24,277 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2017-05-19 20:13:24,279 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2017-05-19 20:13:24,281 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,283 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2017-05-19 20:13:24,285 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,287 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,288 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,290 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,292 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,294 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,296 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,298 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,299 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,301 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,303 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,305 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,308 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,309 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,311 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,313 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,315 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,317 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,319 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,320 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,322 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,324 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2017-05-19 20:13:24,326 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2017-05-19 20:13:24,328 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2017-05-19 20:13:24,330 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2017-05-19 20:13:24,332 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,334 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2017-05-19 20:13:24,336 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,338 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,339 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,341 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,343 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,345 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,347 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,349 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,351 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,352 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,354 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,356 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,358 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,360 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,361 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,363 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,365 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,367 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,369 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,371 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,373 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,375 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,376 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,378 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2017-05-19 20:13:24,380 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,382 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2017-05-19 20:13:24,384 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,386 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,388 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,390 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,391 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,393 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,395 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,397 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,399 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,400 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,402 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,404 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,406 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,408 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,410 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,412 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,413 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,415 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,417 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:24,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2017-05-19 20:13:24,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb4f1a938 2017-05-19 20:13:24,429 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2017-05-19 20:13:24,428 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:24,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb4f1a938 2017-05-19 20:13:24,452 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb4f36c90, "(null)", "(null)") 2017-05-19 20:13:24,454 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2017-05-19 20:13:24,456 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2017-05-19 20:13:24,458 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2017-05-19 20:13:24,461 [INFO] [doorpi.sipphone.from_linphone] srtp init 2017-05-19 20:13:24,491 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2017-05-19 20:13:25,059 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2017-05-19 20:13:25,103 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2017-05-19 20:13:25,113 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2017-05-19 20:13:25,175 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2017-05-19 20:13:25,216 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC2D0p' failed (-2) 2017-05-19 20:13:25,219 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_dmix.c:1022 - unable to open slave 2017-05-19 20:13:25,223 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB PnP Sound Device' added 2017-05-19 20:13:25,225 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2017-05-19 20:13:25,228 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2017-05-19 20:13:25,230 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2017-05-19 20:13:25,232 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2017-05-19 20:13:25,234 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2017-05-19 20:13:25,237 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2017-05-19 20:13:25,240 [INFO] [doorpi.sipphone.from_linphone] Vtable [0xafd558] registered on core [0xafc4c8] 2017-05-19 20:13:25,242 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xafd558, 1, "Starting up") 2017-05-19 20:13:25,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2017-05-19 20:13:25,246 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [global_state_changed] 2017-05-19 20:13:25,248 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2017-05-19 20:13:25,250 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2017-05-19 20:13:25,252 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2017-05-19 20:13:25,254 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2017-05-19 20:13:25,256 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2017-05-19 20:13:25,258 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 20:13:25,260 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 20:13:25,262 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2017-05-19 20:13:25,264 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2017-05-19 20:13:25,267 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2017-05-19 20:13:25,269 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2017-05-19 20:13:25,271 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2017-05-19 20:13:25,273 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2017-05-19 20:13:25,276 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2017-05-19 20:13:25,289 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2017-05-19 20:13:25,291 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2017-05-19 20:13:25,293 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2017-05-19 20:13:25,295 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2017-05-19 20:13:25,297 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2017-05-19 20:13:25,299 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2017-05-19 20:13:25,301 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2017-05-19 20:13:25,303 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2017-05-19 20:13:25,305 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2017-05-19 20:13:25,307 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 20:13:25,309 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 20:13:25,311 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 20:13:25,313 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 20:13:25,315 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 20:13:25,324 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 20:13:25,326 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 20:13:25,328 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 20:13:25,330 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 20:13:25,332 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 20:13:25,334 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2017-05-19 20:13:25,336 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2017-05-19 20:13:25,338 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2017-05-19 20:13:25,340 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 20:13:25,342 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 20:13:25,344 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 20:13:25,347 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 20:13:25,349 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 20:13:25,350 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 20:13:25,352 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2017-05-19 20:13:25,355 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2017-05-19 20:13:25,357 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2017-05-19 20:13:25,360 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2017-05-19 20:13:25,362 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2017-05-19 20:13:25,364 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2017-05-19 20:13:25,366 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2017-05-19 20:13:25,368 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2017-05-19 20:13:25,370 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2017-05-19 20:13:25,372 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2017-05-19 20:13:25,374 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2017-05-19 20:13:25,376 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2017-05-19 20:13:25,378 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2017-05-19 20:13:25,380 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2017-05-19 20:13:25,382 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2017-05-19 20:13:25,384 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2017-05-19 20:13:25,387 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2017-05-19 20:13:25,389 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2017-05-19 20:13:25,391 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2017-05-19 20:13:25,393 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2017-05-19 20:13:25,395 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2017-05-19 20:13:25,408 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2017-05-19 20:13:25,410 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2017-05-19 20:13:25,412 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2017-05-19 20:13:25,414 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2017-05-19 20:13:25,417 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2017-05-19 20:13:25,419 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2017-05-19 20:13:25,421 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2017-05-19 20:13:25,423 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2017-05-19 20:13:25,425 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2017-05-19 20:13:25,427 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2017-05-19 20:13:25,429 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2017-05-19 20:13:25,431 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2017-05-19 20:13:25,433 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2017-05-19 20:13:25,437 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2017-05-19 20:13:25,444 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2017-05-19 20:13:25,446 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2017-05-19 20:13:25,448 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2017-05-19 20:13:25,450 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2017-05-19 20:13:25,452 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2017-05-19 20:13:25,454 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2017-05-19 20:13:25,457 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-05-19 20:13:25,459 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,461 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,463 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,465 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0xafd558, 2, "(null)") 2017-05-19 20:13:25,467 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2017-05-19 20:13:25,469 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [configuring_status] 2017-05-19 20:13:25,472 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2017-05-19 20:13:25,474 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2017-05-19 20:13:25,476 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2017-05-19 20:13:25,479 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2017-05-19 20:13:25,481 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2017-05-19 20:13:25,483 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-05-19 20:13:25,485 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-05-19 20:13:25,487 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2017-05-19 20:13:25,489 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2017-05-19 20:13:25,491 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2017-05-19 20:13:25,493 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,495 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,497 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,499 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,501 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2017-05-19 20:13:25,503 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2017-05-19 20:13:25,505 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2017-05-19 20:13:25,507 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,510 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,512 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2017-05-19 20:13:25,514 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-05-19 20:13:25,526 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,529 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2017-05-19 20:13:25,532 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xafce40] on [sip:0.0.0.0:5060;transport=UDP] 2017-05-19 20:13:25,534 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xafdfa0] on [sip:0.0.0.0:5060;transport=TCP] 2017-05-19 20:13:25,537 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xafe140] on [sip:0.0.0.0:-1;transport=TLS] 2017-05-19 20:13:25,539 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 39259 2017-05-19 20:13:25,541 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,543 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,551 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xafd558, 2, "Ready") 2017-05-19 20:13:25,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2017-05-19 20:13:25,561 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [global_state_changed] 2017-05-19 20:13:25,564 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb4f1a938 2017-05-19 20:13:25,566 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2017-05-19 20:13:25,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb4f1a938 [0xafd558], "DoorPi ") 2017-05-19 20:13:25,572 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2017-05-19 20:13:25,574 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2017-05-19 20:13:25,578 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2017-05-19 20:13:25,581 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb4f1a938 [0xafd558], 2) 2017-05-19 20:13:25,582 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2017-05-19 20:13:25,584 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2017-05-19 20:13:25,589 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb4f1a938 [0xafd558], 0x2e0614 [0]) 2017-05-19 20:13:25,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2017-05-19 20:13:25,594 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2017-05-19 20:13:25,588 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2017-05-19 20:13:25,599 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2017-05-19 20:13:25,601 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2017-05-19 20:13:25,603 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb4f1a950 2017-05-19 20:13:25,605 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb4f1a938 [0xafd558], 0xb4f1a950 [0xb4f1a958]) 2017-05-19 20:13:25,607 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xafce40] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2017-05-19 20:13:25,610 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xafdfa0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2017-05-19 20:13:25,612 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xafe140] on [sip:0.0.0.0:39259;transport=TLS] destroyed 2017-05-19 20:13:25,614 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2017-05-19 20:13:25,616 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xb18e18] on [sip:0.0.0.0:5060;transport=UDP] 2017-05-19 20:13:25,619 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xb0be08] on [sip:0.0.0.0:5060;transport=TCP] 2017-05-19 20:13:25,621 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xb1a928] on [sip:0.0.0.0:-1;transport=TLS] 2017-05-19 20:13:25,624 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 39297 2017-05-19 20:13:25,626 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,628 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 20:13:25,630 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2017-05-19 20:13:25,632 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb4f1a950) 2017-05-19 20:13:25,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2017-05-19 20:13:25,647 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2017-05-19 20:13:25,650 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb4f1a938 [0xafd558], 0x2e0614 [0]) 2017-05-19 20:13:25,652 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2017-05-19 20:13:25,654 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2017-05-19 20:13:25,656 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb4f1a938 [0xafd558], "") 2017-05-19 20:13:25,659 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2017-05-19 20:13:25,661 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2017-05-19 20:13:25,664 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2017-05-19 20:13:25,667 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2017-05-19 20:13:25,669 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2017-05-19 20:13:25,671 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2017-05-19 20:13:25,673 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2017-05-19 20:13:25,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb4f1a938 [0xafd558], 0) 2017-05-19 20:13:25,684 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-05-19 20:13:25,686 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-05-19 20:13:25,690 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2017-05-19 20:13:25,692 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2017-05-19 20:13:25,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb4f1a938 [0xafd558], 120) 2017-05-19 20:13:25,696 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2017-05-19 20:13:25,698 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2017-05-19 20:13:25,700 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb4f1a938 [0xafd558], 15) 2017-05-19 20:13:25,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2017-05-19 20:13:25,704 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2017-05-19 20:13:25,710 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2017-05-19 20:13:25,712 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2017-05-19 20:13:25,719 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2017-05-19 20:13:25,721 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:25,723 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2017-05-19 20:13:25,725 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:25,727 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:25,729 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:25,731 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:25,733 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2017-05-19 20:13:25,735 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2017-05-19 20:13:25,737 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2017-05-19 20:13:25,739 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2017-05-19 20:13:25,741 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2017-05-19 20:13:25,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb4f1a938 [0xafd558], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2017-05-19 20:13:25,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2017-05-19 20:13:25,750 [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 /mnt/fritzbox/records/%Y-%m-%d_%H-%M-%S.wav 2017-05-19 20:13:25,752 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2017-05-19 20:13:25,754 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,769 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:25,772 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2017-05-19 20:13:25,774 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,776 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,778 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,780 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,781 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,783 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2017-05-19 20:13:25,786 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2017-05-19 20:13:25,788 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2017-05-19 20:13:25,790 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2017-05-19 20:13:25,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,805 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 20:13:25,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4e63d28 2017-05-19 20:13:25,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4f38950 2017-05-19 20:13:25,813 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2017-05-19 20:13:25,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb4f1a938 [0xafd558], "ALSA: USB PnP Sound Device") 2017-05-19 20:13:25,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2017-05-19 20:13:25,820 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,822 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4f38920 2017-05-19 20:13:25,824 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2017-05-19 20:13:25,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb4f1a938 [0xafd558], "ALSA: default device") 2017-05-19 20:13:25,828 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2017-05-19 20:13:25,830 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,832 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4e63d28 2017-05-19 20:13:25,834 [INFO] [doorpi.sipphone.from_linphone] found 4 possible sounddevices: 2017-05-19 20:13:25,837 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2017-05-19 20:13:25,838 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 20:13:25,840 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,843 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4e63d28 2017-05-19 20:13:25,845 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1a938 [0xafd558], "ALSA: default device") 2017-05-19 20:13:25,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-05-19 20:13:25,849 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1a938 [0xafd558], "ALSA: default device") 2017-05-19 20:13:25,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-05-19 20:13:25,852 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2017-05-19 20:13:25,854 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1a938 [0xafd558], "ALSA: bcm2835 ALSA") 2017-05-19 20:13:25,857 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2017-05-19 20:13:25,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1a938 [0xafd558], "ALSA: bcm2835 ALSA") 2017-05-19 20:13:25,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-05-19 20:13:25,862 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2017-05-19 20:13:25,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1a938 [0xafd558], "ALSA: C-Media USB Headphone Set") 2017-05-19 20:13:25,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-05-19 20:13:25,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1a938 [0xafd558], "ALSA: C-Media USB Headphone Set") 2017-05-19 20:13:25,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-05-19 20:13:25,872 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2017-05-19 20:13:25,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1a938 [0xafd558], "ALSA: USB PnP Sound Device") 2017-05-19 20:13:25,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-05-19 20:13:25,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1a938 [0xafd558], "ALSA: USB PnP Sound Device") 2017-05-19 20:13:25,881 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0614 2017-05-19 20:13:25,882 [DEBUG] [doorpi.sipphone.from_linphone] | X | O | ALSA: USB PnP Sound Device 2017-05-19 20:13:25,884 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 20:13:25,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4f38a70 2017-05-19 20:13:25,890 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB PnP Sound Device 2017-05-19 20:13:25,892 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,894 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4f38a40 2017-05-19 20:13:25,896 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2017-05-19 20:13:25,899 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2017-05-19 20:13:25,901 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:25,903 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafcc40) 2017-05-19 20:13:25,905 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,907 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac410 2017-05-19 20:13:25,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:25,911 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac410 2017-05-19 20:13:25,913 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac410 2017-05-19 20:13:25,915 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafccb0) 2017-05-19 20:13:25,928 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,930 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac420 2017-05-19 20:13:25,932 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:25,934 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac420 2017-05-19 20:13:25,936 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac420 2017-05-19 20:13:25,938 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafcd18) 2017-05-19 20:13:25,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,942 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac430 2017-05-19 20:13:25,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:25,946 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac430 2017-05-19 20:13:25,948 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac430 2017-05-19 20:13:25,950 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafd838) 2017-05-19 20:13:25,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac440 2017-05-19 20:13:25,963 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:25,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac440 2017-05-19 20:13:25,967 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac440 2017-05-19 20:13:25,969 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafd890) 2017-05-19 20:13:25,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,973 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac450 2017-05-19 20:13:25,975 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:25,977 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac450 2017-05-19 20:13:25,979 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac450 2017-05-19 20:13:25,981 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafd8e8) 2017-05-19 20:13:25,983 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac460 2017-05-19 20:13:25,987 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:25,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac460 2017-05-19 20:13:25,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac460 2017-05-19 20:13:25,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafd940) 2017-05-19 20:13:25,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:25,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac470 2017-05-19 20:13:25,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:26,001 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac470 2017-05-19 20:13:26,003 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac470 2017-05-19 20:13:26,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafd998) 2017-05-19 20:13:26,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:26,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac480 2017-05-19 20:13:26,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:26,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac480 2017-05-19 20:13:26,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac480 2017-05-19 20:13:26,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafda00) 2017-05-19 20:13:26,019 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:26,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac490 2017-05-19 20:13:26,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:26,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac490 2017-05-19 20:13:26,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac490 2017-05-19 20:13:26,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xb0bcc8) 2017-05-19 20:13:26,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:26,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac4a0 2017-05-19 20:13:26,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:26,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac4a0 2017-05-19 20:13:26,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac4a0 2017-05-19 20:13:26,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb4e63be8 2017-05-19 20:13:26,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac410 [0xafcc40]) 2017-05-19 20:13:26,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac410 [0xafcc40]) 2017-05-19 20:13:26,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,051 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2017-05-19 20:13:26,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac410 [0xafcc40], 0x2e0614 [0]) 2017-05-19 20:13:26,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac420 [0xafccb0]) 2017-05-19 20:13:26,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f37860 2017-05-19 20:13:26,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac420 [0xafccb0]) 2017-05-19 20:13:26,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f37860 2017-05-19 20:13:26,067 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-05-19 20:13:26,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac420 [0xafccb0], 0x2e0614 [0]) 2017-05-19 20:13:26,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac430 [0xafcd18]) 2017-05-19 20:13:26,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac430 [0xafcd18]) 2017-05-19 20:13:26,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,093 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-05-19 20:13:26,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac430 [0xafcd18], 0x2e0614 [0]) 2017-05-19 20:13:26,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac440 [0xafd838]) 2017-05-19 20:13:26,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f37860 2017-05-19 20:13:26,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac440 [0xafd838]) 2017-05-19 20:13:26,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f37860 2017-05-19 20:13:26,108 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2017-05-19 20:13:26,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac440 [0xafd838], 0x2e0620 [1]) 2017-05-19 20:13:26,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac450 [0xafd890]) 2017-05-19 20:13:26,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac450 [0xafd890]) 2017-05-19 20:13:26,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,130 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2017-05-19 20:13:26,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac450 [0xafd890], 0x2e0620 [1]) 2017-05-19 20:13:26,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac460 [0xafd8e8]) 2017-05-19 20:13:26,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac460 [0xafd8e8]) 2017-05-19 20:13:26,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,144 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2017-05-19 20:13:26,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac460 [0xafd8e8], 0x2e0614 [0]) 2017-05-19 20:13:26,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac470 [0xafd940]) 2017-05-19 20:13:26,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac470 [0xafd940]) 2017-05-19 20:13:26,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f376c0 2017-05-19 20:13:26,159 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2017-05-19 20:13:26,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac470 [0xafd940], 0x2e0614 [0]) 2017-05-19 20:13:26,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac480 [0xafd998]) 2017-05-19 20:13:26,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f37860 2017-05-19 20:13:26,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac480 [0xafd998]) 2017-05-19 20:13:26,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f37860 2017-05-19 20:13:26,173 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-05-19 20:13:26,176 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac480 [0xafd998], 0x2e0614 [0]) 2017-05-19 20:13:26,178 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac490 [0xafda00]) 2017-05-19 20:13:26,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,184 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac490 [0xafda00]) 2017-05-19 20:13:26,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,188 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2017-05-19 20:13:26,190 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac490 [0xafda00], 0x2e0614 [0]) 2017-05-19 20:13:26,193 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac4a0 [0xb0bcc8]) 2017-05-19 20:13:26,197 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac4a0 [0xb0bcc8]) 2017-05-19 20:13:26,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,203 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2017-05-19 20:13:26,205 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac4a0 [0xb0bcc8], 0x2e0614 [0]) 2017-05-19 20:13:26,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,211 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e63d50 2017-05-19 20:13:26,213 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e63d50 2017-05-19 20:13:26,217 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2017-05-19 20:13:26,219 [DEBUG] [doorpi.sipphone.from_linphone] | name 2017-05-19 20:13:26,221 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 20:13:26,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,225 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e63d50 2017-05-19 20:13:26,227 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2017-05-19 20:13:26,229 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 20:13:26,231 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,233 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e63d50 2017-05-19 20:13:26,235 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2017-05-19 20:13:26,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e63d50 2017-05-19 20:13:26,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb4f1a938 [0xafd558], 0x2e0620 [1]) 2017-05-19 20:13:26,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2017-05-19 20:13:26,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb4f1a938 [0xafd558], "StaticImage: Static picture") 2017-05-19 20:13:26,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2017-05-19 20:13:26,265 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2017-05-19 20:13:26,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb4f1a938 [0xafd558], "vga") 2017-05-19 20:13:26,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2017-05-19 20:13:26,271 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,273 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb4f38b60 2017-05-19 20:13:26,275 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2017-05-19 20:13:26,288 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2017-05-19 20:13:26,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xafcba0) 2017-05-19 20:13:26,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 20:13:26,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cac4b0 2017-05-19 20:13:26,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 20:13:26,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cac4b0 2017-05-19 20:13:26,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cac4b0 2017-05-19 20:13:26,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb4e63da0 2017-05-19 20:13:26,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac4b0 [0xafcba0]) 2017-05-19 20:13:26,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,313 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2017-05-19 20:13:26,315 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cac4b0 [0xafcba0]) 2017-05-19 20:13:26,317 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1a950 2017-05-19 20:13:26,319 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2017-05-19 20:13:26,321 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1a938 [0xafd558], 0xb6cac4b0 [0xafcba0], 0x2e0620 [1]) 2017-05-19 20:13:26,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x96c8bc 2017-05-19 20:13:26,325 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2017-05-19 20:13:26,327 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 620 2017-05-19 20:13:26,329 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 620) returns ******* 2017-05-19 20:13:26,331 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2017-05-19 20:13:26,333 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2017-05-19 20:13:26,335 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:26,338 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0xb1dd40) 2017-05-19 20:13:26,340 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2017-05-19 20:13:26,342 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6cac4c0 2017-05-19 20:13:26,343 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2017-05-19 20:13:26,346 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6cac4c0 2017-05-19 20:13:26,348 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6cac4c0 2017-05-19 20:13:26,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6cac4c0 2017-05-19 20:13:26,351 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2017-05-19 20:13:26,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2017-05-19 20:13:26,357 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb1c330) 2017-05-19 20:13:26,359 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 20:13:26,361 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6cac4d0 2017-05-19 20:13:26,363 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 20:13:26,365 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6cac4d0 2017-05-19 20:13:26,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6cac4d0 2017-05-19 20:13:26,369 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6cac4d0 2017-05-19 20:13:26,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6cac4c0 [0xb1dd40], 0xb6cac4d0 [0xb1c330]) 2017-05-19 20:13:26,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2017-05-19 20:13:26,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6cac4c0 [0xb1dd40], "sip:192.168.178.1") 2017-05-19 20:13:26,379 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2017-05-19 20:13:26,381 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6cac4c0 [0xb1dd40], 0x2e0620 [1]) 2017-05-19 20:13:26,383 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2017-05-19 20:13:26,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb4f1a938 [0xafd558], 0xb6cac4c0 [0xb1dd40]) 2017-05-19 20:13:26,389 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x96c8bc 2017-05-19 20:13:26,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb4f1a938 [0xafd558], 0xb6cac4c0 [0xb1dd40]) 2017-05-19 20:13:26,393 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2017-05-19 20:13:26,395 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb4f1a938 [0xafd558], "620", "(null)", "04081966", "(null)", "(null)", "fritz.box") 2017-05-19 20:13:26,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0xb1c7a8) 2017-05-19 20:13:26,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2017-05-19 20:13:26,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6cac4e0 2017-05-19 20:13:26,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2017-05-19 20:13:26,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6cac4e0 2017-05-19 20:13:26,407 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6cac4e0 2017-05-19 20:13:26,409 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6cac4e0 2017-05-19 20:13:26,411 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb4f1a938 [0xafd558], 0xb6cac4e0 [0xb1c7a8]) 2017-05-19 20:13:26,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2017-05-19 20:13:26,415 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2017-05-19 20:13:26,417 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnTimeMinute59'] 2017-05-19 20:13:26,419 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnTimeMinute59' in configfile 2017-05-19 20:13:26,421 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnTimeMinute59 returns ['10'] 2017-05-19 20:13:26,423 [INFO] [doorpi.doorpi] registering action '10' for event 'OnTimeMinute59' 2017-05-19 20:13:26,425 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnTimeMinute59 (default: ) returns call:**2 2017-05-19 20:13:26,429 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2017-05-19 20:13:26,431 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**2'} was added to new evententry OnTimeMinute59 2017-05-19 20:13:26,434 [WARNING] [root] section dummy_InputPins not found in configfile 2017-05-19 20:13:26,436 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2017-05-19 20:13:26,438 [DEBUG] [doorpi.conf.config_object] create new key is_alive_led in section DoorPi with value '' 2017-05-19 20:13:26,442 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2017-05-19 20:13:26,444 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2017-05-19 20:13:26,450 [DEBUG] [doorpi.action.handler] no actions for event OnStartup - skip fire_event OnStartup from doorpi.doorpi 2017-05-19 20:13:26,452 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2017-05-19 20:13:26,457 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2017-05-19 20:13:26,461 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2017-05-19 20:13:26,464 [INFO] [doorpi.doorpi] DoorPi started successfully 2017-05-19 20:13:26,467 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2017-05-19 20:13:26,469 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2017-05-19 20:13:26,472 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2017-05-19 20:13:26,474 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2017-05-19 20:13:26,476 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.48 2017-05-19 20:13:26,478 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2017-05-19 20:13:26,482 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2017-05-19 20:13:26,484 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2017-05-19 20:13:26,487 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2017-05-19 20:13:26,490 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2017-05-19 20:13:26,493 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0xb1dd40] about to register (LinphoneCore version: 3.9.0) 2017-05-19 20:13:26,508 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2017-05-19 20:13:26,510 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: starting resolution of 192.168.178.1 2017-05-19 20:13:26,512 [INFO] [doorpi.sipphone.from_linphone] channel 0xb2d428: state RES_IN_PROGRESS 2017-05-19 20:13:26,514 [INFO] [doorpi.sipphone.from_linphone] transaction [0xb17958] channel state changed to [RES_IN_PROGRESS] 2017-05-19 20:13:26,517 [INFO] [doorpi.sipphone.from_linphone] channel 0xb2d428: state RES_DONE 2017-05-19 20:13:26,519 [INFO] [doorpi.sipphone.from_linphone] transaction [0xb17958] channel state changed to [RES_DONE] 2017-05-19 20:13:26,521 [INFO] [doorpi.sipphone.from_linphone] channel 0xb2d428: state CONNECTING 2017-05-19 20:13:26,523 [INFO] [doorpi.sipphone.from_linphone] transaction [0xb17958] channel state changed to [CONNECTING] 2017-05-19 20:13:26,525 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2017-05-19 20:13:26,527 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2017-05-19 20:13:26,529 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.48:5060 2017-05-19 20:13:26,531 [INFO] [doorpi.sipphone.from_linphone] channel 0xb2d428: state READY 2017-05-19 20:13:26,533 [INFO] [doorpi.sipphone.from_linphone] transaction [0xb17958] channel state changed to [READY] 2017-05-19 20:13:26,535 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xb17958], from state [INIT] to [TRYING] 2017-05-19 20:13:26,538 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: message sent to [UDP://192.168.178.1:5060], size: [514] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.MqIXB~~Fs;rport From: "DoorPi" ;tag=kV-ak4ULW To: "DoorPi" CSeq: 20 REGISTER Call-ID: r~SvgSxPbf 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) 2017-05-19 20:13:26,540 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2017-05-19 20:13:26,543 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xb179d8] takes ownership of transaction [0xb17958] 2017-05-19 20:13:26,545 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xb1dd40] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2017-05-19 20:13:26,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xafd558, 0xb1dd40, 1, "Registration in progress") 2017-05-19 20:13:26,549 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2017-05-19 20:13:26,551 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [registration_state_changed] 2017-05-19 20:13:26,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0xafd558, 0x1) 2017-05-19 20:13:26,606 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2017-05-19 20:13:26,608 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [network_reachable] 2017-05-19 20:13:26,611 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: received [293] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.MqIXB~~Fs;rport=5060 From: "DoorPi" ;tag=kV-ak4ULW To: "DoorPi" ;tag=E76BB51C4009248F Call-ID: r~SvgSxPbf CSeq: 20 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 20:13:26,626 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428] [293] bytes parsed 2017-05-19 20:13:26,628 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: discovered public ip and port are [192.168.178.48:5060] 2017-05-19 20:13:26,630 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 20:13:26,632 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xb17958], from state [TRYING] to [COMPLETED] 2017-05-19 20:13:26,634 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 20:13:26,636 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 20:13:26,638 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xb1dd40] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationFailed] 2017-05-19 20:13:26,640 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xafd558, 0xb1dd40, 4, "Not Found") 2017-05-19 20:13:26,642 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2017-05-19 20:13:26,644 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [registration_state_changed] 2017-05-19 20:13:26,647 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2017-05-19 20:13:26,649 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2017-05-19 20:13:31,650 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xb17958], from state [COMPLETED] to [TERMINATED] 2017-05-19 20:13:31,652 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xb17958] terminated 2017-05-19 20:13:42,985 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /dashboard/pages/status.modules.eventhandler.html 2017-05-19 20:13:43,091 [TRACE] [doorpi.status.webserver_lib.request_handler_static_functions] use onlinefallback - local file /usr/local/etc/DoorPi/../DoorPiWeb/dashboard/pages/status.modules.eventhandler.html not found 2017-05-19 20:13:43,747 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /dashboard/pages/status.modules.eventhandler.html HTTP/1.1', '200', '-') 2017-05-19 20:13:45,488 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 20:13:45,501 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=environment&name=event_handler&output=json.parsed HTTP/1.1', '200', '-') 2017-05-19 20:13:45,531 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 20:13:45,537 [DEBUG] [doorpi.status.status_lib.event_handler] doorpi.status.status_lib.event_handler loaded 2017-05-19 20:13:45,542 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=event_handler&name=actions&output=json&_=1495217626227 HTTP/1.1', '200', '-') 2017-05-19 20:13:45,570 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 20:13:45,579 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 20:13:45,575 [DEBUG] [doorpi.status.status_lib.history_event] doorpi.status.status_lib.history_event loaded 2017-05-19 20:13:45,584 [DEBUG] [doorpi.action.handler] request last 100 event logs with filter 2017-05-19 20:13:45,609 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 20:13:45,614 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=event_handler&name=threads&output=json&_=1495217626230 HTTP/1.1', '200', '-') 2017-05-19 20:13:45,600 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=event_handler&name=events_by_source&output=json&_=1495217626228 HTTP/1.1', '200', '-') 2017-05-19 20:13:45,617 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=history_event&output=json&_=1495217626229 HTTP/1.1', '200', '-') 2017-05-19 20:13:56,187 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /control/trigger_event 2017-05-19 20:13:56,191 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] { "event_name": [ "OnTimeMinute59" ], "event_source": [ "doorpi.action.SingleActions.time_tick" ] } 2017-05-19 20:13:56,194 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2017-05-19 20:13:56,198 [DEBUG] [doorpi.sipphone.from_linphone] call (**2) 2017-05-19 20:13:56,200 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2017-05-19 20:13:56,202 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2017-05-19 18:13:56.202567 2017-05-19 20:13:56,208 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 20:13:56,206 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0xb4f1a938 [0xafd558], 0x2d7824 [(nil)]) 2017-05-19 20:13:56,212 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0xb4505a38) 2017-05-19 20:13:56,214 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2017-05-19 20:13:56,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0xb6cac530 2017-05-19 20:13:56,218 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2017-05-19 20:13:56,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0xb6cac530 2017-05-19 20:13:56,222 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0xb6cac530 2017-05-19 20:13:56,224 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0xb6cac530 2017-05-19 20:13:56,231 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0xb6cac530 [0xb4505a38], "/mnt/fritzbox/records/2017-05-19_20-13-56.wav") 2017-05-19 20:13:56,235 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2017-05-19 20:13:56,237 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0xb6cac530 [0xb4505a38], 0x2e0620 [1]) 2017-05-19 20:13:56,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2017-05-19 20:13:56,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0xb4f1a938 [0xafd558], "**2", 0xb6cac530 [0xb4505a38]) 2017-05-19 20:13:56,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0xafd558, 0xb453f528, 2, "Starting outgoing call") 2017-05-19 20:13:56,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new() 2017-05-19 20:13:56,255 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0xb6cac540 2017-05-19 20:13:56,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init() 2017-05-19 20:13:56,260 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0xb6cac540 2017-05-19 20:13:56,262 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call) 2017-05-19 20:13:56,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6cac540 [0xb453f528]) 2017-05-19 20:13:56,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb453d5a0) 2017-05-19 20:13:56,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 20:13:56,270 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6cac520 2017-05-19 20:13:56,272 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 20:13:56,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6cac520 2017-05-19 20:13:56,276 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6cac520 2017-05-19 20:13:56,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6cac520 2017-05-19 20:13:56,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6cac520 [0xb453d5a0]) 2017-05-19 20:13:56,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4e7eef0 2017-05-19 20:13:56,286 [WARNING] [doorpi.sipphone.from_linphone] There is no object pool created in thread [3003167840]. Use belle_sip_object_pool_push() to create one. Unowned objects not unref'd will be leaked. 2017-05-19 20:13:56,288 [INFO] [doorpi.sipphone.from_linphone] New LinphoneCall [0xb453f528] initialized (LinphoneCore version: 3.9.0) 2017-05-19 20:13:56,290 [INFO] [doorpi.sipphone.from_linphone] Call 0xb453f528: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2017-05-19 20:13:56,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0xb6cac540 [0xb453f528]) 2017-05-19 20:13:56,284 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:56,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x96c8a4 2017-05-19 20:13:56,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_inc_timeout(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_inc_timeout -> 0x96c808 2017-05-19 20:13:56,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8b0 2017-05-19 20:13:56,311 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:56,316 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:56,320 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:56,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,325 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8b0 2017-05-19 20:13:56,327 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 20:13:56,338 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0xafd558, 0xb453f528, 3, "Outgoing call in progress") 2017-05-19 20:13:56,340 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress) 2017-05-19 20:13:56,342 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6cac540 [0xb453f528]) 2017-05-19 20:13:56,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb453d5a0) 2017-05-19 20:13:56,347 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 20:13:56,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6cac510 2017-05-19 20:13:56,351 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 20:13:56,353 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6cac510 2017-05-19 20:13:56,356 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [call_state_changed] 2017-05-19 20:13:56,359 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [audio] on call [0xb453f528] 2017-05-19 20:13:56,361 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [7078] [7079] 2017-05-19 20:13:56,363 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 46 for MSAudio stream. 2017-05-19 20:13:56,366 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6cac510 2017-05-19 20:13:56,368 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6cac510 2017-05-19 20:13:56,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6cac510 [0xb453d5a0]) 2017-05-19 20:13:56,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4e7e740 2017-05-19 20:13:56,374 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:56,365 [INFO] [doorpi.sipphone.from_linphone] Equalizer location: hp 2017-05-19 20:13:56,378 [INFO] [doorpi.sipphone.from_linphone] cannot set noise gate mode to [0] because no volume send 2017-05-19 20:13:56,380 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [video] on call [0xb453f528] 2017-05-19 20:13:56,382 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [9078] [9079] 2017-05-19 20:13:56,384 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 0 for MSVideo stream. 2017-05-19 20:13:56,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8b0 2017-05-19 20:13:56,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8b0 2017-05-19 20:13:56,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8b0 2017-05-19 20:13:56,391 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:56,406 [INFO] [doorpi.sipphone.from_linphone] Contact has been fixed using proxy 2017-05-19 20:13:56,410 [INFO] [doorpi.sipphone.from_linphone] Don't put text stream on local offer for call [0xb453f528] 2017-05-19 20:13:56,412 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSRtpRecv:0xb453dca8,0-->MSVoidSink:0xb45176b8,0 2017-05-19 20:13:56,414 [INFO] [doorpi.sipphone.from_linphone] ["DoorPi" ] calling [sip:**2@192.168.178.1] on op [0xb453d940] 2017-05-19 20:13:56,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 20:13:56,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0xb6cac540 2017-05-19 20:13:56,420 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2017-05-19 20:13:56,423 [INFO] [doorpi.sipphone.from_linphone] Skipping top route of initial route-set because same as request-uri. 2017-05-19 20:13:56,426 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb45284a8], from state [INIT] to [CALLING] 2017-05-19 20:13:56,428 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: message sent to [UDP://192.168.178.1:5060], size: [809] bytes INVITE sip:**2@192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.15qsUR6is;rport From: "DoorPi" ;tag=ZwwgQt8us To: sip:**2@192.168.178.1 CSeq: 20 INVITE Call-ID: JAWxAbDh3j Max-Forwards: 70 Supported: outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 269 Contact: ;+sip.instance="" User-Agent: (belle-sip/1.4.2) v=0 o=620 1895 82 IN IP4 192.168.178.48 s=Talk c=IN IP4 192.168.178.48 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7078 RTP/AVP 0 8 101 a=rtpmap:101 telephone-event/8000 m=video 9078 RTP/AVP 96 a=rtpmap:96 VP8/90000 2017-05-19 20:13:56,430 [INFO] [doorpi.sipphone.from_linphone] Call 0xb453f528: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2017-05-19 20:13:56,432 [INFO] [doorpi.sipphone.from_linphone] Priority used: 99 2017-05-19 20:13:56,434 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker priority set to SCHED_RR and value (99) 2017-05-19 20:13:56,440 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: received [282] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.15qsUR6is;rport=5060 From: "DoorPi" ;tag=ZwwgQt8us To: ;tag=3D883745B6E6713B Call-ID: JAWxAbDh3j CSeq: 20 INVITE User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 20:13:56,439 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2017-05-19 20:13:56,438 [DEBUG] [doorpi.action.handler] [IE6AE0] fire for event OnSipPhoneMakeCall this actions [] 2017-05-19 20:13:56,458 [TRACE] [doorpi.action.handler] [IE6AE0] try to fire action > with args () and kwargs {} 2017-05-19 20:13:56,460 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2017-05-19 20:13:56,462 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron 2017-05-19 20:13:56,467 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [call_state_changed] 2017-05-19 20:13:56,469 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428] [282] bytes parsed 2017-05-19 20:13:56,471 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 20:13:56,473 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb45284a8], from state [CALLING] to [PROCEEDING] 2017-05-19 20:13:56,478 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2017-05-19 20:13:56,483 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 20:13:56,487 [TRACE] [doorpi.action.handler] [IE6AE0] finished fire_event for event_name OnSipPhoneMakeCall 2017-05-19 20:13:56,493 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /control/trigger_event?event_name=OnTimeMinute59&event_source=doorpi.action.SingleActions.time_tick HTTP/1.1', '200', '-') 2017-05-19 20:13:56,496 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb45284a8], from state [PROCEEDING] to [COMPLETED] 2017-05-19 20:13:56,500 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: message sent to [UDP://192.168.178.1:5060], size: [362] bytes ACK sip:**2@192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.15qsUR6is;rport Call-ID: JAWxAbDh3j From: "DoorPi" ;tag=ZwwgQt8us To: ;tag=3D883745B6E6713B Contact: ;+sip.instance="" Max-Forwards: 70 CSeq: 20 ACK 2017-05-19 20:13:56,502 [INFO] [doorpi.sipphone.from_linphone] op [0xb453d940] : set_or_update_dialog() current=[(nil)] new=[(nil)] 2017-05-19 20:13:56,504 [INFO] [doorpi.sipphone.from_linphone] Op [0xb453d940] receiving call response [404], dialog is [(nil)] in state [BELLE_SIP_DIALOG_NULL] 2017-05-19 20:13:56,511 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSRtpRecv:0xb453dca8,0-->MSVoidSink:0xb45176b8,0 2017-05-19 20:13:56,513 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2017-05-19 20:13:56,516 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS 2017-05-19 20:13:56,518 [INFO] [doorpi.sipphone.from_linphone] Name Count Time/tick (ms) CPU Usage 2017-05-19 20:13:56,520 [INFO] [doorpi.sipphone.from_linphone] ----------------------------------------------------------- 2017-05-19 20:13:56,522 [INFO] [doorpi.sipphone.from_linphone] MSRtpRecv 17 0.0827782 99.9999 2017-05-19 20:13:56,524 [INFO] [doorpi.sipphone.from_linphone] MSVoidSink 0 0 0 2017-05-19 20:13:56,526 [INFO] [doorpi.sipphone.from_linphone] MSItcSink 0 0 0 2017-05-19 20:13:56,528 [INFO] [doorpi.sipphone.from_linphone] MSTee 0 0 0 2017-05-19 20:13:56,530 [INFO] [doorpi.sipphone.from_linphone] MSSpeexEC 0 0 0 2017-05-19 20:13:56,532 [INFO] [doorpi.sipphone.from_linphone] MSRtpSend 0 0 0 2017-05-19 20:13:56,534 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2017-05-19 20:13:56,537 [INFO] [doorpi.sipphone.from_linphone] Call 0xb453f528: moving from state LinphoneCallOutgoingProgress to LinphoneCallError 2017-05-19 20:13:56,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_log_updated(0xafd558, 0xb453d3a8) 2017-05-19 20:13:56,549 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_log_updated 2017-05-19 20:13:56,551 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker thread exiting 2017-05-19 20:13:56,553 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [call_log_updated] 2017-05-19 20:13:56,555 [INFO] [doorpi.sipphone.from_linphone] Resetting the current call 2017-05-19 20:13:56,557 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0xafd558, 0xb453f528, 12, "Not Found") 2017-05-19 20:13:56,559 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (12 - Not Found) 2017-05-19 20:13:56,561 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6cac540 [0xb453f528]) 2017-05-19 20:13:56,563 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb453d5a0) 2017-05-19 20:13:56,565 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 20:13:56,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6cac550 2017-05-19 20:13:56,570 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 20:13:56,572 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6cac550 2017-05-19 20:13:56,574 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6cac550 2017-05-19 20:13:56,576 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6cac550 2017-05-19 20:13:56,578 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6cac550 [0xb453d5a0]) 2017-05-19 20:13:56,580 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4e7ee90 2017-05-19 20:13:56,582 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:56,586 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,588 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8bc 2017-05-19 20:13:56,590 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,592 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8bc 2017-05-19 20:13:56,594 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:56,597 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:56,603 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,605 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8bc 2017-05-19 20:13:56,607 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 20:13:56,609 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [call_state_changed] 2017-05-19 20:13:56,611 [INFO] [doorpi.sipphone.from_linphone] Call 0xb453f528: moving from state LinphoneCallError to LinphoneCallReleased 2017-05-19 20:13:56,613 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0xafd558, 0xb453f528, 18, "Call released") 2017-05-19 20:13:56,617 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:56,620 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2017-05-19 20:13:56,622 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6cac540 [0xb453f528]) 2017-05-19 20:13:56,624 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb453d5a0) 2017-05-19 20:13:56,626 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 20:13:56,628 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6cac5a0 2017-05-19 20:13:56,630 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 20:13:56,632 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6cac5a0 2017-05-19 20:13:56,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6cac5a0 2017-05-19 20:13:56,636 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6cac5a0 2017-05-19 20:13:56,639 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6cac5a0 [0xb453d5a0]) 2017-05-19 20:13:56,641 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4e981d0 2017-05-19 20:13:56,643 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 20:13:56,647 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,649 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8bc 2017-05-19 20:13:56,651 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,653 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8bc 2017-05-19 20:13:56,658 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 20:13:56,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4f1a938 [0xafd558]) 2017-05-19 20:13:56,662 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x96c8bc 2017-05-19 20:13:56,664 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 20:13:56,666 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xafd558] notifying [call_state_changed] 2017-05-19 20:13:56,668 [INFO] [doorpi.sipphone.from_linphone] op [0xb453d940] : set_or_update_dialog() current=[(nil)] new=[(nil)] 2017-05-19 20:14:26,659 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xa57fe0], from state [INIT] to [TRYING] 2017-05-19 20:14:26,663 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: message sent to [UDP://192.168.178.1:5060], size: [514] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.~gUNGlx-J;rport From: "DoorPi" ;tag=kV-ak4ULW To: "DoorPi" CSeq: 21 REGISTER Call-ID: r~SvgSxPbf 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) 2017-05-19 20:14:26,717 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428]: received [293] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.~gUNGlx-J;rport=5060 From: "DoorPi" ;tag=kV-ak4ULW To: "DoorPi" ;tag=3C5DA4B47B9C3AA2 Call-ID: r~SvgSxPbf CSeq: 21 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 20:14:26,731 [INFO] [doorpi.sipphone.from_linphone] channel [0xb2d428] [293] bytes parsed 2017-05-19 20:14:26,733 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 20:14:26,735 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xa57fe0], from state [TRYING] to [COMPLETED] 2017-05-19 20:14:26,738 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 20:14:26,740 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 20:14:28,700 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb45284a8], from state [COMPLETED] to [TERMINATED] 2017-05-19 20:14:28,702 [INFO] [doorpi.sipphone.from_linphone] Client INVITE transaction [0xb45284a8] terminated 2017-05-19 20:14:28,704 [INFO] [doorpi.sipphone.from_linphone] Destroying op [0xb453d940] of type [SalOpCall] 2017-05-19 20:14:31,751 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xa57fe0], from state [COMPLETED] to [TERMINATED] 2017-05-19 20:14:31,762 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xa57fe0] terminated