2016-11-01 10:36:50,893 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-11-01 10:36:50,901 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-11-01 10:36:50,907 [DEBUG] [doorpi.doorpi] run 2016-11-01 10:36:50,912 [DEBUG] [doorpi.doorpi] prepare 2016-11-01 10:36:50,917 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-11-01 10:36:50,934 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-11-01 10:36:50,967 [DEBUG] [doorpi.conf.config_object] __init__ 2016-11-01 10:36:50,971 [TRACE] [doorpi.conf.config_object] get_from_config 2016-11-01 10:36:50,994 [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-11-01 10:36:50,998 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-11-01 10:36:51,006 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-11-01 10:36:51,017 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-11-01 10:36:51,022 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-11-01 10:36:51,026 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-11-01 10:36:51,031 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-11-01 10:36:51,037 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-11-01 10:36:51,041 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-11-01 10:36:51,046 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-11-01 10:36:51,051 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-11-01 10:36:51,056 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-11-01 10:36:51,060 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-11-01 10:36:51,065 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-11-01 10:36:51,070 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-11-01 10:36:51,075 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-11-01 10:36:51,083 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-11-01 10:36:51,092 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-11-01 10:36:51,107 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-11-01 10:36:51,111 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-11-01 10:36:51,116 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-11-01 10:36:51,122 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-11-01 10:36:51,133 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-11-01 10:36:51,137 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-11-01 10:36:51,142 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-11-01 10:36:51,147 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-11-01 10:36:51,152 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-11-01 10:36:51,156 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-11-01 10:36:51,161 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-11-01 10:36:51,169 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-11-01 10:36:51,174 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-11-01 10:36:51,182 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-11-01 10:36:51,186 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-11-01 10:36:51,194 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-11-01 10:36:51,199 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-11-01 10:36:51,207 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-11-01 10:36:51,211 [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-11-01 10:36:51,219 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-11-01 10:36:51,223 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-11-01 10:36:51,229 [WARNING] [root] section ReadPermission not found in configfile 2016-11-01 10:36:51,233 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-11-01 10:36:51,238 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-11-01 10:36:51,243 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-11-01 10:36:51,248 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-11-01 10:36:51,253 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_keyboard', 'Group', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-11-01 10:36:51,260 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-11-01 10:36:51,265 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-11-01 10:36:51,269 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-11-01 10:36:51,275 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-11-01 10:36:51,280 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-11-01 10:36:51,288 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,292 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-11-01 10:36:51,301 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,306 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,298 [DEBUG] [doorpi.action.handler] [U2IJEH] fire for event OnWebServerStart this actions [] 2016-11-01 10:36:51,315 [TRACE] [doorpi.action.handler] [U2IJEH] try to fire action WebServerStartupAction with args () and kwargs {} 2016-11-01 10:36:51,318 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,327 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,330 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-11-01 10:36:51,333 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,342 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,347 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,352 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,357 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,362 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,367 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,371 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,376 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,381 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,386 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-11-01 10:36:51,391 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-11-01 10:36:51,396 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-11-01 10:36:51,401 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-11-01 10:36:51,406 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2016-11-01 10:36:51,411 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['22'] 2016-11-01 10:36:51,416 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-11-01 10:36:51,421 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2016-11-01 10:36:51,426 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2016-11-01 10:36:51,431 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-11-01 10:36:51,437 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-11-01 10:36:51,443 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-11-01 10:36:51,455 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['22'], output_pins = [], bouncetime = 200.0, polarity = 0) 2016-11-01 10:36:51,461 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2016-11-01 10:36:51,467 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-11-01 10:36:51,480 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,489 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-11-01 10:36:51,496 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,509 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_22 from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,513 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_22 and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,518 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.22 from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,523 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.22 and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,539 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,543 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,548 [TRACE] [doorpi.action.handler] register Event OnKeyUp_22 from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,553 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_22 and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,558 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.22 from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,568 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.22 and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,573 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,586 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,591 [TRACE] [doorpi.action.handler] register Event OnKeyDown_22 from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,596 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_22 and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,600 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.22 from doorpi.keyboard.from_gpio 2016-11-01 10:36:51,618 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.22 and registered source doorpi.keyboard.from_gpio 2016-11-01 10:36:51,622 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-11-01 10:36:51,628 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-11-01 10:36:51,632 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2016-11-01 10:36:51,640 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-11-01 10:36:51,662 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-11-01 10:36:51,671 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-11-01 10:36:51,681 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-11-01 10:36:51,803 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-11-01 10:36:51,813 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-11-01 10:36:51,825 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-11-01 10:36:51,987 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-11-01 10:36:52,008 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-11-01 10:36:52,013 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-11-01 10:36:52,021 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-11-01 10:36:52,031 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-11-01 10:36:52,040 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-11-01 10:36:52,050 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-11-01 10:36:52,058 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-11-01 10:36:52,062 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-11-01 10:36:52,068 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,072 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-11-01 10:36:52,077 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,082 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,087 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,092 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,096 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,101 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,106 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,111 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,116 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,121 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,126 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,131 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,135 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,140 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,145 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,150 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,154 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,159 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,164 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,169 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,174 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,179 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-11-01 10:36:52,184 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-11-01 10:36:52,189 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-11-01 10:36:52,194 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-11-01 10:36:52,200 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,204 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-11-01 10:36:52,210 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,214 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,219 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,224 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,229 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,234 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,239 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,244 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,249 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,253 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,259 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,262 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,267 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,272 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,277 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,281 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,287 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,291 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,296 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,301 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,306 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,311 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,315 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,320 [WARNING] [root] section DTMF not found in configfile 2016-11-01 10:36:52,325 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-11-01 10:36:52,330 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,335 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-11-01 10:36:52,340 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,345 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,350 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,354 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,359 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,363 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,368 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,372 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,377 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,381 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,386 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,391 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,396 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,401 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,406 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,410 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,415 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,419 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,424 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-11-01 10:36:52,432 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-11-01 10:36:52,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb46e38c0 2016-11-01 10:36:52,436 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-11-01 10:36:52,452 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-11-01 10:36:52,459 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb46e38c0 2016-11-01 10:36:52,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb4701b70, "(null)", "(null)") 2016-11-01 10:36:52,468 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-11-01 10:36:52,473 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2016-11-01 10:36:52,479 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-11-01 10:36:52,483 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-11-01 10:36:52,545 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-11-01 10:36:52,648 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-11-01 10:36:52,827 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: snd_rpi_wsp' added 2016-11-01 10:36:52,832 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-11-01 10:36:52,838 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-11-01 10:36:52,842 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-11-01 10:36:52,847 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-11-01 10:36:52,853 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-11-01 10:36:52,858 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-11-01 10:36:52,863 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x22d2610] registered on core [0x22fd208] 2016-11-01 10:36:52,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x22d2610, 1, "Starting up") 2016-11-01 10:36:52,875 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-11-01 10:36:52,881 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x22d2610] notifying [global_state_changed] 2016-11-01 10:36:52,886 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-11-01 10:36:52,892 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-11-01 10:36:52,896 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-11-01 10:36:52,902 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-11-01 10:36:52,906 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2016-11-01 10:36:52,912 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-11-01 10:36:52,917 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-11-01 10:36:52,922 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-11-01 10:36:52,927 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-11-01 10:36:52,932 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-11-01 10:36:52,937 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-11-01 10:36:52,943 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-11-01 10:36:52,948 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-11-01 10:36:52,953 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-11-01 10:36:52,958 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-11-01 10:36:52,963 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-11-01 10:36:52,968 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-11-01 10:36:52,973 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-11-01 10:36:52,978 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-11-01 10:36:52,983 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-11-01 10:36:52,988 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-11-01 10:36:52,993 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-11-01 10:36:52,998 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-11-01 10:36:53,004 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-11-01 10:36:53,009 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-11-01 10:36:53,014 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-11-01 10:36:53,019 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-11-01 10:36:53,024 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-11-01 10:36:53,029 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-11-01 10:36:53,034 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-11-01 10:36:53,039 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-11-01 10:36:53,044 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-11-01 10:36:53,049 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-11-01 10:36:53,054 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-11-01 10:36:53,059 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-11-01 10:36:53,064 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-11-01 10:36:53,069 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-11-01 10:36:53,074 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-11-01 10:36:53,079 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-11-01 10:36:53,084 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-11-01 10:36:53,089 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-11-01 10:36:53,094 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-11-01 10:36:53,100 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-11-01 10:36:53,104 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-11-01 10:36:53,109 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-11-01 10:36:53,114 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-11-01 10:36:53,119 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-11-01 10:36:53,125 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-11-01 10:36:53,129 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-11-01 10:36:53,134 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-11-01 10:36:53,139 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-11-01 10:36:53,145 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-11-01 10:36:53,150 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-11-01 10:36:53,155 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-11-01 10:36:53,160 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-11-01 10:36:53,165 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-11-01 10:36:53,170 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-11-01 10:36:53,175 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-11-01 10:36:53,180 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-11-01 10:36:53,185 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-11-01 10:36:53,190 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-11-01 10:36:53,195 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-11-01 10:36:53,200 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-11-01 10:36:53,205 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-11-01 10:36:53,210 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-11-01 10:36:53,216 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-11-01 10:36:53,221 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-11-01 10:36:53,226 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-11-01 10:36:53,231 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-11-01 10:36:53,236 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-11-01 10:36:53,241 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-11-01 10:36:53,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-11-01 10:36:53,251 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-11-01 10:36:53,257 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-11-01 10:36:53,262 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-11-01 10:36:53,267 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-11-01 10:36:53,272 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-11-01 10:36:53,277 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-11-01 10:36:53,282 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-11-01 10:36:53,287 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-11-01 10:36:53,292 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-11-01 10:36:53,297 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-11-01 10:36:53,302 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-11-01 10:36:53,308 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-11-01 10:36:53,313 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,318 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,323 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,328 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x22d2610, 2, "(null)") 2016-11-01 10:36:53,334 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-11-01 10:36:53,340 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x22d2610] notifying [configuring_status] 2016-11-01 10:36:53,345 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-11-01 10:36:53,350 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-11-01 10:36:53,355 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-11-01 10:36:53,361 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-11-01 10:36:53,365 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-11-01 10:36:53,371 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-11-01 10:36:53,375 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-11-01 10:36:53,380 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-11-01 10:36:53,385 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-11-01 10:36:53,390 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-11-01 10:36:53,395 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,401 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,405 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,411 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,416 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-11-01 10:36:53,421 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-11-01 10:36:53,426 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-11-01 10:36:53,431 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,436 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,441 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-11-01 10:36:53,446 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-11-01 10:36:53,452 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,457 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-11-01 10:36:53,462 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x22fd698] on [sip:0.0.0.0:5060;transport=UDP] 2016-11-01 10:36:53,468 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x22fd8c8] on [sip:0.0.0.0:5060;transport=TCP] 2016-11-01 10:36:53,473 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x22fda90] on [sip:0.0.0.0:-1;transport=TLS] 2016-11-01 10:36:53,478 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 42153 2016-11-01 10:36:53,483 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,488 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,500 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x22d2610, 2, "Ready") 2016-11-01 10:36:53,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-11-01 10:36:53,512 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x22d2610] notifying [global_state_changed] 2016-11-01 10:36:53,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb46e38c0 2016-11-01 10:36:53,522 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-11-01 10:36:53,528 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb46e38c0 [0x22d2610], "DoorPi ") 2016-11-01 10:36:53,536 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-11-01 10:36:53,542 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-11-01 10:36:53,549 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-11-01 10:36:53,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb46e38c0 [0x22d2610], 2) 2016-11-01 10:36:53,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-11-01 10:36:53,562 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-11-01 10:36:53,578 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-11-01 10:36:53,583 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb46e38c0 [0x22d2610], 0x2e0614 [0]) 2016-11-01 10:36:53,589 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-11-01 10:36:53,594 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-11-01 10:36:53,599 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-11-01 10:36:53,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-11-01 10:36:53,610 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb46e38d8 2016-11-01 10:36:53,616 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb46e38c0 [0x22d2610], 0xb46e38d8 [0xb46e38e0]) 2016-11-01 10:36:53,622 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x22fd698] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-11-01 10:36:53,627 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x22fd8c8] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-11-01 10:36:53,633 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x22fda90] on [sip:0.0.0.0:42153;transport=TLS] destroyed 2016-11-01 10:36:53,638 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-11-01 10:36:53,643 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21deb10] on [sip:0.0.0.0:5060;transport=UDP] 2016-11-01 10:36:53,648 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x22f4c28] on [sip:0.0.0.0:5060;transport=TCP] 2016-11-01 10:36:53,654 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x22fd890] on [sip:0.0.0.0:-1;transport=TLS] 2016-11-01 10:36:53,660 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 39131 2016-11-01 10:36:53,664 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,670 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-11-01 10:36:53,674 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-11-01 10:36:53,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb46e38d8) 2016-11-01 10:36:53,687 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-11-01 10:36:53,692 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-11-01 10:36:53,697 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb46e38c0 [0x22d2610], 0x2e0614 [0]) 2016-11-01 10:36:53,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-11-01 10:36:53,709 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-11-01 10:36:53,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb46e38c0 [0x22d2610], "") 2016-11-01 10:36:53,721 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-11-01 10:36:53,726 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-11-01 10:36:53,734 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-11-01 10:36:53,739 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-11-01 10:36:53,744 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-11-01 10:36:53,751 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-11-01 10:36:53,755 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-11-01 10:36:53,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb46e38c0 [0x22d2610], 0) 2016-11-01 10:36:53,767 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-11-01 10:36:53,771 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-11-01 10:36:53,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-11-01 10:36:53,785 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-11-01 10:36:53,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb46e38c0 [0x22d2610], 120) 2016-11-01 10:36:53,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-11-01 10:36:53,800 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-11-01 10:36:53,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb46e38c0 [0x22d2610], 15) 2016-11-01 10:36:53,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-11-01 10:36:53,817 [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-11-01 10:36:53,825 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-11-01 10:36:53,830 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-11-01 10:36:53,839 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-11-01 10:36:53,844 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-11-01 10:36:53,849 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-11-01 10:36:53,854 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-11-01 10:36:53,859 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-11-01 10:36:53,863 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-11-01 10:36:53,868 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-11-01 10:36:53,871 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-11-01 10:36:53,876 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-11-01 10:36:53,882 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-11-01 10:36:53,887 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-11-01 10:36:53,892 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-11-01 10:36:53,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb46e38c0 [0x22d2610], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-11-01 10:36:53,906 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-11-01 10:36:53,915 [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-11-01 10:36:53,910 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-11-01 10:36:53,925 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-11-01 10:36:53,930 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:53,934 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-11-01 10:36:53,940 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:53,944 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:53,949 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:53,956 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:53,960 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:53,966 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-11-01 10:36:53,971 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-11-01 10:36:53,976 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-11-01 10:36:53,981 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-11-01 10:36:53,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:53,998 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4634e40 2016-11-01 10:36:54,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,002 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-11-01 10:36:54,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4632890 2016-11-01 10:36:54,024 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-11-01 10:36:54,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb46e38c0 [0x22d2610], "ALSA: default device") 2016-11-01 10:36:54,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-11-01 10:36:54,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb46328c0 2016-11-01 10:36:54,052 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-11-01 10:36:54,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb46e38c0 [0x22d2610], "ALSA: default device") 2016-11-01 10:36:54,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-11-01 10:36:54,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4634d00 2016-11-01 10:36:54,080 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2016-11-01 10:36:54,086 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-11-01 10:36:54,091 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-11-01 10:36:54,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4634d00 2016-11-01 10:36:54,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb46e38c0 [0x22d2610], "ALSA: default device") 2016-11-01 10:36:54,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-11-01 10:36:54,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb46e38c0 [0x22d2610], "ALSA: default device") 2016-11-01 10:36:54,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-11-01 10:36:54,130 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-11-01 10:36:54,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb46e38c0 [0x22d2610], "ALSA: snd_rpi_wsp") 2016-11-01 10:36:54,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-11-01 10:36:54,146 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb46e38c0 [0x22d2610], "ALSA: snd_rpi_wsp") 2016-11-01 10:36:54,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-11-01 10:36:54,157 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: snd_rpi_wsp 2016-11-01 10:36:54,162 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-11-01 10:36:54,166 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4632950 2016-11-01 10:36:54,176 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-11-01 10:36:54,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,187 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4632920 2016-11-01 10:36:54,193 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-11-01 10:36:54,198 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-11-01 10:36:54,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2bd8) 2016-11-01 10:36:54,214 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92410 2016-11-01 10:36:54,228 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,233 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92410 2016-11-01 10:36:54,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92410 2016-11-01 10:36:54,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2c48) 2016-11-01 10:36:54,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,255 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92420 2016-11-01 10:36:54,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92420 2016-11-01 10:36:54,273 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92420 2016-11-01 10:36:54,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2cb0) 2016-11-01 10:36:54,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92430 2016-11-01 10:36:54,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92430 2016-11-01 10:36:54,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92430 2016-11-01 10:36:54,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2d18) 2016-11-01 10:36:54,317 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,322 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92440 2016-11-01 10:36:54,328 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,334 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92440 2016-11-01 10:36:54,339 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92440 2016-11-01 10:36:54,345 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2d70) 2016-11-01 10:36:54,351 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,357 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92450 2016-11-01 10:36:54,362 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,368 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92450 2016-11-01 10:36:54,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92450 2016-11-01 10:36:54,379 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2dc8) 2016-11-01 10:36:54,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,390 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92460 2016-11-01 10:36:54,396 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92460 2016-11-01 10:36:54,406 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92460 2016-11-01 10:36:54,412 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2e20) 2016-11-01 10:36:54,417 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,423 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92470 2016-11-01 10:36:54,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,434 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92470 2016-11-01 10:36:54,440 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92470 2016-11-01 10:36:54,446 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2e78) 2016-11-01 10:36:54,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,457 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92480 2016-11-01 10:36:54,462 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,468 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92480 2016-11-01 10:36:54,473 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92480 2016-11-01 10:36:54,477 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2ee0) 2016-11-01 10:36:54,483 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,489 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c92490 2016-11-01 10:36:54,494 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,500 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c92490 2016-11-01 10:36:54,505 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c92490 2016-11-01 10:36:54,511 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2f38) 2016-11-01 10:36:54,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:54,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c924a0 2016-11-01 10:36:54,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:54,532 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c924a0 2016-11-01 10:36:54,541 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c924a0 2016-11-01 10:36:54,546 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb4634e68 2016-11-01 10:36:54,552 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92410 [0x22d2bd8]) 2016-11-01 10:36:54,557 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,563 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92410 [0x22d2bd8]) 2016-11-01 10:36:54,568 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,573 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-11-01 10:36:54,578 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92410 [0x22d2bd8], 0x2e0614 [0]) 2016-11-01 10:36:54,584 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92420 [0x22d2c48]) 2016-11-01 10:36:54,596 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47008a0 2016-11-01 10:36:54,603 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92420 [0x22d2c48]) 2016-11-01 10:36:54,608 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47008a0 2016-11-01 10:36:54,613 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-11-01 10:36:54,624 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92420 [0x22d2c48], 0x2e0614 [0]) 2016-11-01 10:36:54,630 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,637 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92430 [0x22d2cb0]) 2016-11-01 10:36:54,642 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,646 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92430 [0x22d2cb0]) 2016-11-01 10:36:54,652 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,658 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-11-01 10:36:54,663 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92430 [0x22d2cb0], 0x2e0614 [0]) 2016-11-01 10:36:54,669 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,674 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92440 [0x22d2d18]) 2016-11-01 10:36:54,680 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47008a0 2016-11-01 10:36:54,686 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92440 [0x22d2d18]) 2016-11-01 10:36:54,692 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47008a0 2016-11-01 10:36:54,697 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-11-01 10:36:54,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92440 [0x22d2d18], 0x2e0620 [1]) 2016-11-01 10:36:54,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92450 [0x22d2d70]) 2016-11-01 10:36:54,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92450 [0x22d2d70]) 2016-11-01 10:36:54,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,742 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-11-01 10:36:54,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92450 [0x22d2d70], 0x2e0620 [1]) 2016-11-01 10:36:54,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92460 [0x22d2dc8]) 2016-11-01 10:36:54,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:54,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92460 [0x22d2dc8]) 2016-11-01 10:36:54,776 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:54,782 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-11-01 10:36:54,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92460 [0x22d2dc8], 0x2e0614 [0]) 2016-11-01 10:36:54,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92470 [0x22d2e20]) 2016-11-01 10:36:54,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92470 [0x22d2e20]) 2016-11-01 10:36:54,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4700720 2016-11-01 10:36:54,821 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-11-01 10:36:54,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92470 [0x22d2e20], 0x2e0614 [0]) 2016-11-01 10:36:54,833 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,839 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92480 [0x22d2e78]) 2016-11-01 10:36:54,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47008a0 2016-11-01 10:36:54,850 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92480 [0x22d2e78]) 2016-11-01 10:36:54,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47008a0 2016-11-01 10:36:54,861 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-11-01 10:36:54,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92480 [0x22d2e78], 0x2e0614 [0]) 2016-11-01 10:36:54,873 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92490 [0x22d2ee0]) 2016-11-01 10:36:54,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:54,890 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c92490 [0x22d2ee0]) 2016-11-01 10:36:54,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:54,901 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-11-01 10:36:54,906 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c92490 [0x22d2ee0], 0x2e0614 [0]) 2016-11-01 10:36:54,913 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,918 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c924a0 [0x22d2f38]) 2016-11-01 10:36:54,924 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:54,929 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c924a0 [0x22d2f38]) 2016-11-01 10:36:54,935 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:54,940 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-11-01 10:36:54,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c924a0 [0x22d2f38], 0x2e0614 [0]) 2016-11-01 10:36:54,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:54,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,964 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4634e40 2016-11-01 10:36:54,970 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:54,975 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4634e40 2016-11-01 10:36:54,981 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-11-01 10:36:54,986 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-11-01 10:36:54,991 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-11-01 10:36:54,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4634e40 2016-11-01 10:36:55,008 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-11-01 10:36:55,013 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-11-01 10:36:55,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4634e40 2016-11-01 10:36:55,028 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-11-01 10:36:55,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4634e40 2016-11-01 10:36:55,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb46e38c0 [0x22d2610], 0x2e0620 [1]) 2016-11-01 10:36:55,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-11-01 10:36:55,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb46e38c0 [0x22d2610], "StaticImage: Static picture") 2016-11-01 10:36:55,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-11-01 10:36:55,068 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-11-01 10:36:55,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb46e38c0 [0x22d2610], "vga") 2016-11-01 10:36:55,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-11-01 10:36:55,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb4632a40 2016-11-01 10:36:55,093 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-11-01 10:36:55,099 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-11-01 10:36:55,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x22d2b38) 2016-11-01 10:36:55,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-11-01 10:36:55,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c924b0 2016-11-01 10:36:55,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-11-01 10:36:55,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c924b0 2016-11-01 10:36:55,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c924b0 2016-11-01 10:36:55,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb4634f08 2016-11-01 10:36:55,152 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c924b0 [0x22d2b38]) 2016-11-01 10:36:55,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:55,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-11-01 10:36:55,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c924b0 [0x22d2b38]) 2016-11-01 10:36:55,178 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46e38d8 2016-11-01 10:36:55,183 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-11-01 10:36:55,189 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46e38c0 [0x22d2610], 0xb6c924b0 [0x22d2b38], 0x2e0620 [1]) 2016-11-01 10:36:55,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x216a8bc 2016-11-01 10:36:55,202 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2016-11-01 10:36:55,207 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 621 2016-11-01 10:36:55,212 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 621) returns ******* 2016-11-01 10:36:55,217 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns fritz.box 2016-11-01 10:36:55,222 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-11-01 10:36:55,228 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb46e38c0 [0x22d2610]) 2016-11-01 10:36:55,235 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x2300ce8) 2016-11-01 10:36:55,240 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-11-01 10:36:55,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6c924c0 2016-11-01 10:36:55,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-11-01 10:36:55,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6c924c0 2016-11-01 10:36:55,262 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6c924c0 2016-11-01 10:36:55,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6c924c0 2016-11-01 10:36:55,273 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-11-01 10:36:55,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-11-01 10:36:55,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x22f36a8) 2016-11-01 10:36:55,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-11-01 10:36:55,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6c924d0 2016-11-01 10:36:55,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-11-01 10:36:55,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6c924d0 2016-11-01 10:36:55,314 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6c924d0 2016-11-01 10:36:55,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6c924d0 2016-11-01 10:36:55,325 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6c924c0 [0x2300ce8], 0xb6c924d0 [0x22f36a8]) 2016-11-01 10:36:55,329 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-11-01 10:36:55,336 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6c924c0 [0x2300ce8], "sip:192.168.1.1") 2016-11-01 10:36:55,343 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-11-01 10:36:55,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6c924c0 [0x2300ce8], 0x2e0620 [1]) 2016-11-01 10:36:55,356 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-11-01 10:36:55,362 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb46e38c0 [0x22d2610], 0xb6c924c0 [0x2300ce8]) 2016-11-01 10:36:55,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x216a8bc 2016-11-01 10:36:55,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb46e38c0 [0x22d2610], 0xb6c924c0 [0x2300ce8]) 2016-11-01 10:36:55,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-11-01 10:36:55,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb46e38c0 [0x22d2610], "621", "(null)", "bandit1100", "(null)", "(null)", "fritz.box") 2016-11-01 10:36:55,393 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x22f7c28) 2016-11-01 10:36:55,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-11-01 10:36:55,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6c924e0 2016-11-01 10:36:55,411 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-11-01 10:36:55,417 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6c924e0 2016-11-01 10:36:55,423 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6c924e0 2016-11-01 10:36:55,429 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6c924e0 2016-11-01 10:36:55,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb46e38c0 [0x22d2610], 0xb6c924e0 [0x22f7c28]) 2016-11-01 10:36:55,443 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-11-01 10:36:55,448 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-11-01 10:36:55,453 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-11-01 10:36:55,458 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-11-01 10:36:55,463 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-11-01 10:36:55,468 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-11-01 10:36:55,473 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-11-01 10:36:55,479 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-11-01 10:36:55,485 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-11-01 10:36:55,490 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section onboardpins_InputPins (default: ) returns call:**11 2016-11-01 10:36:55,497 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-11-01 10:36:55,502 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**11'} was added to new evententry OnKeyPressed_onboardpins.22 2016-11-01 10:36:55,508 [WARNING] [root] section DTMF not found in configfile 2016-11-01 10:36:55,513 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-11-01 10:36:55,517 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-11-01 10:36:55,523 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-11-01 10:36:55,531 [DEBUG] [doorpi.action.handler] [ZID6SN] fire for event OnStartup this actions [] 2016-11-01 10:36:55,539 [TRACE] [doorpi.action.handler] [ZID6SN] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-11-01 10:36:55,544 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-11-01 10:36:55,535 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-11-01 10:36:56,556 [TRACE] [doorpi.action.handler] [ZID6SN] finished fire_event for event_name OnStartup 2016-11-01 10:36:56,561 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-11-01 10:36:56,568 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-11-01 10:36:56,572 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-11-01 10:36:56,580 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-11-01 10:36:56,577 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-11-01 10:36:56,592 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-11-01 10:36:56,597 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-11-01 10:36:56,603 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.40 2016-11-01 10:36:56,608 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-11-01 10:36:56,614 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-11-01 10:36:56,619 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-11-01 10:36:56,627 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-11-01 10:36:56,631 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-11-01 10:36:56,638 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x2300ce8] about to register (LinphoneCore version: 3.9.0) 2016-11-01 10:36:56,661 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-11-01 10:36:56,666 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60]: starting resolution of 192.168.1.1 2016-11-01 10:36:56,671 [INFO] [doorpi.sipphone.from_linphone] channel 0x230ba60: state RES_IN_PROGRESS 2016-11-01 10:36:56,676 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2302390] channel state changed to [RES_IN_PROGRESS] 2016-11-01 10:36:56,681 [INFO] [doorpi.sipphone.from_linphone] channel 0x230ba60: state RES_DONE 2016-11-01 10:36:56,686 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2302390] channel state changed to [RES_DONE] 2016-11-01 10:36:56,691 [INFO] [doorpi.sipphone.from_linphone] channel 0x230ba60: state CONNECTING 2016-11-01 10:36:56,696 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2302390] channel state changed to [CONNECTING] 2016-11-01 10:36:56,701 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.1:5060] 2016-11-01 10:36:56,706 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-11-01 10:36:56,711 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.40:5060 2016-11-01 10:36:56,717 [INFO] [doorpi.sipphone.from_linphone] channel 0x230ba60: state READY 2016-11-01 10:36:56,721 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2302390] channel state changed to [READY] 2016-11-01 10:36:56,727 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2302390], from state [INIT] to [TRYING] 2016-11-01 10:36:56,733 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60]: message sent to [UDP://192.168.1.1:5060], size: [504] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.z~Ump8OKy;rport From: "DoorPi" ;tag=SLppq~giu To: "DoorPi" CSeq: 20 REGISTER Call-ID: 1G~WihEdil 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-11-01 10:36:56,740 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-11-01 10:36:56,745 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x2302678] takes ownership of transaction [0x2302390] 2016-11-01 10:36:56,751 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x2300ce8] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-11-01 10:36:56,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x22d2610, 0x2300ce8, 1, "Registration in progress") 2016-11-01 10:36:56,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-11-01 10:36:56,768 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x22d2610] notifying [registration_state_changed] 2016-11-01 10:36:56,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x22d2610, 0x1) 2016-11-01 10:36:56,832 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-11-01 10:36:56,838 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x22d2610] notifying [network_reachable] 2016-11-01 10:36:56,843 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60]: received [360] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.z~Ump8OKy;rport=5060 From: "DoorPi" ;tag=SLppq~giu To: "DoorPi" ;tag=0808F12EBE38EFFC Call-ID: 1G~WihEdil CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="A3CBE5BDB730B614" User-Agent: FRITZ!OS Content-Length: 0 2016-11-01 10:36:56,867 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60] [360] bytes parsed 2016-11-01 10:36:56,871 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60]: discovered public ip and port are [192.168.1.40:5060] 2016-11-01 10:36:56,877 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-11-01 10:36:56,882 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2302390], from state [TRYING] to [COMPLETED] 2016-11-01 10:36:56,888 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=621, realm= 2016-11-01 10:36:56,893 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [621] realm [fritz.box] 2016-11-01 10:36:56,899 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23025f8], from state [INIT] to [TRYING] 2016-11-01 10:36:56,905 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60]: message sent to [UDP://192.168.1.1:5060], size: [657] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.FQlL4Y-4e;rport From: "DoorPi" ;tag=SLppq~giu To: "DoorPi" CSeq: 21 REGISTER Call-ID: 1G~WihEdil 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="A3CBE5BDB730B614", username="621", uri="sip:192.168.1.1", response="793d3748a5ca4745f902a286e2e72896" 2016-11-01 10:36:56,914 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-11-01 10:36:56,919 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-11-01 10:36:56,976 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60]: received [662] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.FQlL4Y-4e;rport=5060 From: "DoorPi" ;tag=SLppq~giu To: "DoorPi" ;tag=265F226AA8C586D2 Call-ID: 1G~WihEdil CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7490 113.06.60 (Jun 23 2016) Supported: 100rel,replaces,timer Allow-Events: telephone-event,refer,reg Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH Accept: application/sdp, multipart/mixed Accept-Encoding: identity Content-Length: 0 2016-11-01 10:36:57,008 [INFO] [doorpi.sipphone.from_linphone] channel [0x230ba60] [662] bytes parsed 2016-11-01 10:36:57,013 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-11-01 10:36:57,017 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23025f8], from state [TRYING] to [COMPLETED] 2016-11-01 10:36:57,023 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x2302678]: has no contact for request [0x2369500]. 2016-11-01 10:36:57,028 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2016-11-01 10:36:57,033 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.1.1] 2016-11-01 10:36:57,039 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x2300ce8] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2016-11-01 10:36:57,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x22d2610, 0x2300ce8, 2, "Registration successful") 2016-11-01 10:36:57,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-11-01 10:36:57,055 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x22d2610] notifying [registration_state_changed] 2016-11-01 10:37:01,947 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2302390], from state [COMPLETED] to [TERMINATED] 2016-11-01 10:37:01,951 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x2302390] terminated 2016-11-01 10:37:02,061 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23025f8], from state [COMPLETED] to [TERMINATED] 2016-11-01 10:37:02,065 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x23025f8] terminated