2016-03-19 01:57:03,848 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.0 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-03-19 01:57:03,857 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-03-19 01:57:03,864 [DEBUG] [doorpi.doorpi] run 2016-03-19 01:57:03,869 [DEBUG] [doorpi.doorpi] prepare 2016-03-19 01:57:03,874 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-03-19 01:57:03,888 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-03-19 01:57:03,921 [DEBUG] [doorpi.conf.config_object] __init__ 2016-03-19 01:57:03,925 [TRACE] [doorpi.conf.config_object] get_from_config 2016-03-19 01:57:03,948 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-03-19 01:57:03,953 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-03-19 01:57:03,959 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-03-19 01:57:03,972 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-03-19 01:57:03,977 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-03-19 01:57:03,982 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-03-19 01:57:03,987 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-03-19 01:57:03,992 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-03-19 01:57:03,997 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-03-19 01:57:04,003 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-03-19 01:57:04,007 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-03-19 01:57:04,013 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-03-19 01:57:04,018 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-03-19 01:57:04,023 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-03-19 01:57:04,028 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-03-19 01:57:04,033 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-03-19 01:57:04,042 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-03-19 01:57:04,049 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-03-19 01:57:04,067 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-03-19 01:57:04,073 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-03-19 01:57:04,078 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-03-19 01:57:04,084 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-03-19 01:57:04,095 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-03-19 01:57:04,100 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-03-19 01:57:04,105 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-03-19 01:57:04,110 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-03-19 01:57:04,116 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-03-19 01:57:04,121 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-03-19 01:57:04,126 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-03-19 01:57:04,133 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-03-19 01:57:04,138 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-03-19 01:57:04,145 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-03-19 01:57:04,150 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-03-19 01:57:04,157 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-03-19 01:57:04,161 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-03-19 01:57:04,168 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-03-19 01:57:04,173 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-03-19 01:57:04,179 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-03-19 01:57:04,185 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-03-19 01:57:04,190 [WARNING] [root] section ReadPermission not found in configfile 2016-03-19 01:57:04,196 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-03-19 01:57:04,201 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-03-19 01:57:04,206 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-03-19 01:57:04,211 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-03-19 01:57:04,217 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_keyboard', 'Group', 'keyboards', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-03-19 01:57:04,223 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-03-19 01:57:04,229 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-03-19 01:57:04,234 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-03-19 01:57:04,239 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-03-19 01:57:04,246 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-03-19 01:57:04,254 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,259 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-03-19 01:57:04,268 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,277 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,273 [DEBUG] [doorpi.action.handler] [6NLC93] fire for event OnWebServerStart this actions [] 2016-03-19 01:57:04,288 [TRACE] [doorpi.action.handler] [6NLC93] try to fire action WebServerStartupAction with args () and kwargs {} 2016-03-19 01:57:04,284 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,298 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,304 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,293 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-03-19 01:57:04,314 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,319 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,324 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,329 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,334 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,339 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,344 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,349 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,353 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,358 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 01:57:04,364 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-03-19 01:57:04,369 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-03-19 01:57:04,374 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-03-19 01:57:04,380 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2016-03-19 01:57:04,385 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['11'] 2016-03-19 01:57:04,390 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-03-19 01:57:04,395 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2016-03-19 01:57:04,401 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2016-03-19 01:57:04,407 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-03-19 01:57:04,412 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-03-19 01:57:04,420 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-03-19 01:57:04,433 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['11'], output_pins = [], bouncetime = 200.0, polarity = 0) 2016-03-19 01:57:04,438 [DEBUG] [doorpi.conf.config_object] create new key mode in section onboardpins_keyboard with value 'BOARD' 2016-03-19 01:57:04,443 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2016-03-19 01:57:04,449 [DEBUG] [doorpi.conf.config_object] create new key pull_up_down in section onboardpins_keyboard with value 'PUD_OFF' 2016-03-19 01:57:04,454 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-03-19 01:57:04,472 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,477 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-03-19 01:57:04,493 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,498 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,504 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_11 and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,509 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,514 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,524 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,533 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,538 [TRACE] [doorpi.action.handler] register Event OnKeyUp_11 from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,550 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_11 and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,555 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,560 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,570 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,576 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,587 [TRACE] [doorpi.action.handler] register Event OnKeyDown_11 from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,592 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_11 and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,605 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-19 01:57:04,610 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-19 01:57:04,615 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-03-19 01:57:04,630 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-03-19 01:57:04,635 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2016-03-19 01:57:04,646 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-03-19 01:57:04,667 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-03-19 01:57:04,676 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-03-19 01:57:04,696 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-03-19 01:57:04,821 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-03-19 01:57:04,831 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-03-19 01:57:04,845 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-03-19 01:57:05,015 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-03-19 01:57:05,038 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-03-19 01:57:05,043 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-03-19 01:57:05,053 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-03-19 01:57:05,063 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-03-19 01:57:05,072 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-03-19 01:57:05,083 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-03-19 01:57:05,091 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-03-19 01:57:05,096 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-03-19 01:57:05,102 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,107 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-03-19 01:57:05,112 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,117 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,122 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,128 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,133 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,138 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,143 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,148 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,154 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,158 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,164 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,169 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,174 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,179 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,185 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,190 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,195 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,200 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,205 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,210 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,216 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,221 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-03-19 01:57:05,226 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-03-19 01:57:05,231 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-03-19 01:57:05,237 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-03-19 01:57:05,242 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,247 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-03-19 01:57:05,253 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,258 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,263 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,268 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,274 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,278 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,283 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,288 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,293 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,298 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,303 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,307 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,313 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,318 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,323 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,329 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,334 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,339 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,344 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,350 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,355 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,360 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,365 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,370 [WARNING] [root] section DTMF not found in configfile 2016-03-19 01:57:05,376 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-19 01:57:05,381 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,386 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-03-19 01:57:05,392 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,397 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,402 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,407 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,412 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,417 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,423 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,428 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,433 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,438 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,444 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,448 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,454 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,459 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,464 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,469 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,474 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,479 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,484 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-03-19 01:57:05,493 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-03-19 01:57:05,500 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb47b3890 2016-03-19 01:57:05,509 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-03-19 01:57:05,503 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 01:57:05,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb47b3890 2016-03-19 01:57:05,528 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb47d2a50, "(null)", "(null)") 2016-03-19 01:57:05,535 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-03-19 01:57:05,540 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2016-03-19 01:57:05,546 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-03-19 01:57:05,552 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-03-19 01:57:05,617 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-03-19 01:57:05,863 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-03-19 01:57:05,902 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-03-19 01:57:05,914 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-03-19 01:57:05,960 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB PnP Sound Device' added 2016-03-19 01:57:05,965 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-03-19 01:57:05,971 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-03-19 01:57:05,977 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-03-19 01:57:05,982 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-03-19 01:57:05,988 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-03-19 01:57:05,993 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-03-19 01:57:05,999 [INFO] [doorpi.sipphone.from_linphone] Vtable [0xcaebe8] registered on core [0xcb5b50] 2016-03-19 01:57:06,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xcaebe8, 1, "Starting up") 2016-03-19 01:57:06,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-19 01:57:06,017 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xcaebe8] notifying [global_state_changed] 2016-03-19 01:57:06,023 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-03-19 01:57:06,028 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-03-19 01:57:06,033 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-03-19 01:57:06,039 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-03-19 01:57:06,046 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2016-03-19 01:57:06,051 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 01:57:06,057 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 01:57:06,062 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-19 01:57:06,068 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-19 01:57:06,074 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-03-19 01:57:06,079 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-03-19 01:57:06,084 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-03-19 01:57:06,090 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-03-19 01:57:06,096 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-03-19 01:57:06,101 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-03-19 01:57:06,106 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-03-19 01:57:06,112 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-03-19 01:57:06,117 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-03-19 01:57:06,123 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-03-19 01:57:06,128 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-03-19 01:57:06,133 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-03-19 01:57:06,139 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-19 01:57:06,144 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-19 01:57:06,149 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 01:57:06,155 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 01:57:06,160 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 01:57:06,166 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 01:57:06,171 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 01:57:06,176 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 01:57:06,182 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 01:57:06,187 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 01:57:06,192 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 01:57:06,198 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 01:57:06,203 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-03-19 01:57:06,208 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-03-19 01:57:06,214 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-03-19 01:57:06,219 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 01:57:06,225 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 01:57:06,230 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 01:57:06,235 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 01:57:06,241 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 01:57:06,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 01:57:06,252 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-03-19 01:57:06,257 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-03-19 01:57:06,263 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-03-19 01:57:06,268 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-03-19 01:57:06,273 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-03-19 01:57:06,279 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-03-19 01:57:06,284 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-03-19 01:57:06,290 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-03-19 01:57:06,295 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-03-19 01:57:06,300 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-03-19 01:57:06,306 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-03-19 01:57:06,311 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-03-19 01:57:06,317 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-03-19 01:57:06,322 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-03-19 01:57:06,328 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-03-19 01:57:06,333 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-03-19 01:57:06,338 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-03-19 01:57:06,344 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-03-19 01:57:06,349 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-03-19 01:57:06,355 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-03-19 01:57:06,360 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-03-19 01:57:06,366 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-03-19 01:57:06,371 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-03-19 01:57:06,377 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-19 01:57:06,382 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-03-19 01:57:06,387 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-03-19 01:57:06,393 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-03-19 01:57:06,398 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-03-19 01:57:06,404 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-03-19 01:57:06,409 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-03-19 01:57:06,414 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-03-19 01:57:06,420 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-03-19 01:57:06,425 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-03-19 01:57:06,431 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-03-19 01:57:06,436 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-19 01:57:06,442 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-19 01:57:06,447 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-03-19 01:57:06,452 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-03-19 01:57:06,458 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-03-19 01:57:06,463 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-03-19 01:57:06,468 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-19 01:57:06,474 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-19 01:57:06,480 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,487 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,492 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,498 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0xcaebe8, 2, "(null)") 2016-03-19 01:57:06,504 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-03-19 01:57:06,510 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xcaebe8] notifying [configuring_status] 2016-03-19 01:57:06,515 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-03-19 01:57:06,520 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-03-19 01:57:06,526 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-03-19 01:57:06,532 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-03-19 01:57:06,537 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-03-19 01:57:06,542 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-19 01:57:06,548 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-19 01:57:06,553 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-03-19 01:57:06,558 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-03-19 01:57:06,564 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-03-19 01:57:06,569 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,575 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,581 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,586 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,591 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-03-19 01:57:06,597 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-19 01:57:06,602 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-19 01:57:06,608 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,613 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,618 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-03-19 01:57:06,624 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-19 01:57:06,629 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,635 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-19 01:57:06,640 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xcb5838] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-19 01:57:06,646 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xcae230] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-19 01:57:06,652 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xcaf778] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-19 01:57:06,658 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 40142 2016-03-19 01:57:06,663 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,669 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xcaebe8, 2, "Ready") 2016-03-19 01:57:06,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-19 01:57:06,693 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xcaebe8] notifying [global_state_changed] 2016-03-19 01:57:06,698 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb47b3890 2016-03-19 01:57:06,704 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns 621 2016-03-19 01:57:06,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb47b3890 [0xcaebe8], "621 ") 2016-03-19 01:57:06,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-03-19 01:57:06,725 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-03-19 01:57:06,732 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-03-19 01:57:06,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb47b3890 [0xcaebe8], 2) 2016-03-19 01:57:06,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-03-19 01:57:06,742 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-19 01:57:06,759 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-03-19 01:57:06,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb47b3890 [0xcaebe8], 0x2e0614 [0]) 2016-03-19 01:57:06,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-03-19 01:57:06,779 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-19 01:57:06,784 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-19 01:57:06,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-03-19 01:57:06,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb47b38a8 2016-03-19 01:57:06,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb47b3890 [0xcaebe8], 0xb47b38a8 [0xb47b38b0]) 2016-03-19 01:57:06,809 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xcb5838] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-03-19 01:57:06,814 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xcae230] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-19 01:57:06,820 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xcaf778] on [sip:0.0.0.0:40142;transport=TLS] destroyed 2016-03-19 01:57:06,826 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-19 01:57:06,832 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xcc3518] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-19 01:57:06,837 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xcc32b0] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-19 01:57:06,843 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xcb5970] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-19 01:57:06,848 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 33969 2016-03-19 01:57:06,854 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,860 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 01:57:06,866 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-03-19 01:57:06,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb47b38a8) 2016-03-19 01:57:06,878 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-03-19 01:57:06,883 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-03-19 01:57:06,889 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb47b3890 [0xcaebe8], 0x2e0614 [0]) 2016-03-19 01:57:06,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-03-19 01:57:06,902 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-03-19 01:57:06,908 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb47b3890 [0xcaebe8], "") 2016-03-19 01:57:06,915 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-19 01:57:06,920 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-19 01:57:06,929 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-19 01:57:06,934 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-19 01:57:06,939 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-03-19 01:57:06,946 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-03-19 01:57:06,951 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-03-19 01:57:06,957 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb47b3890 [0xcaebe8], 0) 2016-03-19 01:57:06,964 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-19 01:57:06,969 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-19 01:57:06,977 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-03-19 01:57:06,984 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-03-19 01:57:06,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb47b3890 [0xcaebe8], 120) 2016-03-19 01:57:06,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-03-19 01:57:07,002 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-03-19 01:57:07,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb47b3890 [0xcaebe8], 15) 2016-03-19 01:57:07,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-03-19 01:57:07,019 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2016-03-19 01:57:07,027 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-03-19 01:57:07,032 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-19 01:57:07,041 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-03-19 01:57:07,046 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-19 01:57:07,051 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-03-19 01:57:07,057 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-03-19 01:57:07,062 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-19 01:57:07,067 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-03-19 01:57:07,072 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-19 01:57:07,077 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-03-19 01:57:07,083 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-03-19 01:57:07,089 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-03-19 01:57:07,095 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-03-19 01:57:07,100 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-19 01:57:07,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb47b3890 [0xcaebe8], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-03-19 01:57:07,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-03-19 01:57:07,125 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2016-03-19 01:57:07,119 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-19 01:57:07,136 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-19 01:57:07,141 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,146 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-03-19 01:57:07,151 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,156 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,162 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,167 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,172 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,178 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-03-19 01:57:07,184 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-03-19 01:57:07,189 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-03-19 01:57:07,195 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-03-19 01:57:07,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb47059b8 2016-03-19 01:57:07,219 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,214 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 01:57:07,231 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb47d1800 2016-03-19 01:57:07,238 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-03-19 01:57:07,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb47b3890 [0xcaebe8], "ALSA: USB PnP Sound Device") 2016-03-19 01:57:07,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-03-19 01:57:07,256 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb47d1830 2016-03-19 01:57:07,270 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-03-19 01:57:07,275 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb47b3890 [0xcaebe8], "ALSA: USB PnP Sound Device") 2016-03-19 01:57:07,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-03-19 01:57:07,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb47058a0 2016-03-19 01:57:07,298 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2016-03-19 01:57:07,304 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-03-19 01:57:07,307 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 01:57:07,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,314 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb47058a0 2016-03-19 01:57:07,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb47b3890 [0xcaebe8], "ALSA: default device") 2016-03-19 01:57:07,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-19 01:57:07,331 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb47b3890 [0xcaebe8], "ALSA: default device") 2016-03-19 01:57:07,336 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-19 01:57:07,343 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-03-19 01:57:07,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb47b3890 [0xcaebe8], "ALSA: bcm2835 ALSA") 2016-03-19 01:57:07,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-03-19 01:57:07,359 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb47b3890 [0xcaebe8], "ALSA: bcm2835 ALSA") 2016-03-19 01:57:07,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-19 01:57:07,373 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-03-19 01:57:07,378 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb47b3890 [0xcaebe8], "ALSA: USB PnP Sound Device") 2016-03-19 01:57:07,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-19 01:57:07,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb47b3890 [0xcaebe8], "ALSA: USB PnP Sound Device") 2016-03-19 01:57:07,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-19 01:57:07,404 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB PnP Sound Device 2016-03-19 01:57:07,409 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 01:57:07,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,420 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb47d18f0 2016-03-19 01:57:07,425 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB PnP Sound Device 2016-03-19 01:57:07,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb47d1920 2016-03-19 01:57:07,443 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: USB PnP Sound Device 2016-03-19 01:57:07,449 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-03-19 01:57:07,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:07,461 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcadfc0) 2016-03-19 01:57:07,467 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,473 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64410 2016-03-19 01:57:07,479 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,485 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64410 2016-03-19 01:57:07,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64410 2016-03-19 01:57:07,498 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcae030) 2016-03-19 01:57:07,504 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64420 2016-03-19 01:57:07,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,522 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64420 2016-03-19 01:57:07,529 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64420 2016-03-19 01:57:07,535 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcad8c0) 2016-03-19 01:57:07,543 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,548 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64430 2016-03-19 01:57:07,556 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,561 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64430 2016-03-19 01:57:07,570 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64430 2016-03-19 01:57:07,576 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcad928) 2016-03-19 01:57:07,582 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,589 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64440 2016-03-19 01:57:07,595 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,601 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64440 2016-03-19 01:57:07,608 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64440 2016-03-19 01:57:07,615 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcad980) 2016-03-19 01:57:07,621 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,627 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64450 2016-03-19 01:57:07,633 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,640 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64450 2016-03-19 01:57:07,646 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64450 2016-03-19 01:57:07,652 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcad9d8) 2016-03-19 01:57:07,659 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,665 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64460 2016-03-19 01:57:07,672 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,678 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64460 2016-03-19 01:57:07,684 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64460 2016-03-19 01:57:07,690 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcada30) 2016-03-19 01:57:07,697 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64470 2016-03-19 01:57:07,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64470 2016-03-19 01:57:07,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64470 2016-03-19 01:57:07,736 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcae430) 2016-03-19 01:57:07,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64480 2016-03-19 01:57:07,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64480 2016-03-19 01:57:07,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64480 2016-03-19 01:57:07,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcae498) 2016-03-19 01:57:07,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d64490 2016-03-19 01:57:07,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d64490 2016-03-19 01:57:07,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d64490 2016-03-19 01:57:07,815 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcae4f0) 2016-03-19 01:57:07,821 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:07,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d644a0 2016-03-19 01:57:07,833 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:07,839 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d644a0 2016-03-19 01:57:07,845 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d644a0 2016-03-19 01:57:07,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb47059e0 2016-03-19 01:57:07,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64410 [0xcadfc0]) 2016-03-19 01:57:07,865 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:07,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64410 [0xcadfc0]) 2016-03-19 01:57:07,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:07,883 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-03-19 01:57:07,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64410 [0xcadfc0], 0x2e0614 [0]) 2016-03-19 01:57:07,895 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:07,902 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64420 [0xcae030]) 2016-03-19 01:57:07,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47044a0 2016-03-19 01:57:07,916 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64420 [0xcae030]) 2016-03-19 01:57:07,921 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47044a0 2016-03-19 01:57:07,928 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-19 01:57:07,934 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64420 [0xcae030], 0x2e0614 [0]) 2016-03-19 01:57:07,941 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:07,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64430 [0xcad8c0]) 2016-03-19 01:57:07,953 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:07,959 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64430 [0xcad8c0]) 2016-03-19 01:57:07,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:07,972 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-19 01:57:07,977 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64430 [0xcad8c0], 0x2e0614 [0]) 2016-03-19 01:57:07,983 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:07,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64440 [0xcad928]) 2016-03-19 01:57:07,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47044a0 2016-03-19 01:57:08,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64440 [0xcad928]) 2016-03-19 01:57:08,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47044a0 2016-03-19 01:57:08,015 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-03-19 01:57:08,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64440 [0xcad928], 0x2e0620 [1]) 2016-03-19 01:57:08,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64450 [0xcad980]) 2016-03-19 01:57:08,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:08,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64450 [0xcad980]) 2016-03-19 01:57:08,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:08,059 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-03-19 01:57:08,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64450 [0xcad980], 0x2e0620 [1]) 2016-03-19 01:57:08,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64460 [0xcad9d8]) 2016-03-19 01:57:08,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64460 [0xcad9d8]) 2016-03-19 01:57:08,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,102 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-03-19 01:57:08,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64460 [0xcad9d8], 0x2e0614 [0]) 2016-03-19 01:57:08,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64470 [0xcada30]) 2016-03-19 01:57:08,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:08,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64470 [0xcada30]) 2016-03-19 01:57:08,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4704620 2016-03-19 01:57:08,144 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-03-19 01:57:08,150 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64470 [0xcada30], 0x2e0614 [0]) 2016-03-19 01:57:08,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64480 [0xcae430]) 2016-03-19 01:57:08,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47044a0 2016-03-19 01:57:08,176 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64480 [0xcae430]) 2016-03-19 01:57:08,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47044a0 2016-03-19 01:57:08,189 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-19 01:57:08,194 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64480 [0xcae430], 0x2e0614 [0]) 2016-03-19 01:57:08,202 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64490 [0xcae498]) 2016-03-19 01:57:08,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d64490 [0xcae498]) 2016-03-19 01:57:08,229 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,234 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-19 01:57:08,240 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d64490 [0xcae498], 0x2e0614 [0]) 2016-03-19 01:57:08,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d644a0 [0xcae4f0]) 2016-03-19 01:57:08,260 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d644a0 [0xcae4f0]) 2016-03-19 01:57:08,273 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,280 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-19 01:57:08,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d644a0 [0xcae4f0], 0x2e0614 [0]) 2016-03-19 01:57:08,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb47059b8 2016-03-19 01:57:08,312 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb47059b8 2016-03-19 01:57:08,325 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-03-19 01:57:08,330 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-03-19 01:57:08,335 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 01:57:08,340 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,347 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb47059b8 2016-03-19 01:57:08,352 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-03-19 01:57:08,358 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 01:57:08,363 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb47059b8 2016-03-19 01:57:08,377 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-03-19 01:57:08,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb47059b8 2016-03-19 01:57:08,395 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb47b3890 [0xcaebe8], 0x2e0620 [1]) 2016-03-19 01:57:08,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-03-19 01:57:08,407 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb47b3890 [0xcaebe8], "StaticImage: Static picture") 2016-03-19 01:57:08,412 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-03-19 01:57:08,417 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-03-19 01:57:08,423 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb47b3890 [0xcaebe8], "vga") 2016-03-19 01:57:08,430 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-03-19 01:57:08,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb47d1a10 2016-03-19 01:57:08,446 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-03-19 01:57:08,452 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-03-19 01:57:08,457 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,464 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcadf20) 2016-03-19 01:57:08,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 01:57:08,476 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d644b0 2016-03-19 01:57:08,483 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 01:57:08,489 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d644b0 2016-03-19 01:57:08,495 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d644b0 2016-03-19 01:57:08,502 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb4705a80 2016-03-19 01:57:08,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d644b0 [0xcadf20]) 2016-03-19 01:57:08,513 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,518 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,525 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-03-19 01:57:08,531 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d644b0 [0xcadf20]) 2016-03-19 01:57:08,535 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47b38a8 2016-03-19 01:57:08,541 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-03-19 01:57:08,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb47b3890 [0xcaebe8], 0xb6d644b0 [0xcadf20], 0x2e0620 [1]) 2016-03-19 01:57:08,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb1d8bc 2016-03-19 01:57:08,561 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2016-03-19 01:57:08,566 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 621 2016-03-19 01:57:08,572 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 621) returns ******* 2016-03-19 01:57:08,577 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns 2016-03-19 01:57:08,582 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-03-19 01:57:08,587 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb47b3890 [0xcaebe8]) 2016-03-19 01:57:08,595 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0xcc7660) 2016-03-19 01:57:08,602 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-03-19 01:57:08,607 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6d644c0 2016-03-19 01:57:08,613 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-03-19 01:57:08,619 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6d644c0 2016-03-19 01:57:08,625 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6d644c0 2016-03-19 01:57:08,632 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6d644c0 2016-03-19 01:57:08,638 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns 621 2016-03-19 01:57:08,643 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("621 ") 2016-03-19 01:57:08,651 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xcc1908) 2016-03-19 01:57:08,658 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-03-19 01:57:08,665 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d644d0 2016-03-19 01:57:08,670 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-03-19 01:57:08,677 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d644d0 2016-03-19 01:57:08,682 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d644d0 2016-03-19 01:57:08,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6d644d0 2016-03-19 01:57:08,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6d644c0 [0xcc7660], 0xb6d644d0 [0xcc1908]) 2016-03-19 01:57:08,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-03-19 01:57:08,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6d644c0 [0xcc7660], "sip:192.168.1.1") 2016-03-19 01:57:08,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-03-19 01:57:08,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6d644c0 [0xcc7660], 0x2e0620 [1]) 2016-03-19 01:57:08,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-03-19 01:57:08,732 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb47b3890 [0xcaebe8], 0xb6d644c0 [0xcc7660]) 2016-03-19 01:57:08,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0xb1d8bc 2016-03-19 01:57:08,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb47b3890 [0xcaebe8], 0xb6d644c0 [0xcc7660]) 2016-03-19 01:57:08,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-03-19 01:57:08,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb47b3890 [0xcaebe8], "621", "(null)", "bandit1100", "(null)", "(null)", "") 2016-03-19 01:57:08,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0xcc6250) 2016-03-19 01:57:08,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-03-19 01:57:08,776 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6d644e0 2016-03-19 01:57:08,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-03-19 01:57:08,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6d644e0 2016-03-19 01:57:08,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6d644e0 2016-03-19 01:57:08,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6d644e0 2016-03-19 01:57:08,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb47b3890 [0xcaebe8], 0xb6d644e0 [0xcc6250]) 2016-03-19 01:57:08,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-03-19 01:57:08,817 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-03-19 01:57:08,822 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-03-19 01:57:08,827 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-03-19 01:57:08,833 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-03-19 01:57:08,838 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-03-19 01:57:08,843 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-03-19 01:57:08,851 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-03-19 01:57:08,856 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-03-19 01:57:08,862 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_InputPins (default: ) returns call:11 2016-03-19 01:57:08,869 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-03-19 01:57:08,875 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '11'} was added to new evententry OnKeyPressed_onboardpins.11 2016-03-19 01:57:08,881 [WARNING] [root] section DTMF not found in configfile 2016-03-19 01:57:08,886 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-19 01:57:08,892 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-03-19 01:57:08,897 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-03-19 01:57:08,906 [DEBUG] [doorpi.action.handler] [CVUST2] fire for event OnStartup this actions [] 2016-03-19 01:57:08,916 [TRACE] [doorpi.action.handler] [CVUST2] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-03-19 01:57:08,922 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-03-19 01:57:08,913 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-03-19 01:57:09,930 [TRACE] [doorpi.action.handler] [CVUST2] finished fire_event for event_name OnStartup 2016-03-19 01:57:09,935 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-03-19 01:57:09,942 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-03-19 01:57:09,947 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-03-19 01:57:09,955 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-03-19 01:57:09,951 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-03-19 01:57:09,968 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-03-19 01:57:09,973 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-19 01:57:09,979 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.40 2016-03-19 01:57:09,984 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-03-19 01:57:09,991 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-19 01:57:09,996 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-19 01:57:10,005 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-19 01:57:10,009 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-19 01:57:10,017 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0xcc7660] about to register (LinphoneCore version: 3.9.0) 2016-03-19 01:57:10,035 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-03-19 01:57:10,040 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8]: starting resolution of 192.168.1.1 2016-03-19 01:57:10,046 [INFO] [doorpi.sipphone.from_linphone] channel 0xcd76a8: state RES_IN_PROGRESS 2016-03-19 01:57:10,051 [INFO] [doorpi.sipphone.from_linphone] transaction [0xcc6498] channel state changed to [RES_IN_PROGRESS] 2016-03-19 01:57:10,058 [INFO] [doorpi.sipphone.from_linphone] channel 0xcd76a8: state RES_DONE 2016-03-19 01:57:10,063 [INFO] [doorpi.sipphone.from_linphone] transaction [0xcc6498] channel state changed to [RES_DONE] 2016-03-19 01:57:10,068 [INFO] [doorpi.sipphone.from_linphone] channel 0xcd76a8: state CONNECTING 2016-03-19 01:57:10,074 [INFO] [doorpi.sipphone.from_linphone] transaction [0xcc6498] channel state changed to [CONNECTING] 2016-03-19 01:57:10,079 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.1:5060] 2016-03-19 01:57:10,084 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-03-19 01:57:10,090 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.40:5060 2016-03-19 01:57:10,096 [INFO] [doorpi.sipphone.from_linphone] channel 0xcd76a8: state READY 2016-03-19 01:57:10,101 [INFO] [doorpi.sipphone.from_linphone] transaction [0xcc6498] channel state changed to [READY] 2016-03-19 01:57:10,106 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xcc6498], from state [INIT] to [TRYING] 2016-03-19 01:57:10,113 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8]: message sent to [UDP://192.168.1.1:5060], size: [498] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.0F~tn-uU9;rport From: "621" ;tag=tUQENlU1J To: "621" CSeq: 20 REGISTER Call-ID: OExQsWqLZd Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-19 01:57:10,122 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-03-19 01:57:10,126 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xcca160] takes ownership of transaction [0xcc6498] 2016-03-19 01:57:10,132 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xcc7660] for identity ["621" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-03-19 01:57:10,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xcaebe8, 0xcc7660, 1, "Registration in progress") 2016-03-19 01:57:10,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-19 01:57:10,149 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xcaebe8] notifying [registration_state_changed] 2016-03-19 01:57:10,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0xcaebe8, 0x1) 2016-03-19 01:57:10,214 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-03-19 01:57:10,220 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xcaebe8] notifying [network_reachable] 2016-03-19 01:57:10,226 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8]: received [354] 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.0F~tn-uU9;rport=5060 From: "621" ;tag=tUQENlU1J To: "621" ;tag=6CC7FF250F59795F Call-ID: OExQsWqLZd CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="E8F8E5F364D060CA" User-Agent: FRITZ!OS Content-Length: 0 2016-03-19 01:57:10,251 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8] [354] bytes parsed 2016-03-19 01:57:10,256 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8]: discovered public ip and port are [192.168.1.40:5060] 2016-03-19 01:57:10,262 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-19 01:57:10,267 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xcc6498], from state [TRYING] to [COMPLETED] 2016-03-19 01:57:10,273 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=621, realm= 2016-03-19 01:57:10,279 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [621] realm [fritz.box] 2016-03-19 01:57:10,285 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xcce158], from state [INIT] to [TRYING] 2016-03-19 01:57:10,292 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8]: message sent to [UDP://192.168.1.1:5060], size: [651] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.Tf8UAiwCn;rport From: "621" ;tag=tUQENlU1J To: "621" CSeq: 21 REGISTER Call-ID: OExQsWqLZd 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="E8F8E5F364D060CA", username="621", uri="sip:192.168.1.1", response="b44994f818b10b70d9966ba8cd3114d0" 2016-03-19 01:57:10,302 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-03-19 01:57:10,307 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-19 01:57:10,364 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8]: received [656] 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.Tf8UAiwCn;rport=5060 From: "621" ;tag=tUQENlU1J To: "621" ;tag=4DBE01F9261FF4C8 Call-ID: OExQsWqLZd CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7490 113.06.51 (Feb 3 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-03-19 01:57:10,397 [INFO] [doorpi.sipphone.from_linphone] channel [0xcd76a8] [656] bytes parsed 2016-03-19 01:57:10,402 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-19 01:57:10,407 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xcce158], from state [TRYING] to [COMPLETED] 2016-03-19 01:57:10,413 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xcca160]: has no contact for request [0xcc8c48]. 2016-03-19 01:57:10,418 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2016-03-19 01:57:10,424 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.1.1] 2016-03-19 01:57:10,429 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xcc7660] for identity ["621" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2016-03-19 01:57:10,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xcaebe8, 0xcc7660, 2, "Registration successful") 2016-03-19 01:57:10,442 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-19 01:57:10,447 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xcaebe8] notifying [registration_state_changed] 2016-03-19 01:57:15,350 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xcc6498], from state [COMPLETED] to [TERMINATED] 2016-03-19 01:57:15,356 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xcc6498] terminated 2016-03-19 01:57:15,466 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xcce158], from state [COMPLETED] to [TERMINATED] 2016-03-19 01:57:15,472 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xcce158] terminated