pi@tuer:~ $ sudo doorpi_cli --trace 2016-03-18 20:51:00,721 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.0 / /_// (_) | (_) | | / ___/| | 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 2016-03-18 20:51:00,729 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-03-18 20:51:00,737 [DEBUG] [doorpi.doorpi] run 2016-03-18 20:51:00,742 [DEBUG] [doorpi.doorpi] prepare 2016-03-18 20:51:00,748 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-03-18 20:51:00,762 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-03-18 20:51:00,788 [DEBUG] [doorpi.conf.config_object] __init__ 2016-03-18 20:51:00,794 [TRACE] [doorpi.conf.config_object] get_from_config 2016-03-18 20:51:00,811 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-03-18 20:51:00,816 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-03-18 20:51:00,823 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-03-18 20:51:00,836 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-03-18 20:51:00,841 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-03-18 20:51:00,847 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-03-18 20:51:00,853 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-03-18 20:51:00,858 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-03-18 20:51:00,863 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-03-18 20:51:00,868 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-03-18 20:51:00,873 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-03-18 20:51:00,878 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-03-18 20:51:00,883 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-03-18 20:51:00,888 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-03-18 20:51:00,893 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-03-18 20:51:00,898 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-03-18 20:51:00,907 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-03-18 20:51:00,915 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-03-18 20:51:00,934 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-03-18 20:51:00,939 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-03-18 20:51:00,945 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-03-18 20:51:00,951 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-03-18 20:51:00,963 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-03-18 20:51:00,969 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-03-18 20:51:00,974 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-03-18 20:51:00,980 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-03-18 20:51:00,986 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-03-18 20:51:00,990 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-03-18 20:51:00,996 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-03-18 20:51:01,002 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-03-18 20:51:01,008 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-03-18 20:51:01,015 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-03-18 20:51:01,020 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-03-18 20:51:01,027 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-03-18 20:51:01,033 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-03-18 20:51:01,040 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-03-18 20:51:01,046 [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 2016-03-18 20:51:01,052 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-03-18 20:51:01,058 [WARNING] [root] section WritePermission not found in configfile 2016-03-18 20:51:01,064 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2016-03-18 20:51:01,069 [WARNING] [root] section ReadPermission not found in configfile 2016-03-18 20:51:01,075 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-03-18 20:51:01,080 [WARNING] [root] section Group not found in configfile 2016-03-18 20:51:01,086 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2016-03-18 20:51:01,091 [WARNING] [root] section User not found in configfile 2016-03-18 20:51:01,097 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2016-03-18 20:51:01,102 [TRACE] [doorpi.conf.config_object] get_sections returns ['DoorPiWeb', 'keyboards', 'EVENT_OnStartup', 'SIP-Phone', 'DoorPi'] 2016-03-18 20:51:01,109 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-03-18 20:51:01,114 [ERROR] [doorpi.status.webserver] no groups in configfile! 2016-03-18 20:51:01,119 [ERROR] [doorpi.status.webserver] no WritePermission found 2016-03-18 20:51:01,124 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-03-18 20:51:01,129 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-03-18 20:51:01,135 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-03-18 20:51:01,144 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,149 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-03-18 20:51:01,160 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,166 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,156 [DEBUG] [doorpi.action.handler] [082HSN] fire for event OnWebServerStart this actions [] 2016-03-18 20:51:01,177 [TRACE] [doorpi.action.handler] [082HSN] try to fire action WebServerStartupAction with args () and kwargs {} 2016-03-18 20:51:01,173 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,188 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,195 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,190 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-03-18 20:51:01,200 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,211 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,217 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,222 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,227 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,233 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,238 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,242 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,248 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,253 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-03-18 20:51:01,258 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['dummy'] 2016-03-18 20:51:01,263 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: dummy) 2016-03-18 20:51:01,269 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'dummy' to handler 2016-03-18 20:51:01,275 [TRACE] [doorpi.conf.config_object] get_string for key dummy in section keyboards (default: dummy) returns dummy 2016-03-18 20:51:01,280 [WARNING] [root] section dummy_InputPins not found in configfile 2016-03-18 20:51:01,286 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2016-03-18 20:51:01,291 [WARNING] [root] section dummy_OutputPins not found in configfile 2016-03-18 20:51:01,297 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2016-03-18 20:51:01,302 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section dummy_keyboard (default: 2000) returns 2000.0 2016-03-18 20:51:01,308 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section dummy_keyboard (default: 0) returns 0 2016-03-18 20:51:01,314 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section dummy_keyboard (default: True) returns True 2016-03-18 20:51:01,321 [DEBUG] [doorpi.keyboard.from_dummy] doorpi.keyboard.from_dummy loaded 2016-03-18 20:51:01,328 [DEBUG] [doorpi.keyboard.from_dummy] __init__(input_pins = [], output_pins = [], bouncetime = 2000.0, polarity = 0) 2016-03-18 20:51:01,334 [WARNING] [root] section dummy_InputPins not found in configfile 2016-03-18 20:51:01,340 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2016-03-18 20:51:01,345 [WARNING] [root] section dummy_OutputPins not found in configfile 2016-03-18 20:51:01,350 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2016-03-18 20:51:01,356 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-03-18 20:51:01,362 [WARNING] [root] section dummy_OutputPins not found in configfile 2016-03-18 20:51:01,367 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2016-03-18 20:51:01,377 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-03-18 20:51:01,387 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-03-18 20:51:01,396 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-03-18 20:51:01,405 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-03-18 20:51:01,527 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-03-18 20:51:01,538 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-03-18 20:51:01,550 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-03-18 20:51:01,743 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-03-18 20:51:01,768 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-03-18 20:51:01,774 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-03-18 20:51:01,783 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-03-18 20:51:01,794 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-03-18 20:51:01,804 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-03-18 20:51:01,816 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-03-18 20:51:01,824 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-03-18 20:51:01,830 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-03-18 20:51:01,836 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,842 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-03-18 20:51:01,847 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,853 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,858 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,863 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,868 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,874 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,879 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,884 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,889 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,894 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,900 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,905 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,911 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,916 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,921 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,927 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,931 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,936 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,942 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,948 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,952 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,958 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-03-18 20:51:01,963 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-03-18 20:51:01,968 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-03-18 20:51:01,974 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-03-18 20:51:01,979 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:01,985 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-03-18 20:51:01,990 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:01,995 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,000 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,005 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,010 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,015 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,020 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,026 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,031 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,037 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,041 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,047 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,053 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,058 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,063 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,068 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,074 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,079 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,084 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,089 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,094 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,100 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,106 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,111 [WARNING] [root] section DTMF not found in configfile 2016-03-18 20:51:02,117 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-18 20:51:02,122 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,127 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-03-18 20:51:02,132 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,138 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,143 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,148 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,153 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,158 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,164 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,169 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,173 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,178 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,184 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,189 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,193 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,198 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,204 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,209 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,214 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,220 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,225 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-03-18 20:51:02,234 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-03-18 20:51:02,244 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-18 20:51:02,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb4f1c818 2016-03-18 20:51:02,256 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-03-18 20:51:02,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb4f1c818 2016-03-18 20:51:02,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb4e906f0, "(null)", "(null)") 2016-03-18 20:51:02,275 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-03-18 20:51:02,281 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2016-03-18 20:51:02,287 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-03-18 20:51:02,293 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-03-18 20:51:02,358 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-03-18 20:51:02,613 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-03-18 20:51:02,653 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-03-18 20:51:02,665 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-03-18 20:51:02,717 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB PnP Sound Device' added 2016-03-18 20:51:02,726 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-03-18 20:51:02,732 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-03-18 20:51:02,738 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-03-18 20:51:02,744 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-03-18 20:51:02,750 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-03-18 20:51:02,756 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-03-18 20:51:02,762 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x1470b70] registered on core [0x1470748] 2016-03-18 20:51:02,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1470b70, 1, "Starting up") 2016-03-18 20:51:02,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-18 20:51:02,779 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [global_state_changed] 2016-03-18 20:51:02,785 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-03-18 20:51:02,790 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-03-18 20:51:02,796 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-03-18 20:51:02,802 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-03-18 20:51:02,808 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2016-03-18 20:51:02,813 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-18 20:51:02,819 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-18 20:51:02,825 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-18 20:51:02,830 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-18 20:51:02,836 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-03-18 20:51:02,842 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-03-18 20:51:02,848 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-03-18 20:51:02,854 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-03-18 20:51:02,860 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-03-18 20:51:02,865 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-03-18 20:51:02,871 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-03-18 20:51:02,876 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-03-18 20:51:02,881 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-03-18 20:51:02,887 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-03-18 20:51:02,893 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-03-18 20:51:02,899 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-03-18 20:51:02,904 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-18 20:51:02,909 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-18 20:51:02,915 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-18 20:51:02,920 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-18 20:51:02,926 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-18 20:51:02,932 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-18 20:51:02,938 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-18 20:51:02,943 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-18 20:51:02,948 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-18 20:51:02,954 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-18 20:51:02,960 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-18 20:51:02,966 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-18 20:51:02,971 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-03-18 20:51:02,976 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-03-18 20:51:02,982 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-03-18 20:51:02,988 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-18 20:51:02,993 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-18 20:51:02,999 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-18 20:51:03,004 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-18 20:51:03,010 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-18 20:51:03,016 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-18 20:51:03,022 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-03-18 20:51:03,027 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-03-18 20:51:03,033 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-03-18 20:51:03,038 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-03-18 20:51:03,044 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-03-18 20:51:03,049 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-03-18 20:51:03,055 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-03-18 20:51:03,060 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-03-18 20:51:03,066 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-03-18 20:51:03,072 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-03-18 20:51:03,077 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-03-18 20:51:03,082 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-03-18 20:51:03,088 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-03-18 20:51:03,093 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-03-18 20:51:03,099 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-03-18 20:51:03,104 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-03-18 20:51:03,110 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-03-18 20:51:03,116 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-03-18 20:51:03,121 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-03-18 20:51:03,127 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-03-18 20:51:03,132 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-03-18 20:51:03,138 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-03-18 20:51:03,144 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-03-18 20:51:03,150 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-18 20:51:03,156 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-03-18 20:51:03,161 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-03-18 20:51:03,167 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-03-18 20:51:03,173 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-03-18 20:51:03,179 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-03-18 20:51:03,184 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-03-18 20:51:03,189 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-03-18 20:51:03,195 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-03-18 20:51:03,200 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-03-18 20:51:03,206 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-03-18 20:51:03,212 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-18 20:51:03,218 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-18 20:51:03,224 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-03-18 20:51:03,229 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-03-18 20:51:03,235 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-03-18 20:51:03,240 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-03-18 20:51:03,246 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-18 20:51:03,253 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-18 20:51:03,258 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,264 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,270 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,276 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x1470b70, 2, "(null)") 2016-03-18 20:51:03,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-03-18 20:51:03,287 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [configuring_status] 2016-03-18 20:51:03,293 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-03-18 20:51:03,299 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-03-18 20:51:03,304 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-03-18 20:51:03,310 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-03-18 20:51:03,316 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-03-18 20:51:03,322 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-18 20:51:03,327 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-18 20:51:03,333 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-03-18 20:51:03,339 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-03-18 20:51:03,345 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-03-18 20:51:03,351 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,356 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,362 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,368 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,374 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-03-18 20:51:03,379 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-18 20:51:03,385 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-18 20:51:03,390 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,395 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,401 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-03-18 20:51:03,407 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-18 20:51:03,413 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,419 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-18 20:51:03,425 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x14715e0] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-18 20:51:03,431 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1471760] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-18 20:51:03,438 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1472168] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-18 20:51:03,444 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 59473 2016-03-18 20:51:03,451 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,457 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,469 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x1470b70, 2, "Ready") 2016-03-18 20:51:03,476 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-18 20:51:03,482 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [global_state_changed] 2016-03-18 20:51:03,488 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb4f1c818 2016-03-18 20:51:03,494 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-03-18 20:51:03,499 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb4f1c818 [0x1470b70], "DoorPi ") 2016-03-18 20:51:03,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-03-18 20:51:03,515 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-03-18 20:51:03,523 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-03-18 20:51:03,528 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb4f1c818 [0x1470b70], 2) 2016-03-18 20:51:03,539 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-03-18 20:51:03,533 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-18 20:51:03,551 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-03-18 20:51:03,557 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb4f1c818 [0x1470b70], 0x2e0614 [0]) 2016-03-18 20:51:03,564 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-03-18 20:51:03,570 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-18 20:51:03,576 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-18 20:51:03,581 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-03-18 20:51:03,587 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb4f1c830 2016-03-18 20:51:03,593 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb4f1c818 [0x1470b70], 0xb4f1c830 [0xb4f1c838]) 2016-03-18 20:51:03,599 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x14715e0] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-03-18 20:51:03,605 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1471760] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-18 20:51:03,611 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1472168] on [sip:0.0.0.0:59473;transport=TLS] destroyed 2016-03-18 20:51:03,617 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-18 20:51:03,623 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1485d28] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-18 20:51:03,629 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1471938] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-18 20:51:03,635 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1472688] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-18 20:51:03,641 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 47071 2016-03-18 20:51:03,646 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,652 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-18 20:51:03,657 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-03-18 20:51:03,664 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb4f1c830) 2016-03-18 20:51:03,670 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-03-18 20:51:03,676 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-03-18 20:51:03,682 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb4f1c818 [0x1470b70], 0x2e0614 [0]) 2016-03-18 20:51:03,687 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-03-18 20:51:03,692 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-03-18 20:51:03,698 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb4f1c818 [0x1470b70], "") 2016-03-18 20:51:03,705 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-18 20:51:03,710 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-18 20:51:03,717 [INFO] [doorpi.sipphone.from_linphone] fdd6:1b70:7f5d::1 2016-03-18 20:51:03,723 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-18 20:51:03,729 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-18 20:51:03,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-03-18 20:51:03,740 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-03-18 20:51:03,746 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-03-18 20:51:03,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb4f1c818 [0x1470b70], 0) 2016-03-18 20:51:03,757 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-18 20:51:03,763 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-18 20:51:03,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-03-18 20:51:03,777 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-03-18 20:51:03,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb4f1c818 [0x1470b70], 120) 2016-03-18 20:51:03,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-03-18 20:51:03,796 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-03-18 20:51:03,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb4f1c818 [0x1470b70], 15) 2016-03-18 20:51:03,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-03-18 20:51:03,814 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2016-03-18 20:51:03,821 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-03-18 20:51:03,827 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-18 20:51:03,835 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-03-18 20:51:03,840 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-18 20:51:03,845 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-03-18 20:51:03,850 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-03-18 20:51:03,856 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-18 20:51:03,861 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-03-18 20:51:03,866 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-18 20:51:03,871 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-03-18 20:51:03,876 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-03-18 20:51:03,882 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-03-18 20:51:03,888 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-03-18 20:51:03,893 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-18 20:51:03,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb4f1c818 [0x1470b70], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-03-18 20:51:03,907 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-03-18 20:51:03,917 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2016-03-18 20:51:03,912 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-18 20:51:03,928 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-18 20:51:03,933 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:03,939 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-03-18 20:51:03,944 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:03,949 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:03,954 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:03,959 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:03,964 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:03,969 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-03-18 20:51:03,975 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-03-18 20:51:03,981 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-03-18 20:51:03,986 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-03-18 20:51:03,994 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4e91698 2016-03-18 20:51:04,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,005 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-18 20:51:04,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4f3d740 2016-03-18 20:51:04,028 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-03-18 20:51:04,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb4f1c818 [0x1470b70], "ALSA: default device") 2016-03-18 20:51:04,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-03-18 20:51:04,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4f3d7a0 2016-03-18 20:51:04,056 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-03-18 20:51:04,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb4f1c818 [0x1470b70], "ALSA: default device") 2016-03-18 20:51:04,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-03-18 20:51:04,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4e91580 2016-03-18 20:51:04,085 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2016-03-18 20:51:04,091 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-03-18 20:51:04,097 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-18 20:51:04,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4e91580 2016-03-18 20:51:04,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1c818 [0x1470b70], "ALSA: default device") 2016-03-18 20:51:04,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-18 20:51:04,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1c818 [0x1470b70], "ALSA: default device") 2016-03-18 20:51:04,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-18 20:51:04,137 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-03-18 20:51:04,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1c818 [0x1470b70], "ALSA: bcm2835 ALSA") 2016-03-18 20:51:04,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-03-18 20:51:04,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1c818 [0x1470b70], "ALSA: bcm2835 ALSA") 2016-03-18 20:51:04,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-18 20:51:04,165 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-03-18 20:51:04,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4f1c818 [0x1470b70], "ALSA: USB PnP Sound Device") 2016-03-18 20:51:04,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-18 20:51:04,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4f1c818 [0x1470b70], "ALSA: USB PnP Sound Device") 2016-03-18 20:51:04,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-18 20:51:04,193 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB PnP Sound Device 2016-03-18 20:51:04,197 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-18 20:51:04,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4f3d890 2016-03-18 20:51:04,214 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-03-18 20:51:04,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,225 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4f3d8c0 2016-03-18 20:51:04,230 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-03-18 20:51:04,236 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-03-18 20:51:04,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,247 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x14704c8) 2016-03-18 20:51:04,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb410 2016-03-18 20:51:04,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,270 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb410 2016-03-18 20:51:04,276 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb410 2016-03-18 20:51:04,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x14708a8) 2016-03-18 20:51:04,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb420 2016-03-18 20:51:04,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb420 2016-03-18 20:51:04,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb420 2016-03-18 20:51:04,314 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1470900) 2016-03-18 20:51:04,320 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,324 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb430 2016-03-18 20:51:04,331 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,336 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb430 2016-03-18 20:51:04,343 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb430 2016-03-18 20:51:04,348 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1470968) 2016-03-18 20:51:04,353 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb440 2016-03-18 20:51:04,364 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,369 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb440 2016-03-18 20:51:04,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb440 2016-03-18 20:51:04,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x14709c0) 2016-03-18 20:51:04,386 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,393 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb450 2016-03-18 20:51:04,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,404 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb450 2016-03-18 20:51:04,409 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb450 2016-03-18 20:51:04,415 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1470a18) 2016-03-18 20:51:04,420 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,426 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb460 2016-03-18 20:51:04,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb460 2016-03-18 20:51:04,442 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb460 2016-03-18 20:51:04,447 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1478140) 2016-03-18 20:51:04,453 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,458 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb470 2016-03-18 20:51:04,464 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,469 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb470 2016-03-18 20:51:04,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb470 2016-03-18 20:51:04,480 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1478198) 2016-03-18 20:51:04,486 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,491 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb480 2016-03-18 20:51:04,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,502 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb480 2016-03-18 20:51:04,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb480 2016-03-18 20:51:04,513 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1478200) 2016-03-18 20:51:04,518 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,524 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb490 2016-03-18 20:51:04,529 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,534 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb490 2016-03-18 20:51:04,540 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb490 2016-03-18 20:51:04,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1478258) 2016-03-18 20:51:04,552 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:04,558 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb4a0 2016-03-18 20:51:04,564 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:04,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb4a0 2016-03-18 20:51:04,575 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb4a0 2016-03-18 20:51:04,581 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb4e916c0 2016-03-18 20:51:04,587 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb410 [0x14704c8]) 2016-03-18 20:51:04,592 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,597 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb410 [0x14704c8]) 2016-03-18 20:51:04,603 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,609 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-03-18 20:51:04,614 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb410 [0x14704c8], 0x2e0614 [0]) 2016-03-18 20:51:04,621 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,627 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb420 [0x14708a8]) 2016-03-18 20:51:04,633 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e380 2016-03-18 20:51:04,640 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb420 [0x14708a8]) 2016-03-18 20:51:04,646 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e380 2016-03-18 20:51:04,652 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-18 20:51:04,657 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb420 [0x14708a8], 0x2e0614 [0]) 2016-03-18 20:51:04,663 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,669 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb430 [0x1470900]) 2016-03-18 20:51:04,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb430 [0x1470900]) 2016-03-18 20:51:04,686 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,691 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-18 20:51:04,697 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb430 [0x1470900], 0x2e0614 [0]) 2016-03-18 20:51:04,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb440 [0x1470968]) 2016-03-18 20:51:04,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e380 2016-03-18 20:51:04,721 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb440 [0x1470968]) 2016-03-18 20:51:04,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e380 2016-03-18 20:51:04,731 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-03-18 20:51:04,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb440 [0x1470968], 0x2e0620 [1]) 2016-03-18 20:51:04,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb450 [0x14709c0]) 2016-03-18 20:51:04,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb450 [0x14709c0]) 2016-03-18 20:51:04,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,771 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-03-18 20:51:04,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb450 [0x14709c0], 0x2e0620 [1]) 2016-03-18 20:51:04,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb460 [0x1470a18]) 2016-03-18 20:51:04,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:04,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb460 [0x1470a18]) 2016-03-18 20:51:04,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:04,812 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-03-18 20:51:04,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb460 [0x1470a18], 0x2e0614 [0]) 2016-03-18 20:51:04,823 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,829 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb470 [0x1478140]) 2016-03-18 20:51:04,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,841 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb470 [0x1478140]) 2016-03-18 20:51:04,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e3a0 2016-03-18 20:51:04,852 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-03-18 20:51:04,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb470 [0x1478140], 0x2e0614 [0]) 2016-03-18 20:51:04,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb480 [0x1478198]) 2016-03-18 20:51:04,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e380 2016-03-18 20:51:04,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb480 [0x1478198]) 2016-03-18 20:51:04,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4e8e380 2016-03-18 20:51:04,891 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-18 20:51:04,897 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb480 [0x1478198], 0x2e0614 [0]) 2016-03-18 20:51:04,904 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,910 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb490 [0x1478200]) 2016-03-18 20:51:04,916 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:04,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb490 [0x1478200]) 2016-03-18 20:51:04,927 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:04,932 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-18 20:51:04,939 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb490 [0x1478200], 0x2e0614 [0]) 2016-03-18 20:51:04,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb4a0 [0x1478258]) 2016-03-18 20:51:04,957 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:04,963 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb4a0 [0x1478258]) 2016-03-18 20:51:04,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:04,973 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-18 20:51:04,979 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb4a0 [0x1478258], 0x2e0614 [0]) 2016-03-18 20:51:04,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:04,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:04,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e91698 2016-03-18 20:51:05,003 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e91698 2016-03-18 20:51:05,014 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-03-18 20:51:05,019 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-03-18 20:51:05,024 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-18 20:51:05,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e91698 2016-03-18 20:51:05,042 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-03-18 20:51:05,047 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-18 20:51:05,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e91698 2016-03-18 20:51:05,065 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-03-18 20:51:05,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4e91698 2016-03-18 20:51:05,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb4f1c818 [0x1470b70], 0x2e0620 [1]) 2016-03-18 20:51:05,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-03-18 20:51:05,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb4f1c818 [0x1470b70], "StaticImage: Static picture") 2016-03-18 20:51:05,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-03-18 20:51:05,104 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-03-18 20:51:05,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb4f1c818 [0x1470b70], "vga") 2016-03-18 20:51:05,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-03-18 20:51:05,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb4f3d9b0 2016-03-18 20:51:05,131 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-03-18 20:51:05,137 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-03-18 20:51:05,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1470428) 2016-03-18 20:51:05,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-18 20:51:05,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ceb4b0 2016-03-18 20:51:05,166 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-18 20:51:05,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ceb4b0 2016-03-18 20:51:05,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ceb4b0 2016-03-18 20:51:05,184 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb4e91760 2016-03-18 20:51:05,189 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb4b0 [0x1470428]) 2016-03-18 20:51:05,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:05,200 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,206 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-03-18 20:51:05,211 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ceb4b0 [0x1470428]) 2016-03-18 20:51:05,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4f1c830 2016-03-18 20:51:05,222 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-03-18 20:51:05,228 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4f1c818 [0x1470b70], 0xb6ceb4b0 [0x1470428], 0x2e0620 [1]) 2016-03-18 20:51:05,235 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x12e08bc 2016-03-18 20:51:05,242 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2016-03-18 20:51:05,246 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 620 2016-03-18 20:51:05,252 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 620) returns ******* 2016-03-18 20:51:05,257 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns 192.168.1.1 2016-03-18 20:51:05,262 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-03-18 20:51:05,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb4f1c818 [0x1470b70]) 2016-03-18 20:51:05,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x1486f68) 2016-03-18 20:51:05,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-03-18 20:51:05,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6ceb4c0 2016-03-18 20:51:05,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-03-18 20:51:05,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6ceb4c0 2016-03-18 20:51:05,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6ceb4c0 2016-03-18 20:51:05,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6ceb4c0 2016-03-18 20:51:05,314 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-03-18 20:51:05,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-03-18 20:51:05,325 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x1484218) 2016-03-18 20:51:05,333 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-03-18 20:51:05,338 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6ceb4d0 2016-03-18 20:51:05,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-03-18 20:51:05,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6ceb4d0 2016-03-18 20:51:05,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6ceb4d0 2016-03-18 20:51:05,360 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6ceb4d0 2016-03-18 20:51:05,366 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6ceb4c0 [0x1486f68], 0xb6ceb4d0 [0x1484218]) 2016-03-18 20:51:05,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-03-18 20:51:05,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6ceb4c0 [0x1486f68], "sip:192.168.1.1") 2016-03-18 20:51:05,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-03-18 20:51:05,392 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6ceb4c0 [0x1486f68], 0x2e0620 [1]) 2016-03-18 20:51:05,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-03-18 20:51:05,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb4f1c818 [0x1470b70], 0xb6ceb4c0 [0x1486f68]) 2016-03-18 20:51:05,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x12e08bc 2016-03-18 20:51:05,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb4f1c818 [0x1470b70], 0xb6ceb4c0 [0x1486f68]) 2016-03-18 20:51:05,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-03-18 20:51:05,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb4f1c818 [0x1470b70], "620", "(null)", "blabla", "(null)", "(null)", "192.168.1.1") 2016-03-18 20:51:05,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x1489048) 2016-03-18 20:51:05,443 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-03-18 20:51:05,448 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6ceb4e0 2016-03-18 20:51:05,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-03-18 20:51:05,460 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6ceb4e0 2016-03-18 20:51:05,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6ceb4e0 2016-03-18 20:51:05,472 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6ceb4e0 2016-03-18 20:51:05,477 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb4f1c818 [0x1470b70], 0xb6ceb4e0 [0x1489048]) 2016-03-18 20:51:05,483 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-03-18 20:51:05,489 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-03-18 20:51:05,494 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-03-18 20:51:05,499 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-03-18 20:51:05,504 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-03-18 20:51:05,510 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-03-18 20:51:05,515 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-03-18 20:51:05,522 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-03-18 20:51:05,528 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-03-18 20:51:05,534 [WARNING] [root] section dummy_InputPins not found in configfile 2016-03-18 20:51:05,540 [WARNING] [root] section DTMF not found in configfile 2016-03-18 20:51:05,545 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-18 20:51:05,551 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-03-18 20:51:05,556 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-03-18 20:51:05,565 [DEBUG] [doorpi.action.handler] [3WWZ36] fire for event OnStartup this actions [] 2016-03-18 20:51:05,574 [TRACE] [doorpi.action.handler] [3WWZ36] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-03-18 20:51:05,579 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-03-18 20:51:05,570 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-03-18 20:51:06,593 [TRACE] [doorpi.action.handler] [3WWZ36] finished fire_event for event_name OnStartup 2016-03-18 20:51:06,599 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-03-18 20:51:06,606 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-03-18 20:51:06,612 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-03-18 20:51:06,621 [INFO] [doorpi.doorpi] Weburl is http://tuer/ 2016-03-18 20:51:06,616 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-03-18 20:51:06,634 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-03-18 20:51:06,640 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-18 20:51:06,646 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.174 2016-03-18 20:51:06,652 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-03-18 20:51:06,659 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-18 20:51:06,665 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-18 20:51:06,672 [INFO] [doorpi.sipphone.from_linphone] fdd6:1b70:7f5d::1 2016-03-18 20:51:06,679 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-18 20:51:06,686 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-18 20:51:06,693 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x1486f68] about to register (LinphoneCore version: 3.9.0) 2016-03-18 20:51:06,713 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-03-18 20:51:06,718 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: starting resolution of 192.168.1.1 2016-03-18 20:51:06,724 [INFO] [doorpi.sipphone.from_linphone] channel 0x1499228: state RES_IN_PROGRESS 2016-03-18 20:51:06,729 [INFO] [doorpi.sipphone.from_linphone] transaction [0x148f1b8] channel state changed to [RES_IN_PROGRESS] 2016-03-18 20:51:06,735 [INFO] [doorpi.sipphone.from_linphone] channel 0x1499228: state RES_DONE 2016-03-18 20:51:06,741 [INFO] [doorpi.sipphone.from_linphone] transaction [0x148f1b8] channel state changed to [RES_DONE] 2016-03-18 20:51:06,746 [INFO] [doorpi.sipphone.from_linphone] channel 0x1499228: state CONNECTING 2016-03-18 20:51:06,752 [INFO] [doorpi.sipphone.from_linphone] transaction [0x148f1b8] channel state changed to [CONNECTING] 2016-03-18 20:51:06,757 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.1:5060] 2016-03-18 20:51:06,763 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-03-18 20:51:06,768 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.174:5060 2016-03-18 20:51:06,774 [INFO] [doorpi.sipphone.from_linphone] channel 0x1499228: state READY 2016-03-18 20:51:06,779 [INFO] [doorpi.sipphone.from_linphone] transaction [0x148f1b8] channel state changed to [READY] 2016-03-18 20:51:06,785 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x148f1b8], from state [INIT] to [TRYING] 2016-03-18 20:51:06,791 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: message sent to [UDP://192.168.1.1:5060], size: [506] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.dtq5-qqiZ;rport From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" CSeq: 20 REGISTER Call-ID: rGOCPWrCvW 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) 2016-03-18 20:51:06,800 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-03-18 20:51:06,807 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x148b158] takes ownership of transaction [0x148f1b8] 2016-03-18 20:51:06,812 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1486f68] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-03-18 20:51:06,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1470b70, 0x1486f68, 1, "Registration in progress") 2016-03-18 20:51:06,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-18 20:51:06,830 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [registration_state_changed] 2016-03-18 20:51:06,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x1470b70, 0x1) 2016-03-18 20:51:06,893 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-03-18 20:51:06,899 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [network_reachable] 2016-03-18 20:51:06,905 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: received [361] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.dtq5-qqiZ;rport=5060 From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" ;tag=442EF4416E168458 Call-ID: rGOCPWrCvW CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="85762A2958616AD5" User-Agent: FRITZ!OS Content-Length: 0 2016-03-18 20:51:06,929 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228] [361] bytes parsed 2016-03-18 20:51:06,935 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: discovered public ip and port are [192.168.1.174:5060] 2016-03-18 20:51:06,940 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-18 20:51:06,945 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x148f1b8], from state [TRYING] to [COMPLETED] 2016-03-18 20:51:06,951 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=620, realm= 2016-03-18 20:51:06,957 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [620] realm [fritz.box] 2016-03-18 20:51:06,963 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1489e70], from state [INIT] to [TRYING] 2016-03-18 20:51:06,969 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: message sent to [UDP://192.168.1.1:5060], size: [659] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.HRHnvs2oF;rport From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" CSeq: 21 REGISTER Call-ID: rGOCPWrCvW Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="85762A2958616AD5", username="620", uri="sip:192.168.1.1", response="a213beed7369bf87c93ac274ccd49e41" 2016-03-18 20:51:06,978 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-03-18 20:51:06,983 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-18 20:51:07,041 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: received [361] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.HRHnvs2oF;rport=5060 From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" ;tag=14D7A692C93BCE6E Call-ID: rGOCPWrCvW CSeq: 21 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="636D7998FDB90D47" User-Agent: FRITZ!OS Content-Length: 0 2016-03-18 20:51:07,065 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228] [361] bytes parsed 2016-03-18 20:51:07,070 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-18 20:51:07,076 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1489e70], from state [TRYING] to [COMPLETED] 2016-03-18 20:51:07,082 [WARNING] [doorpi.sipphone.from_linphone] Authentication is failing constantly, will retry later 2016-03-18 20:51:07,087 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2016-03-18 20:51:07,094 [INFO] [doorpi.sipphone.from_linphone] Register refresher [401] reason [Unauthorized] for proxy [sip:192.168.1.1] 2016-03-18 20:51:07,099 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x1486f68] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationFailed] 2016-03-18 20:51:07,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x1470b70, 0x1486f68, 4, "Unauthorized") 2016-03-18 20:51:07,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-18 20:51:07,117 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [registration_state_changed] 2016-03-18 20:51:07,123 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=620, realm=fritz.box 2016-03-18 20:51:07,128 [INFO] [doorpi.sipphone.from_linphone] fritz.box/620/192.168.1.1 authentication fails. 2016-03-18 20:51:07,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_auth_info_requested(0x1470b70, "fritz.box", "620", "192.168.1.1") 2016-03-18 20:51:07,141 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_auth_info_requested 2016-03-18 20:51:07,146 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [auth_info_requested] 2016-03-18 20:51:12,005 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x148f1b8], from state [COMPLETED] to [TERMINATED] 2016-03-18 20:51:12,011 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x148f1b8] terminated 2016-03-18 20:51:12,174 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x1489e70], from state [COMPLETED] to [TERMINATED] 2016-03-18 20:51:12,179 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x1489e70] terminated 2016-03-18 20:52:07,136 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=620, realm=fritz.box 2016-03-18 20:52:07,142 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [620] realm [fritz.box] 2016-03-18 20:52:07,149 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x14f6770], from state [INIT] to [TRYING] 2016-03-18 20:52:07,156 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: message sent to [UDP://192.168.1.1:5060], size: [659] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.LoKHgokyv;rport From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" CSeq: 22 REGISTER Call-ID: rGOCPWrCvW Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="636D7998FDB90D47", username="620", uri="sip:192.168.1.1", response="5b22f1fe5ccef637968feaceb11fb063" 2016-03-18 20:52:07,217 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: received [361] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.LoKHgokyv;rport=5060 From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" ;tag=26FD7A6110CF0AE7 Call-ID: rGOCPWrCvW CSeq: 22 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="EF5F952555AE6D5B" User-Agent: FRITZ!OS Content-Length: 0 2016-03-18 20:52:07,243 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228] [361] bytes parsed 2016-03-18 20:52:07,249 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-18 20:52:07,254 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x14f6770], from state [TRYING] to [COMPLETED] 2016-03-18 20:52:07,261 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=620, realm=fritz.box 2016-03-18 20:52:07,267 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [620] realm [fritz.box] 2016-03-18 20:52:07,273 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x148af70], from state [INIT] to [TRYING] 2016-03-18 20:52:07,280 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: message sent to [UDP://192.168.1.1:5060], size: [659] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.cun669v2K;rport From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" CSeq: 23 REGISTER Call-ID: rGOCPWrCvW Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="EF5F952555AE6D5B", username="620", uri="sip:192.168.1.1", response="611d7c0e4d38b1b05a0b3c75ffe59607" 2016-03-18 20:52:07,341 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228]: received [361] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.174:5060;branch=z9hG4bK.cun669v2K;rport=5060 From: "DoorPi" ;tag=mXgIOhCVG To: "DoorPi" ;tag=507453CBA452A4D4 Call-ID: rGOCPWrCvW CSeq: 23 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="4F655DF8D0904A06" User-Agent: FRITZ!OS Content-Length: 0 2016-03-18 20:52:07,367 [INFO] [doorpi.sipphone.from_linphone] channel [0x1499228] [361] bytes parsed 2016-03-18 20:52:07,373 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-18 20:52:07,378 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x148af70], from state [TRYING] to [COMPLETED] 2016-03-18 20:52:07,384 [WARNING] [doorpi.sipphone.from_linphone] Authentication is failing constantly, will retry later 2016-03-18 20:52:07,390 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2016-03-18 20:52:07,396 [INFO] [doorpi.sipphone.from_linphone] Register refresher [401] reason [Unauthorized] for proxy [sip:192.168.1.1] 2016-03-18 20:52:07,402 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=620, realm=fritz.box 2016-03-18 20:52:07,408 [INFO] [doorpi.sipphone.from_linphone] fritz.box/620/192.168.1.1 authentication fails. 2016-03-18 20:52:07,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_auth_info_requested(0x1470b70, "fritz.box", "620", "192.168.1.1") 2016-03-18 20:52:07,419 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_auth_info_requested 2016-03-18 20:52:07,425 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x1470b70] notifying [auth_info_requested] 2016-03-18 20:52:12,288 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x14f6770], from state [COMPLETED] to [TERMINATED] 2016-03-18 20:52:12,295 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x14f6770] terminated 2016-03-18 20:52:12,455 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x148af70], from state [COMPLETED] to [TERMINATED] 2016-03-18 20:52:12,461 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x148af70] terminated