2017-05-19 19:31:53,335 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2017-05-19 19:31:53,338 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2017-05-19 19:31:53,341 [DEBUG] [doorpi.doorpi] run 2017-05-19 19:31:53,343 [DEBUG] [doorpi.doorpi] prepare 2017-05-19 19:31:53,345 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2017-05-19 19:31:53,359 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2017-05-19 19:31:53,387 [DEBUG] [doorpi.conf.config_object] __init__ 2017-05-19 19:31:53,389 [TRACE] [doorpi.conf.config_object] get_from_config 2017-05-19 19:31:53,407 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2017-05-19 19:31:53,409 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2017-05-19 19:31:53,413 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2017-05-19 19:31:53,422 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2017-05-19 19:31:53,424 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2017-05-19 19:31:53,426 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2017-05-19 19:31:53,428 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2017-05-19 19:31:53,429 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2017-05-19 19:31:53,431 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2017-05-19 19:31:53,433 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2017-05-19 19:31:53,435 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2017-05-19 19:31:53,436 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2017-05-19 19:31:53,438 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2017-05-19 19:31:53,440 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2017-05-19 19:31:53,442 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2017-05-19 19:31:53,443 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2017-05-19 19:31:53,449 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2017-05-19 19:31:53,453 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2017-05-19 19:31:53,466 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2017-05-19 19:31:53,468 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2017-05-19 19:31:53,471 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2017-05-19 19:31:53,473 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2017-05-19 19:31:53,481 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2017-05-19 19:31:53,483 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2017-05-19 19:31:53,484 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2017-05-19 19:31:53,487 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2017-05-19 19:31:53,488 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2017-05-19 19:31:53,490 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2017-05-19 19:31:53,492 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2017-05-19 19:31:53,497 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2017-05-19 19:31:53,499 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2017-05-19 19:31:53,503 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2017-05-19 19:31:53,505 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2017-05-19 19:31:53,510 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2017-05-19 19:31:53,512 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2017-05-19 19:31:53,516 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2017-05-19 19:31:53,518 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2017-05-19 19:31:53,522 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2017-05-19 19:31:53,524 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2017-05-19 19:31:53,526 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2017-05-19 19:31:53,528 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2017-05-19 19:31:53,530 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['pi'] 2017-05-19 19:31:53,532 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnTimeMinute59', 'AREA_public', 'Group', 'ReadPermission', 'AdminNumbers', 'DTMF', 'SIP-Phone', 'WritePermission', 'User', 'DoorPiWeb', 'DoorPi'] 2017-05-19 19:31:53,535 [ERROR] [doorpi.status.webserver] no ReadPermission found 2017-05-19 19:31:53,537 [ERROR] [doorpi.status.webserver] no groups in configfile! 2017-05-19 19:31:53,538 [ERROR] [doorpi.status.webserver] no WritePermission found 2017-05-19 19:31:53,540 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2017-05-19 19:31:53,542 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2017-05-19 19:31:53,544 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2017-05-19 19:31:53,549 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,551 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2017-05-19 19:31:53,553 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,559 [DEBUG] [doorpi.action.handler] [W6ZFE4] fire for event OnWebServerStart this actions [] 2017-05-19 19:31:53,561 [TRACE] [doorpi.action.handler] [W6ZFE4] try to fire action WebServerStartupAction with args () and kwargs {} 2017-05-19 19:31:53,563 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2017-05-19 19:31:53,555 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,567 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,569 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,570 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,572 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,574 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,576 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,578 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,579 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,581 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,583 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,585 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,590 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,591 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2017-05-19 19:31:53,593 [WARNING] [root] section keyboards not found in configfile 2017-05-19 19:31:53,595 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns [] 2017-05-19 19:31:53,597 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode with dummy keyboard 2017-05-19 19:31:53,599 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'dummy' to handler 2017-05-19 19:31:53,601 [DEBUG] [doorpi.conf.config_object] create new key dummy in section keyboards with value 'dummy' 2017-05-19 19:31:53,603 [TRACE] [doorpi.conf.config_object] get_string for key dummy in section keyboards (default: dummy) returns dummy 2017-05-19 19:31:53,605 [WARNING] [root] section dummy_InputPins not found in configfile 2017-05-19 19:31:53,607 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2017-05-19 19:31:53,609 [WARNING] [root] section dummy_OutputPins not found in configfile 2017-05-19 19:31:53,611 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2017-05-19 19:31:53,613 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section dummy_keyboard (default: 2000) returns 2000.0 2017-05-19 19:31:53,615 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section dummy_keyboard (default: 0) returns 0 2017-05-19 19:31:53,617 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section dummy_keyboard (default: True) returns True 2017-05-19 19:31:53,621 [DEBUG] [doorpi.keyboard.from_dummy] doorpi.keyboard.from_dummy loaded 2017-05-19 19:31:53,624 [DEBUG] [doorpi.keyboard.from_dummy] __init__(input_pins = [], output_pins = [], bouncetime = 2000.0, polarity = 0) 2017-05-19 19:31:53,627 [WARNING] [root] section dummy_InputPins not found in configfile 2017-05-19 19:31:53,628 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_InputPins returns [] 2017-05-19 19:31:53,631 [WARNING] [root] section dummy_OutputPins not found in configfile 2017-05-19 19:31:53,632 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2017-05-19 19:31:53,635 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2017-05-19 19:31:53,637 [WARNING] [root] section dummy_OutputPins not found in configfile 2017-05-19 19:31:53,639 [TRACE] [doorpi.conf.config_object] get_keys for section dummy_OutputPins returns [] 2017-05-19 19:31:53,644 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2017-05-19 19:31:53,650 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2017-05-19 19:31:53,656 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2017-05-19 19:31:53,661 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2017-05-19 19:31:53,773 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2017-05-19 19:31:53,780 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2017-05-19 19:31:53,788 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2017-05-19 19:31:53,963 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2017-05-19 19:31:53,982 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2017-05-19 19:31:53,984 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2017-05-19 19:31:53,990 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2017-05-19 19:31:53,997 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2017-05-19 19:31:54,002 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2017-05-19 19:31:54,010 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2017-05-19 19:31:54,015 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2017-05-19 19:31:54,017 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2017-05-19 19:31:54,019 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,021 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2017-05-19 19:31:54,023 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,025 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,027 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,029 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,031 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,032 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,034 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,036 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,038 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,040 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,042 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,044 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,046 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,048 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,050 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,051 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,053 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,055 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,057 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,059 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,061 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,063 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2017-05-19 19:31:54,065 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2017-05-19 19:31:54,067 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2017-05-19 19:31:54,069 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2017-05-19 19:31:54,071 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,073 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2017-05-19 19:31:54,075 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,077 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,079 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,081 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,083 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,084 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,087 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,088 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,090 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,092 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,094 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,096 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,098 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,100 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,102 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,104 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,106 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,108 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,110 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,111 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,113 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,115 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,117 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,119 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2017-05-19 19:31:54,121 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,123 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2017-05-19 19:31:54,125 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,127 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,129 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,131 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,133 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,134 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,136 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,138 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,140 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,142 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,144 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,146 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,148 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,150 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,152 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,154 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,156 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,158 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,160 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:31:54,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2017-05-19 19:31:54,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb4fd7920 2017-05-19 19:31:54,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2017-05-19 19:31:54,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb4fd7920 2017-05-19 19:31:54,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb4ff3c90, "(null)", "(null)") 2017-05-19 19:31:54,178 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:31:54,180 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2017-05-19 19:31:54,183 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2017-05-19 19:31:54,185 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2017-05-19 19:31:54,187 [INFO] [doorpi.sipphone.from_linphone] srtp init 2017-05-19 19:31:54,210 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2017-05-19 19:31:54,606 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2017-05-19 19:31:54,642 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2017-05-19 19:31:54,650 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2017-05-19 19:31:54,692 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2017-05-19 19:31:54,723 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC2D0p' failed (-2) 2017-05-19 19:31:54,726 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_dmix.c:1022 - unable to open slave 2017-05-19 19:31:54,729 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB PnP Sound Device' added 2017-05-19 19:31:54,732 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2017-05-19 19:31:54,734 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2017-05-19 19:31:54,737 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2017-05-19 19:31:54,739 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2017-05-19 19:31:54,741 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2017-05-19 19:31:54,744 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2017-05-19 19:31:54,746 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x7fb558] registered on core [0x7fa4c8] 2017-05-19 19:31:54,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x7fb558, 1, "Starting up") 2017-05-19 19:31:54,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2017-05-19 19:31:54,753 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [global_state_changed] 2017-05-19 19:31:54,755 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2017-05-19 19:31:54,757 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2017-05-19 19:31:54,759 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2017-05-19 19:31:54,761 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2017-05-19 19:31:54,763 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2017-05-19 19:31:54,766 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 19:31:54,768 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 19:31:54,770 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2017-05-19 19:31:54,772 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2017-05-19 19:31:54,774 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2017-05-19 19:31:54,777 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2017-05-19 19:31:54,779 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2017-05-19 19:31:54,781 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2017-05-19 19:31:54,783 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2017-05-19 19:31:54,786 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2017-05-19 19:31:54,788 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2017-05-19 19:31:54,790 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2017-05-19 19:31:54,792 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2017-05-19 19:31:54,794 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2017-05-19 19:31:54,797 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2017-05-19 19:31:54,799 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2017-05-19 19:31:54,801 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2017-05-19 19:31:54,803 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2017-05-19 19:31:54,805 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 19:31:54,807 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 19:31:54,810 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 19:31:54,812 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 19:31:54,814 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 19:31:54,816 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 19:31:54,818 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 19:31:54,820 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 19:31:54,823 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-05-19 19:31:54,825 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-05-19 19:31:54,827 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2017-05-19 19:31:54,829 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2017-05-19 19:31:54,831 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2017-05-19 19:31:54,834 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 19:31:54,836 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 19:31:54,838 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 19:31:54,840 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 19:31:54,842 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-05-19 19:31:54,844 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-05-19 19:31:54,847 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2017-05-19 19:31:54,849 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2017-05-19 19:31:54,851 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2017-05-19 19:31:54,853 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2017-05-19 19:31:54,855 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2017-05-19 19:31:54,857 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2017-05-19 19:31:54,860 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2017-05-19 19:31:54,862 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2017-05-19 19:31:54,864 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2017-05-19 19:31:54,866 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2017-05-19 19:31:54,868 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2017-05-19 19:31:54,870 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2017-05-19 19:31:54,873 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2017-05-19 19:31:54,875 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2017-05-19 19:31:54,877 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2017-05-19 19:31:54,879 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2017-05-19 19:31:54,881 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2017-05-19 19:31:54,883 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2017-05-19 19:31:54,886 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2017-05-19 19:31:54,888 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2017-05-19 19:31:54,890 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2017-05-19 19:31:54,892 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2017-05-19 19:31:54,894 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2017-05-19 19:31:54,897 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2017-05-19 19:31:54,899 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2017-05-19 19:31:54,901 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2017-05-19 19:31:54,903 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2017-05-19 19:31:54,905 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2017-05-19 19:31:54,907 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2017-05-19 19:31:54,910 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2017-05-19 19:31:54,912 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2017-05-19 19:31:54,914 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2017-05-19 19:31:54,916 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2017-05-19 19:31:54,919 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2017-05-19 19:31:54,921 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2017-05-19 19:31:54,923 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2017-05-19 19:31:54,925 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2017-05-19 19:31:54,927 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2017-05-19 19:31:54,929 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2017-05-19 19:31:54,931 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2017-05-19 19:31:54,934 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2017-05-19 19:31:54,936 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-05-19 19:31:54,938 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:54,941 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:54,943 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:54,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x7fb558, 2, "(null)") 2017-05-19 19:31:54,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2017-05-19 19:31:54,949 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [configuring_status] 2017-05-19 19:31:54,952 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2017-05-19 19:31:54,954 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2017-05-19 19:31:54,956 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2017-05-19 19:31:54,958 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2017-05-19 19:31:54,960 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2017-05-19 19:31:54,963 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-05-19 19:31:54,965 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-05-19 19:31:54,967 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2017-05-19 19:31:54,969 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2017-05-19 19:31:54,971 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2017-05-19 19:31:54,973 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,976 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,978 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,980 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,982 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2017-05-19 19:31:54,984 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2017-05-19 19:31:54,986 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2017-05-19 19:31:54,989 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,991 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,993 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2017-05-19 19:31:54,995 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-05-19 19:31:54,998 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:55,000 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2017-05-19 19:31:55,002 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x7fae40] on [sip:0.0.0.0:5060;transport=UDP] 2017-05-19 19:31:55,005 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x7fbfa0] on [sip:0.0.0.0:5060;transport=TCP] 2017-05-19 19:31:55,008 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x7fc140] on [sip:0.0.0.0:-1;transport=TLS] 2017-05-19 19:31:55,010 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 43053 2017-05-19 19:31:55,012 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:55,014 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:55,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x7fb558, 2, "Ready") 2017-05-19 19:31:55,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2017-05-19 19:31:55,027 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [global_state_changed] 2017-05-19 19:31:55,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb4fd7920 2017-05-19 19:31:55,031 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2017-05-19 19:31:55,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb4fd7920 [0x7fb558], "DoorPi ") 2017-05-19 19:31:55,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2017-05-19 19:31:55,040 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2017-05-19 19:31:55,044 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2017-05-19 19:31:55,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb4fd7920 [0x7fb558], 2) 2017-05-19 19:31:55,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2017-05-19 19:31:55,051 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2017-05-19 19:31:55,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb4fd7920 [0x7fb558], 0x2e0614 [0]) 2017-05-19 19:31:55,057 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2017-05-19 19:31:55,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2017-05-19 19:31:55,062 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2017-05-19 19:31:55,064 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2017-05-19 19:31:55,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2017-05-19 19:31:55,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb4fd7938 2017-05-19 19:31:55,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb4fd7920 [0x7fb558], 0xb4fd7938 [0xb4fd7940]) 2017-05-19 19:31:55,073 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x7fae40] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2017-05-19 19:31:55,075 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x7fbfa0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2017-05-19 19:31:55,078 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x7fc140] on [sip:0.0.0.0:43053;transport=TLS] destroyed 2017-05-19 19:31:55,080 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2017-05-19 19:31:55,082 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x816a38] on [sip:0.0.0.0:5060;transport=UDP] 2017-05-19 19:31:55,085 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x816cb0] on [sip:0.0.0.0:5060;transport=TCP] 2017-05-19 19:31:55,088 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x8188f0] on [sip:0.0.0.0:-1;transport=TLS] 2017-05-19 19:31:55,090 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 40451 2017-05-19 19:31:55,092 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:55,094 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-05-19 19:31:55,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2017-05-19 19:31:55,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb4fd7938) 2017-05-19 19:31:55,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2017-05-19 19:31:55,103 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2017-05-19 19:31:55,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb4fd7920 [0x7fb558], 0x2e0614 [0]) 2017-05-19 19:31:55,107 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2017-05-19 19:31:55,109 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2017-05-19 19:31:55,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb4fd7920 [0x7fb558], "") 2017-05-19 19:31:55,115 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2017-05-19 19:31:55,117 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2017-05-19 19:31:55,121 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2017-05-19 19:31:55,123 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2017-05-19 19:31:55,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2017-05-19 19:31:55,128 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2017-05-19 19:31:55,129 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2017-05-19 19:31:55,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb4fd7920 [0x7fb558], 0) 2017-05-19 19:31:55,134 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-05-19 19:31:55,136 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-05-19 19:31:55,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2017-05-19 19:31:55,142 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2017-05-19 19:31:55,144 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb4fd7920 [0x7fb558], 120) 2017-05-19 19:31:55,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2017-05-19 19:31:55,149 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2017-05-19 19:31:55,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb4fd7920 [0x7fb558], 15) 2017-05-19 19:31:55,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2017-05-19 19:31:55,155 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2017-05-19 19:31:55,161 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2017-05-19 19:31:55,163 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2017-05-19 19:31:55,169 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2017-05-19 19:31:55,171 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2017-05-19 19:31:55,173 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2017-05-19 19:31:55,175 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2017-05-19 19:31:55,177 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2017-05-19 19:31:55,179 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2017-05-19 19:31:55,181 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2017-05-19 19:31:55,183 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2017-05-19 19:31:55,185 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2017-05-19 19:31:55,187 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2017-05-19 19:31:55,190 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2017-05-19 19:31:55,192 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2017-05-19 19:31:55,196 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb4fd7920 [0x7fb558], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2017-05-19 19:31:55,198 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2017-05-19 19:31:55,200 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns /mnt/fritzbox/records/%Y-%m-%d_%H-%M-%S.wav 2017-05-19 19:31:55,203 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2017-05-19 19:31:55,207 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2017-05-19 19:31:55,205 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,211 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2017-05-19 19:31:55,213 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,215 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,217 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,219 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,221 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,223 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2017-05-19 19:31:55,225 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2017-05-19 19:31:55,227 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2017-05-19 19:31:55,229 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2017-05-19 19:31:55,233 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,236 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4f20d78 2017-05-19 19:31:55,238 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,240 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4ff5830 2017-05-19 19:31:55,242 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2017-05-19 19:31:55,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb4fd7920 [0x7fb558], "ALSA: USB PnP Sound Device") 2017-05-19 19:31:55,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2017-05-19 19:31:55,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4ff58c0 2017-05-19 19:31:55,248 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2017-05-19 19:31:55,258 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2017-05-19 19:31:55,260 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb4fd7920 [0x7fb558], "ALSA: default device") 2017-05-19 19:31:55,262 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2017-05-19 19:31:55,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,271 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4f20c38 2017-05-19 19:31:55,273 [INFO] [doorpi.sipphone.from_linphone] found 4 possible sounddevices: 2017-05-19 19:31:55,276 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2017-05-19 19:31:55,278 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 19:31:55,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4f20c38 2017-05-19 19:31:55,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4fd7920 [0x7fb558], "ALSA: default device") 2017-05-19 19:31:55,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-05-19 19:31:55,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4fd7920 [0x7fb558], "ALSA: default device") 2017-05-19 19:31:55,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-05-19 19:31:55,293 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2017-05-19 19:31:55,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4fd7920 [0x7fb558], "ALSA: bcm2835 ALSA") 2017-05-19 19:31:55,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2017-05-19 19:31:55,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4fd7920 [0x7fb558], "ALSA: bcm2835 ALSA") 2017-05-19 19:31:55,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-05-19 19:31:55,304 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2017-05-19 19:31:55,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4fd7920 [0x7fb558], "ALSA: C-Media USB Headphone Set") 2017-05-19 19:31:55,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-05-19 19:31:55,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4fd7920 [0x7fb558], "ALSA: C-Media USB Headphone Set") 2017-05-19 19:31:55,312 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-05-19 19:31:55,314 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2017-05-19 19:31:55,316 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4fd7920 [0x7fb558], "ALSA: USB PnP Sound Device") 2017-05-19 19:31:55,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-05-19 19:31:55,321 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4fd7920 [0x7fb558], "ALSA: USB PnP Sound Device") 2017-05-19 19:31:55,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0614 2017-05-19 19:31:55,325 [DEBUG] [doorpi.sipphone.from_linphone] | X | O | ALSA: USB PnP Sound Device 2017-05-19 19:31:55,327 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 19:31:55,329 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,331 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4ff5a40 2017-05-19 19:31:55,333 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB PnP Sound Device 2017-05-19 19:31:55,335 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,337 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4ff5a10 2017-05-19 19:31:55,339 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2017-05-19 19:31:55,341 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2017-05-19 19:31:55,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,346 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fac40) 2017-05-19 19:31:55,348 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66410 2017-05-19 19:31:55,352 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66410 2017-05-19 19:31:55,357 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66410 2017-05-19 19:31:55,359 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7facb0) 2017-05-19 19:31:55,361 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,363 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66420 2017-05-19 19:31:55,365 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66420 2017-05-19 19:31:55,369 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66420 2017-05-19 19:31:55,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fad18) 2017-05-19 19:31:55,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66430 2017-05-19 19:31:55,378 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66430 2017-05-19 19:31:55,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66430 2017-05-19 19:31:55,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fb838) 2017-05-19 19:31:55,386 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66440 2017-05-19 19:31:55,390 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,392 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66440 2017-05-19 19:31:55,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66440 2017-05-19 19:31:55,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fb890) 2017-05-19 19:31:55,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66450 2017-05-19 19:31:55,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66450 2017-05-19 19:31:55,407 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66450 2017-05-19 19:31:55,409 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fb8e8) 2017-05-19 19:31:55,411 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66460 2017-05-19 19:31:55,416 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66460 2017-05-19 19:31:55,420 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66460 2017-05-19 19:31:55,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fb940) 2017-05-19 19:31:55,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,426 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66470 2017-05-19 19:31:55,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,430 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66470 2017-05-19 19:31:55,433 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66470 2017-05-19 19:31:55,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fb998) 2017-05-19 19:31:55,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,439 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66480 2017-05-19 19:31:55,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,443 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66480 2017-05-19 19:31:55,445 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66480 2017-05-19 19:31:55,447 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7fba00) 2017-05-19 19:31:55,449 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d66490 2017-05-19 19:31:55,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,456 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d66490 2017-05-19 19:31:55,458 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d66490 2017-05-19 19:31:55,460 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x809cc8) 2017-05-19 19:31:55,462 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,464 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d664a0 2017-05-19 19:31:55,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,468 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d664a0 2017-05-19 19:31:55,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d664a0 2017-05-19 19:31:55,473 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb4f20da0 2017-05-19 19:31:55,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66410 [0x7fac40]) 2017-05-19 19:31:55,477 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,479 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66410 [0x7fac40]) 2017-05-19 19:31:55,481 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,483 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2017-05-19 19:31:55,485 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66410 [0x7fac40], 0x2e0614 [0]) 2017-05-19 19:31:55,488 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,490 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66420 [0x7facb0]) 2017-05-19 19:31:55,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff4920 2017-05-19 19:31:55,495 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66420 [0x7facb0]) 2017-05-19 19:31:55,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff4920 2017-05-19 19:31:55,499 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-05-19 19:31:55,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66420 [0x7facb0], 0x2e0614 [0]) 2017-05-19 19:31:55,503 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,506 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66430 [0x7fad18]) 2017-05-19 19:31:55,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66430 [0x7fad18]) 2017-05-19 19:31:55,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,514 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-05-19 19:31:55,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66430 [0x7fad18], 0x2e0614 [0]) 2017-05-19 19:31:55,519 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,521 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66440 [0x7fb838]) 2017-05-19 19:31:55,523 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff4920 2017-05-19 19:31:55,525 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66440 [0x7fb838]) 2017-05-19 19:31:55,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff4920 2017-05-19 19:31:55,529 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2017-05-19 19:31:55,531 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66440 [0x7fb838], 0x2e0620 [1]) 2017-05-19 19:31:55,534 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,536 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66450 [0x7fb890]) 2017-05-19 19:31:55,539 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,541 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66450 [0x7fb890]) 2017-05-19 19:31:55,543 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,545 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2017-05-19 19:31:55,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66450 [0x7fb890], 0x2e0620 [1]) 2017-05-19 19:31:55,550 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,552 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66460 [0x7fb8e8]) 2017-05-19 19:31:55,554 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,556 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66460 [0x7fb8e8]) 2017-05-19 19:31:55,558 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,560 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2017-05-19 19:31:55,562 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66460 [0x7fb8e8], 0x2e0614 [0]) 2017-05-19 19:31:55,565 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66470 [0x7fb940]) 2017-05-19 19:31:55,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,571 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66470 [0x7fb940]) 2017-05-19 19:31:55,573 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff46e0 2017-05-19 19:31:55,575 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2017-05-19 19:31:55,578 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66470 [0x7fb940], 0x2e0614 [0]) 2017-05-19 19:31:55,580 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,582 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66480 [0x7fb998]) 2017-05-19 19:31:55,585 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff4920 2017-05-19 19:31:55,587 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66480 [0x7fb998]) 2017-05-19 19:31:55,589 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4ff4920 2017-05-19 19:31:55,591 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-05-19 19:31:55,593 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66480 [0x7fb998], 0x2e0614 [0]) 2017-05-19 19:31:55,596 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,598 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66490 [0x7fba00]) 2017-05-19 19:31:55,600 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,602 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d66490 [0x7fba00]) 2017-05-19 19:31:55,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,606 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2017-05-19 19:31:55,609 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d66490 [0x7fba00], 0x2e0614 [0]) 2017-05-19 19:31:55,611 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,613 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d664a0 [0x809cc8]) 2017-05-19 19:31:55,615 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,617 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d664a0 [0x809cc8]) 2017-05-19 19:31:55,620 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,622 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2017-05-19 19:31:55,624 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d664a0 [0x809cc8], 0x2e0614 [0]) 2017-05-19 19:31:55,626 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,629 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,631 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4f20e18 2017-05-19 19:31:55,633 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,635 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4f20e18 2017-05-19 19:31:55,637 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2017-05-19 19:31:55,639 [DEBUG] [doorpi.sipphone.from_linphone] | name 2017-05-19 19:31:55,641 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 19:31:55,643 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,645 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4f20e18 2017-05-19 19:31:55,647 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2017-05-19 19:31:55,649 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-05-19 19:31:55,651 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,653 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4f20e18 2017-05-19 19:31:55,655 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2017-05-19 19:31:55,657 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,659 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4f20e18 2017-05-19 19:31:55,662 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb4fd7920 [0x7fb558], 0x2e0620 [1]) 2017-05-19 19:31:55,664 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2017-05-19 19:31:55,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb4fd7920 [0x7fb558], "StaticImage: Static picture") 2017-05-19 19:31:55,668 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2017-05-19 19:31:55,671 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2017-05-19 19:31:55,673 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb4fd7920 [0x7fb558], "vga") 2017-05-19 19:31:55,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2017-05-19 19:31:55,677 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,679 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb4ff5b30 2017-05-19 19:31:55,681 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2017-05-19 19:31:55,683 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2017-05-19 19:31:55,686 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x7faba0) 2017-05-19 19:31:55,690 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-05-19 19:31:55,692 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d664b0 2017-05-19 19:31:55,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-05-19 19:31:55,696 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d664b0 2017-05-19 19:31:55,698 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d664b0 2017-05-19 19:31:55,700 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb4f20d78 2017-05-19 19:31:55,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d664b0 [0x7faba0]) 2017-05-19 19:31:55,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2017-05-19 19:31:55,711 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d664b0 [0x7faba0]) 2017-05-19 19:31:55,713 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4fd7938 2017-05-19 19:31:55,715 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2017-05-19 19:31:55,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4fd7920 [0x7fb558], 0xb6d664b0 [0x7faba0], 0x2e0620 [1]) 2017-05-19 19:31:55,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x66a8bc 2017-05-19 19:31:55,722 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2017-05-19 19:31:55,724 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns Doorpi_IpFon 2017-05-19 19:31:55,732 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: Doorpi_IpFon) returns ******* 2017-05-19 19:31:55,734 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2017-05-19 19:31:55,737 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2017-05-19 19:31:55,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb4fd7920 [0x7fb558]) 2017-05-19 19:31:55,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x81bd78) 2017-05-19 19:31:55,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2017-05-19 19:31:55,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6d664c0 2017-05-19 19:31:55,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2017-05-19 19:31:55,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6d664c0 2017-05-19 19:31:55,752 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6d664c0 2017-05-19 19:31:55,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6d664c0 2017-05-19 19:31:55,756 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2017-05-19 19:31:55,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2017-05-19 19:31:55,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x81a3e8) 2017-05-19 19:31:55,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 19:31:55,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d664d0 2017-05-19 19:31:55,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 19:31:55,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d664d0 2017-05-19 19:31:55,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d664d0 2017-05-19 19:31:55,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6d664d0 2017-05-19 19:31:55,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6d664c0 [0x81bd78], 0xb6d664d0 [0x81a3e8]) 2017-05-19 19:31:55,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2017-05-19 19:31:55,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6d664c0 [0x81bd78], "sip:192.168.178.1") 2017-05-19 19:31:55,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2017-05-19 19:31:55,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6d664c0 [0x81bd78], 0x2e0620 [1]) 2017-05-19 19:31:55,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2017-05-19 19:31:55,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb4fd7920 [0x7fb558], 0xb6d664c0 [0x81bd78]) 2017-05-19 19:31:55,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x66a8bc 2017-05-19 19:31:55,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb4fd7920 [0x7fb558], 0xb6d664c0 [0x81bd78]) 2017-05-19 19:31:55,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2017-05-19 19:31:55,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb4fd7920 [0x7fb558], "Doorpi_IpFon", "(null)", "Doorpi_IpFon", "(null)", "(null)", "fritz.box") 2017-05-19 19:31:55,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x815900) 2017-05-19 19:31:55,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2017-05-19 19:31:55,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6d664e0 2017-05-19 19:31:55,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2017-05-19 19:31:55,813 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6d664e0 2017-05-19 19:31:55,815 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6d664e0 2017-05-19 19:31:55,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6d664e0 2017-05-19 19:31:55,819 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb4fd7920 [0x7fb558], 0xb6d664e0 [0x815900]) 2017-05-19 19:31:55,822 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2017-05-19 19:31:55,824 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2017-05-19 19:31:55,826 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnTimeMinute59'] 2017-05-19 19:31:55,828 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnTimeMinute59' in configfile 2017-05-19 19:31:55,830 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnTimeMinute59 returns ['10'] 2017-05-19 19:31:55,832 [INFO] [doorpi.doorpi] registering action '10' for event 'OnTimeMinute59' 2017-05-19 19:31:55,834 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnTimeMinute59 (default: ) returns call:**2 2017-05-19 19:31:55,838 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2017-05-19 19:31:55,840 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**2'} was added to new evententry OnTimeMinute59 2017-05-19 19:31:55,843 [WARNING] [root] section dummy_InputPins not found in configfile 2017-05-19 19:31:55,845 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2017-05-19 19:31:55,847 [DEBUG] [doorpi.conf.config_object] create new key is_alive_led in section DoorPi with value '' 2017-05-19 19:31:55,851 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2017-05-19 19:31:55,853 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2017-05-19 19:31:55,859 [DEBUG] [doorpi.action.handler] no actions for event OnStartup - skip fire_event OnStartup from doorpi.doorpi 2017-05-19 19:31:55,861 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2017-05-19 19:31:55,866 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2017-05-19 19:31:55,871 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2017-05-19 19:31:55,873 [INFO] [doorpi.doorpi] DoorPi started successfully 2017-05-19 19:31:55,875 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2017-05-19 19:31:55,878 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2017-05-19 19:31:55,881 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2017-05-19 19:31:55,883 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2017-05-19 19:31:55,885 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.48 2017-05-19 19:31:55,888 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2017-05-19 19:31:55,891 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2017-05-19 19:31:55,893 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2017-05-19 19:31:55,896 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2017-05-19 19:31:55,899 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2017-05-19 19:31:55,903 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x81bd78] about to register (LinphoneCore version: 3.9.0) 2017-05-19 19:31:55,930 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2017-05-19 19:31:55,932 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: starting resolution of 192.168.178.1 2017-05-19 19:31:55,934 [INFO] [doorpi.sipphone.from_linphone] channel 0x82b428: state RES_IN_PROGRESS 2017-05-19 19:31:55,936 [INFO] [doorpi.sipphone.from_linphone] transaction [0x7fc900] channel state changed to [RES_IN_PROGRESS] 2017-05-19 19:31:55,938 [INFO] [doorpi.sipphone.from_linphone] channel 0x82b428: state RES_DONE 2017-05-19 19:31:55,941 [INFO] [doorpi.sipphone.from_linphone] transaction [0x7fc900] channel state changed to [RES_DONE] 2017-05-19 19:31:55,943 [INFO] [doorpi.sipphone.from_linphone] channel 0x82b428: state CONNECTING 2017-05-19 19:31:55,945 [INFO] [doorpi.sipphone.from_linphone] transaction [0x7fc900] channel state changed to [CONNECTING] 2017-05-19 19:31:55,947 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2017-05-19 19:31:55,949 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2017-05-19 19:31:55,952 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.48:5060 2017-05-19 19:31:55,954 [INFO] [doorpi.sipphone.from_linphone] channel 0x82b428: state READY 2017-05-19 19:31:55,956 [INFO] [doorpi.sipphone.from_linphone] transaction [0x7fc900] channel state changed to [READY] 2017-05-19 19:31:55,958 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc900], from state [INIT] to [TRYING] 2017-05-19 19:31:55,961 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.~XftuXLph;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 20 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:31:55,964 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2017-05-19 19:31:55,966 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x7fcb80] takes ownership of transaction [0x7fc900] 2017-05-19 19:31:55,968 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x81bd78] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2017-05-19 19:31:55,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x7fb558, 0x81bd78, 1, "Registration in progress") 2017-05-19 19:31:55,973 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2017-05-19 19:31:55,975 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [registration_state_changed] 2017-05-19 19:31:56,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x7fb558, 0x1) 2017-05-19 19:31:56,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2017-05-19 19:31:56,033 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [network_reachable] 2017-05-19 19:31:56,035 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.~XftuXLph;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=B8B1FAB4341FA648 Call-ID: N7XaFr9Hgf CSeq: 20 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:31:56,051 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:31:56,053 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: discovered public ip and port are [192.168.178.48:5060] 2017-05-19 19:31:56,056 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:31:56,058 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc900], from state [TRYING] to [COMPLETED] 2017-05-19 19:31:56,060 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:31:56,062 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:31:56,064 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x81bd78] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationFailed] 2017-05-19 19:31:56,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x7fb558, 0x81bd78, 4, "Not Found") 2017-05-19 19:31:56,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2017-05-19 19:31:56,070 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [registration_state_changed] 2017-05-19 19:31:56,073 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2017-05-19 19:31:56,075 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2017-05-19 19:32:01,113 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc900], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:32:01,115 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x7fc900] terminated 2017-05-19 19:32:56,095 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e628], from state [INIT] to [TRYING] 2017-05-19 19:32:56,098 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.bVDrMYvVp;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 21 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:32:56,152 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.bVDrMYvVp;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=78D2A1D3021B60D7 Call-ID: N7XaFr9Hgf CSeq: 21 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:32:56,167 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:32:56,169 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:32:56,171 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e628], from state [TRYING] to [COMPLETED] 2017-05-19 19:32:56,174 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:32:56,176 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:33:01,203 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e628], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:33:01,214 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x81e628] terminated 2017-05-19 19:33:56,221 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81c280], from state [INIT] to [TRYING] 2017-05-19 19:33:56,224 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.vD9~QbA4r;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 22 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:33:56,278 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.vD9~QbA4r;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=B10EB253BCB6D950 Call-ID: N7XaFr9Hgf CSeq: 22 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:33:56,293 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:33:56,295 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:33:56,297 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81c280], from state [TRYING] to [COMPLETED] 2017-05-19 19:33:56,300 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:33:56,302 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:34:01,320 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81c280], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:34:01,322 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x81c280] terminated 2017-05-19 19:34:56,344 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x822a10], from state [INIT] to [TRYING] 2017-05-19 19:34:56,360 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.nHjBP7vQJ;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 23 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:34:56,419 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.nHjBP7vQJ;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=28AA0D204C5DCD9F Call-ID: N7XaFr9Hgf CSeq: 23 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:34:56,435 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:34:56,438 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:34:56,440 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x822a10], from state [TRYING] to [COMPLETED] 2017-05-19 19:34:56,442 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:34:56,444 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:35:01,446 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x822a10], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:35:01,453 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x822a10] terminated 2017-05-19 19:35:56,469 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820420], from state [INIT] to [TRYING] 2017-05-19 19:35:56,473 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.NpM0mcW-K;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 24 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:35:56,527 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.NpM0mcW-K;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=D1C3DA021BD4ED53 Call-ID: N7XaFr9Hgf CSeq: 24 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:35:56,553 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:35:56,566 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:35:56,568 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820420], from state [TRYING] to [COMPLETED] 2017-05-19 19:35:56,570 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:35:56,572 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:36:01,579 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820420], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:36:01,581 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x820420] terminated 2017-05-19 19:36:56,624 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x887568], from state [INIT] to [TRYING] 2017-05-19 19:36:56,628 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.ZOc7t~yVB;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 25 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:36:56,682 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.ZOc7t~yVB;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=5BAF2C0527BF0012 Call-ID: N7XaFr9Hgf CSeq: 25 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:36:56,697 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:36:56,699 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:36:56,701 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x887568], from state [TRYING] to [COMPLETED] 2017-05-19 19:36:56,703 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:36:56,705 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:37:01,710 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x887568], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:37:01,717 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x887568] terminated 2017-05-19 19:37:56,756 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825918], from state [INIT] to [TRYING] 2017-05-19 19:37:56,760 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.ojDF8d0eu;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 26 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:37:56,814 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.ojDF8d0eu;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=41C22982D125C2BB Call-ID: N7XaFr9Hgf CSeq: 26 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:37:56,829 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:37:56,831 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:37:56,833 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825918], from state [TRYING] to [COMPLETED] 2017-05-19 19:37:56,835 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:37:56,837 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:38:01,841 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825918], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:38:01,844 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x825918] terminated 2017-05-19 19:38:56,857 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x823db8], from state [INIT] to [TRYING] 2017-05-19 19:38:56,860 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.d-NBU-VAo;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 27 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:38:56,914 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.d-NBU-VAo;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=383B947CA817E709 Call-ID: N7XaFr9Hgf CSeq: 27 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:38:56,941 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:38:56,943 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:38:56,945 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x823db8], from state [TRYING] to [COMPLETED] 2017-05-19 19:38:56,947 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:38:56,949 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:39:01,985 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x823db8], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:39:02,004 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x823db8] terminated 2017-05-19 19:39:56,994 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x82acf8], from state [INIT] to [TRYING] 2017-05-19 19:39:56,998 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.-XovxGTtF;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 28 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:39:57,052 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.-XovxGTtF;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=67783849FD34EBA8 Call-ID: N7XaFr9Hgf CSeq: 28 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:39:57,067 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:39:57,069 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:39:57,071 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x82acf8], from state [TRYING] to [COMPLETED] 2017-05-19 19:39:57,073 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:39:57,075 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:40:02,078 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x82acf8], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:40:02,100 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x82acf8] terminated 2017-05-19 19:40:57,085 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x821058], from state [INIT] to [TRYING] 2017-05-19 19:40:57,088 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.vB-iPqBGS;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 29 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:40:57,142 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.vB-iPqBGS;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=D3E985BB612CD921 Call-ID: N7XaFr9Hgf CSeq: 29 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:40:57,158 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:40:57,160 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:40:57,162 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x821058], from state [TRYING] to [COMPLETED] 2017-05-19 19:40:57,164 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:40:57,166 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:41:02,181 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x821058], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:41:02,232 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x821058] terminated 2017-05-19 19:41:57,198 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825918], from state [INIT] to [TRYING] 2017-05-19 19:41:57,207 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.6HUt658uB;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 30 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:41:57,261 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.6HUt658uB;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=83C3AC500848B667 Call-ID: N7XaFr9Hgf CSeq: 30 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:41:57,277 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:41:57,279 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:41:57,281 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825918], from state [TRYING] to [COMPLETED] 2017-05-19 19:41:57,283 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:41:57,286 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:42:02,316 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825918], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:42:02,319 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x825918] terminated 2017-05-19 19:42:57,320 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc890], from state [INIT] to [TRYING] 2017-05-19 19:42:57,324 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.Mdz-fHwIQ;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 31 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:42:57,378 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.Mdz-fHwIQ;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=67D751DEF11BEEC9 Call-ID: N7XaFr9Hgf CSeq: 31 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:42:57,393 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:42:57,395 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:42:57,397 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc890], from state [TRYING] to [COMPLETED] 2017-05-19 19:42:57,399 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:42:57,401 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:43:02,406 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc890], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:43:02,424 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x7fc890] terminated 2017-05-19 19:43:57,443 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x817c00], from state [INIT] to [TRYING] 2017-05-19 19:43:57,447 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.Qz2cy7k2C;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 32 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:43:57,501 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.Qz2cy7k2C;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=DEE20F650444DEAB Call-ID: N7XaFr9Hgf CSeq: 32 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:43:57,516 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:43:57,518 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:43:57,520 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x817c00], from state [TRYING] to [COMPLETED] 2017-05-19 19:43:57,523 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:43:57,526 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:44:02,579 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x817c00], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:44:02,595 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x817c00] terminated 2017-05-19 19:44:57,554 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e848], from state [INIT] to [TRYING] 2017-05-19 19:44:57,558 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.MJqjqFKVI;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 33 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:44:57,612 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.MJqjqFKVI;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=2A39FC8548425BCC Call-ID: N7XaFr9Hgf CSeq: 33 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:44:57,627 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:44:57,629 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:44:57,631 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e848], from state [TRYING] to [COMPLETED] 2017-05-19 19:44:57,633 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:44:57,635 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:45:02,638 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e848], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:45:02,649 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x81e848] terminated 2017-05-19 19:45:57,672 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fca00], from state [INIT] to [TRYING] 2017-05-19 19:45:57,682 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.YjtGoUB-A;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 34 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:45:57,736 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.YjtGoUB-A;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=6E9DC571249C7795 Call-ID: N7XaFr9Hgf CSeq: 34 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:45:57,752 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:45:57,755 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:45:57,757 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fca00], from state [TRYING] to [COMPLETED] 2017-05-19 19:45:57,759 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:45:57,761 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:46:02,763 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fca00], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:46:02,818 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x7fca00] terminated 2017-05-19 19:46:57,782 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825928], from state [INIT] to [TRYING] 2017-05-19 19:46:57,786 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.Rge6RTc18;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 35 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:46:57,840 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.Rge6RTc18;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=B1E96422AB8CDC1F Call-ID: N7XaFr9Hgf CSeq: 35 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:46:57,855 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:46:57,857 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:46:57,859 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825928], from state [TRYING] to [COMPLETED] 2017-05-19 19:46:57,861 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:46:57,863 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:47:02,876 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x825928], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:47:02,878 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x825928] terminated 2017-05-19 19:47:57,876 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820420], from state [INIT] to [TRYING] 2017-05-19 19:47:57,879 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.HjRP8ElW3;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 36 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:47:57,935 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.HjRP8ElW3;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=DB8235B59810673D Call-ID: N7XaFr9Hgf CSeq: 36 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:47:57,951 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:47:57,953 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:47:57,955 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820420], from state [TRYING] to [COMPLETED] 2017-05-19 19:47:57,958 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:47:57,960 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:48:02,964 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820420], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:48:02,970 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x820420] terminated 2017-05-19 19:48:58,005 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x821278], from state [INIT] to [TRYING] 2017-05-19 19:48:58,009 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.lM4CsvEk5;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 37 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:48:58,063 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.lM4CsvEk5;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=1C8A1F1AD5CE2268 Call-ID: N7XaFr9Hgf CSeq: 37 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:48:58,078 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:48:58,080 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:48:58,082 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x821278], from state [TRYING] to [COMPLETED] 2017-05-19 19:48:58,084 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:48:58,087 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:49:03,092 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x821278], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:49:03,102 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x821278] terminated 2017-05-19 19:49:58,106 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e8c8], from state [INIT] to [TRYING] 2017-05-19 19:49:58,110 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.VasecZlN~;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 38 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:49:58,164 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.VasecZlN~;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=60269D25C0A4B071 Call-ID: N7XaFr9Hgf CSeq: 38 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:49:58,179 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:49:58,181 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:49:58,183 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e8c8], from state [TRYING] to [COMPLETED] 2017-05-19 19:49:58,186 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:49:58,188 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:50:03,216 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x81e8c8], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:50:03,218 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x81e8c8] terminated 2017-05-19 19:50:58,218 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x822c80], from state [INIT] to [TRYING] 2017-05-19 19:50:58,221 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.ENJ4rrqLb;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 39 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:50:58,275 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.ENJ4rrqLb;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=9EFF1539D6252A67 Call-ID: N7XaFr9Hgf CSeq: 39 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:50:58,290 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:50:58,292 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:50:58,295 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x822c80], from state [TRYING] to [COMPLETED] 2017-05-19 19:50:58,297 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:50:58,299 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:51:03,307 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x822c80], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:51:03,309 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x822c80] terminated 2017-05-19 19:51:58,342 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fca00], from state [INIT] to [TRYING] 2017-05-19 19:51:58,348 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.l3avNv6xz;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 40 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:51:58,402 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.l3avNv6xz;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=E1EF23BFD86FC4EE Call-ID: N7XaFr9Hgf CSeq: 40 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:51:58,417 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:51:58,420 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:51:58,422 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fca00], from state [TRYING] to [COMPLETED] 2017-05-19 19:51:58,424 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:51:58,437 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:52:03,441 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fca00], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:52:03,443 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x7fca00] terminated 2017-05-19 19:52:58,433 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x823820], from state [INIT] to [TRYING] 2017-05-19 19:52:58,437 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.KqXaw1sjW;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 41 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:52:58,491 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.KqXaw1sjW;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=AB6F4AB7B902DE7A Call-ID: N7XaFr9Hgf CSeq: 41 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:52:58,506 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:52:58,508 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:52:58,510 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x823820], from state [TRYING] to [COMPLETED] 2017-05-19 19:52:58,512 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:52:58,514 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:53:03,545 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x823820], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:53:03,554 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x823820] terminated 2017-05-19 19:53:58,546 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc880], from state [INIT] to [TRYING] 2017-05-19 19:53:58,549 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.9LkvKxkvv;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 42 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:53:58,604 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.9LkvKxkvv;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=D8C545F9F4242962 Call-ID: N7XaFr9Hgf CSeq: 42 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:53:58,619 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:53:58,621 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:53:58,623 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc880], from state [TRYING] to [COMPLETED] 2017-05-19 19:53:58,625 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:53:58,628 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:54:03,640 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x7fc880], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:54:03,642 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x7fc880] terminated 2017-05-19 19:54:47,964 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /dashboard/pages/status.modules.eventhandler.html 2017-05-19 19:54:48,083 [TRACE] [doorpi.status.webserver_lib.request_handler_static_functions] use onlinefallback - local file /usr/local/etc/DoorPi/../DoorPiWeb/dashboard/pages/status.modules.eventhandler.html not found 2017-05-19 19:54:48,727 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /dashboard/pages/status.modules.eventhandler.html HTTP/1.1', '200', '-') 2017-05-19 19:54:50,716 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 19:54:50,723 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=environment&name=event_handler&output=json.parsed HTTP/1.1', '200', '-') 2017-05-19 19:54:50,760 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 19:54:50,772 [DEBUG] [doorpi.status.status_lib.event_handler] doorpi.status.status_lib.event_handler loaded 2017-05-19 19:54:50,778 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 19:54:50,790 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 19:54:50,799 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=event_handler&name=events_by_source&output=json&_=1495216491296 HTTP/1.1', '200', '-') 2017-05-19 19:54:50,809 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=event_handler&name=actions&output=json&_=1495216491295 HTTP/1.1', '200', '-') 2017-05-19 19:54:50,817 [DEBUG] [doorpi.status.status_lib.history_event] doorpi.status.status_lib.history_event loaded 2017-05-19 19:54:50,819 [DEBUG] [doorpi.action.handler] request last 100 event logs with filter 2017-05-19 19:54:50,834 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /status 2017-05-19 19:54:50,840 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=history_event&output=json&_=1495216491297 HTTP/1.1', '200', '-') 2017-05-19 19:54:50,852 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /status?module=event_handler&name=threads&output=json&_=1495216491298 HTTP/1.1', '200', '-') 2017-05-19 19:54:58,679 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820710], from state [INIT] to [TRYING] 2017-05-19 19:54:58,682 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [541] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.0ZaB0EycR;rport From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" CSeq: 43 REGISTER Call-ID: N7XaFr9Hgf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2017-05-19 19:54:58,737 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [311] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.0ZaB0EycR;rport=5060 From: "DoorPi" ;tag=mTna~bM6v To: "DoorPi" ;tag=2B091C34E62AC7AB Call-ID: N7XaFr9Hgf CSeq: 43 REGISTER User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:54:58,751 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [311] bytes parsed 2017-05-19 19:54:58,754 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:54:58,756 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820710], from state [TRYING] to [COMPLETED] 2017-05-19 19:54:58,758 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2017-05-19 19:54:58,760 [INFO] [doorpi.sipphone.from_linphone] Register refresher [404] reason [Not Found] for proxy [sip:192.168.178.1] 2017-05-19 19:54:59,152 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] public resource: /control/trigger_event 2017-05-19 19:54:59,157 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] { "event_name": [ "OnTimeMinute59" ], "event_source": [ "doorpi.action.SingleActions.time_tick" ] } 2017-05-19 19:54:59,159 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2017-05-19 19:54:59,163 [DEBUG] [doorpi.sipphone.from_linphone] call (**2) 2017-05-19 19:54:59,166 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2017-05-19 19:54:59,168 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2017-05-19 17:54:59.168263 2017-05-19 19:54:59,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0xb4fd7920 [0x7fb558], 0x2d7824 [(nil)]) 2017-05-19 19:54:59,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0xb4614888) 2017-05-19 19:54:59,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2017-05-19 19:54:59,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0xb6d66590 2017-05-19 19:54:59,184 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2017-05-19 19:54:59,178 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 19:54:59,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0xb6d66590 2017-05-19 19:54:59,190 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0xb6d66590 2017-05-19 19:54:59,192 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0xb6d66590 2017-05-19 19:54:59,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0xb6d66590 [0xb4614888], "/mnt/fritzbox/records/2017-05-19_19-54-59.wav") 2017-05-19 19:54:59,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2017-05-19 19:54:59,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0xb6d66590 [0xb4614888], 0x2e0620 [1]) 2017-05-19 19:54:59,205 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2017-05-19 19:54:59,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0xb4fd7920 [0x7fb558], "**2", 0xb6d66590 [0xb4614888]) 2017-05-19 19:54:59,218 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x7fb558, 0xb4617050, 2, "Starting outgoing call") 2017-05-19 19:54:59,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_new() 2017-05-19 19:54:59,222 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_new -> 0xb6d664f0 2017-05-19 19:54:59,224 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_init() 2017-05-19 19:54:59,226 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_init -> 0xb6d664f0 2017-05-19 19:54:59,230 [WARNING] [doorpi.sipphone.from_linphone] There is no object pool created in thread [3035751520]. Use belle_sip_object_pool_push() to create one. Unowned objects not unref'd will be leaked. 2017-05-19 19:54:59,233 [INFO] [doorpi.sipphone.from_linphone] New LinphoneCall [0xb4617050] initialized (LinphoneCore version: 3.9.0) 2017-05-19 19:54:59,229 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call) 2017-05-19 19:54:59,237 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d664f0 [0xb4617050]) 2017-05-19 19:54:59,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb4617e98) 2017-05-19 19:54:59,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 19:54:59,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d66560 2017-05-19 19:54:59,235 [INFO] [doorpi.sipphone.from_linphone] Call 0xb4617050: moving from state LinphoneCallIdle to LinphoneCallOutgoingInit 2017-05-19 19:54:59,248 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_state(0xb6d664f0 [0xb4617050]) 2017-05-19 19:54:59,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_state -> 0x66a8a4 2017-05-19 19:54:59,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_inc_timeout(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 19:54:59,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d66560 2017-05-19 19:54:59,260 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d66560 2017-05-19 19:54:59,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d66560 2017-05-19 19:54:59,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d66560 [0xb4617e98]) 2017-05-19 19:54:59,256 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_inc_timeout -> 0x66a808 2017-05-19 19:54:59,271 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4f3be90 2017-05-19 19:54:59,273 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:54:59,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8b0 2017-05-19 19:54:59,282 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:54:59,287 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:54:59,291 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:54:59,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8b0 2017-05-19 19:54:59,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 19:54:59,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x7fb558, 0xb4617050, 3, "Outgoing call in progress") 2017-05-19 19:54:59,312 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress) 2017-05-19 19:54:59,314 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d664f0 [0xb4617050]) 2017-05-19 19:54:59,317 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb4617e98) 2017-05-19 19:54:59,319 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 19:54:59,322 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [call_state_changed] 2017-05-19 19:54:59,324 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [audio] on call [0xb4617050] 2017-05-19 19:54:59,321 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d66530 2017-05-19 19:54:59,328 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 19:54:59,330 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d66530 2017-05-19 19:54:59,332 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d66530 2017-05-19 19:54:59,336 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [7078] [7079] 2017-05-19 19:54:59,338 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 46 for MSAudio stream. 2017-05-19 19:54:59,340 [INFO] [doorpi.sipphone.from_linphone] Equalizer location: hp 2017-05-19 19:54:59,342 [INFO] [doorpi.sipphone.from_linphone] cannot set noise gate mode to [0] because no volume send 2017-05-19 19:54:59,344 [INFO] [doorpi.sipphone.from_linphone] Cannot determine multicast role for stream type [video] on call [0xb4617050] 2017-05-19 19:54:59,334 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d66530 2017-05-19 19:54:59,348 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d66530 [0xb4617e98]) 2017-05-19 19:54:59,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4f3bd10 2017-05-19 19:54:59,352 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:54:59,347 [INFO] [doorpi.sipphone.from_linphone] RtpSession bound to [0.0.0.0] ports [9078] [9079] 2017-05-19 19:54:59,358 [INFO] [doorpi.sipphone.from_linphone] Setting DSCP to 0 for MSVideo stream. 2017-05-19 19:54:59,360 [INFO] [doorpi.sipphone.from_linphone] Contact has been fixed using proxy 2017-05-19 19:54:59,362 [INFO] [doorpi.sipphone.from_linphone] Don't put text stream on local offer for call [0xb4617050] 2017-05-19 19:54:59,364 [INFO] [doorpi.sipphone.from_linphone] ms_filter_link: MSRtpRecv:0xb4643508,0-->MSVoidSink:0xb463fce0,0 2017-05-19 19:54:59,368 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:54:59,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,374 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8b0 2017-05-19 19:54:59,378 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8b0 2017-05-19 19:54:59,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8b0 2017-05-19 19:54:59,376 [INFO] [doorpi.sipphone.from_linphone] ["DoorPi" ] calling [sip:**2@192.168.178.1] on op [0xb46167a8] 2017-05-19 19:54:59,387 [INFO] [doorpi.sipphone.from_linphone] Skipping top route of initial route-set because same as request-uri. 2017-05-19 19:54:59,389 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb4626270], from state [INIT] to [CALLING] 2017-05-19 19:54:59,391 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [834] bytes INVITE sip:**2@192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.269r6m~So;rport From: "DoorPi" ;tag=I0vTb-O7t To: sip:**2@192.168.178.1 CSeq: 20 INVITE Call-ID: RtByGCXA1O Max-Forwards: 70 Supported: outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 276 Contact: ;+sip.instance="" User-Agent: (belle-sip/1.4.2) v=0 o=Doorpi_IpFon 4 379 IN IP4 192.168.178.48 s=Talk c=IN IP4 192.168.178.48 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7078 RTP/AVP 0 8 101 a=rtpmap:101 telephone-event/8000 m=video 9078 RTP/AVP 96 a=rtpmap:96 VP8/90000 2017-05-19 19:54:59,393 [INFO] [doorpi.sipphone.from_linphone] Call 0xb4617050: moving from state LinphoneCallOutgoingInit to LinphoneCallOutgoingProgress 2017-05-19 19:54:59,396 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 19:54:59,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0xb6d664f0 2017-05-19 19:54:59,400 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2017-05-19 19:54:59,404 [INFO] [doorpi.sipphone.from_linphone] Priority used: 99 2017-05-19 19:54:59,406 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker priority set to SCHED_RR and value (99) 2017-05-19 19:54:59,408 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: received [291] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.269r6m~So;rport=5060 From: "DoorPi" ;tag=I0vTb-O7t To: ;tag=0695292D2F4725C8 Call-ID: RtByGCXA1O CSeq: 20 INVITE User-Agent: FRITZ!OS Content-Length: 0 2017-05-19 19:54:59,425 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2017-05-19 19:54:59,429 [DEBUG] [doorpi.action.handler] [P06JTU] fire for event OnSipPhoneMakeCall this actions [] 2017-05-19 19:54:59,431 [TRACE] [doorpi.action.handler] [P06JTU] try to fire action > with args () and kwargs {} 2017-05-19 19:54:59,433 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2017-05-19 19:54:59,436 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [call_state_changed] 2017-05-19 19:54:59,438 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428] [291] bytes parsed 2017-05-19 19:54:59,440 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2017-05-19 19:54:59,442 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb4626270], from state [CALLING] to [PROCEEDING] 2017-05-19 19:54:59,444 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb4626270], from state [PROCEEDING] to [COMPLETED] 2017-05-19 19:54:59,448 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-05-19 19:54:59,449 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron 2017-05-19 19:54:59,452 [DEBUG] [doorpi.status.webserver_lib.request_handler_static_functions] [192.168.178.36] ('GET /control/trigger_event?event_name=OnTimeMinute59&event_source=doorpi.action.SingleActions.time_tick HTTP/1.1', '200', '-') 2017-05-19 19:54:59,455 [INFO] [doorpi.sipphone.from_linphone] channel [0x82b428]: message sent to [UDP://192.168.178.1:5060], size: [380] bytes ACK sip:**2@192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.48:5060;branch=z9hG4bK.269r6m~So;rport Call-ID: RtByGCXA1O From: "DoorPi" ;tag=I0vTb-O7t To: ;tag=0695292D2F4725C8 Contact: ;+sip.instance="" Max-Forwards: 70 CSeq: 20 ACK 2017-05-19 19:54:59,469 [INFO] [doorpi.sipphone.from_linphone] op [0xb46167a8] : set_or_update_dialog() current=[(nil)] new=[(nil)] 2017-05-19 19:54:59,471 [INFO] [doorpi.sipphone.from_linphone] Op [0xb46167a8] receiving call response [404], dialog is [(nil)] in state [BELLE_SIP_DIALOG_NULL] 2017-05-19 19:54:59,473 [INFO] [doorpi.sipphone.from_linphone] ms_filter_unlink: MSRtpRecv:0xb4643508,0-->MSVoidSink:0xb463fce0,0 2017-05-19 19:54:59,478 [TRACE] [doorpi.action.handler] [P06JTU] finished fire_event for event_name OnSipPhoneMakeCall 2017-05-19 19:54:59,482 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2017-05-19 19:54:59,485 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2017-05-19 19:54:59,488 [INFO] [doorpi.sipphone.from_linphone] FILTER USAGE STATISTICS 2017-05-19 19:54:59,490 [INFO] [doorpi.sipphone.from_linphone] Name Count Time/tick (ms) CPU Usage 2017-05-19 19:54:59,492 [INFO] [doorpi.sipphone.from_linphone] ----------------------------------------------------------- 2017-05-19 19:54:59,494 [INFO] [doorpi.sipphone.from_linphone] MSRtpRecv 16 0.0727642 99.9999 2017-05-19 19:54:59,497 [INFO] [doorpi.sipphone.from_linphone] MSVoidSink 0 0 0 2017-05-19 19:54:59,500 [INFO] [doorpi.sipphone.from_linphone] MSItcSink 0 0 0 2017-05-19 19:54:59,502 [INFO] [doorpi.sipphone.from_linphone] MSTee 0 0 0 2017-05-19 19:54:59,504 [INFO] [doorpi.sipphone.from_linphone] MSSpeexEC 0 0 0 2017-05-19 19:54:59,507 [INFO] [doorpi.sipphone.from_linphone] MSRtpSend 0 0 0 2017-05-19 19:54:59,509 [INFO] [doorpi.sipphone.from_linphone] =========================================================== 2017-05-19 19:54:59,511 [INFO] [doorpi.sipphone.from_linphone] Call 0xb4617050: moving from state LinphoneCallOutgoingProgress to LinphoneCallError 2017-05-19 19:54:59,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_log_updated(0x7fb558, 0xb4640550) 2017-05-19 19:54:59,519 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_log_updated 2017-05-19 19:54:59,521 [INFO] [doorpi.sipphone.from_linphone] MSAudio MSTicker thread exiting 2017-05-19 19:54:59,523 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [call_log_updated] 2017-05-19 19:54:59,525 [INFO] [doorpi.sipphone.from_linphone] Resetting the current call 2017-05-19 19:54:59,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x7fb558, 0xb4617050, 12, "Not Found") 2017-05-19 19:54:59,530 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (12 - Not Found) 2017-05-19 19:54:59,532 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d664f0 [0xb4617050]) 2017-05-19 19:54:59,534 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb4617e98) 2017-05-19 19:54:59,536 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 19:54:59,538 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d66510 2017-05-19 19:54:59,540 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 19:54:59,542 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d66510 2017-05-19 19:54:59,544 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d66510 2017-05-19 19:54:59,546 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d66510 2017-05-19 19:54:59,548 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d66510 [0xb4617e98]) 2017-05-19 19:54:59,550 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4f54170 2017-05-19 19:54:59,552 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:54:59,556 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,558 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8bc 2017-05-19 19:54:59,560 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,563 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8bc 2017-05-19 19:54:59,564 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:54:59,567 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:54:59,574 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,576 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8bc 2017-05-19 19:54:59,578 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 19:54:59,580 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [call_state_changed] 2017-05-19 19:54:59,582 [INFO] [doorpi.sipphone.from_linphone] Call 0xb4617050: moving from state LinphoneCallError to LinphoneCallReleased 2017-05-19 19:54:59,584 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_call_state_changed(0x7fb558, 0xb4617050, 18, "Call released") 2017-05-19 19:54:59,589 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2017-05-19 19:54:59,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Call_get_remote_address(0xb6d664f0 [0xb4617050]) 2017-05-19 19:54:59,594 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xb4617e98) 2017-05-19 19:54:59,587 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:54:59,598 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-05-19 19:54:59,600 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d665a0 2017-05-19 19:54:59,602 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-05-19 19:54:59,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d665a0 2017-05-19 19:54:59,606 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d665a0 2017-05-19 19:54:59,608 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Call_get_remote_address -> 0xb6d665a0 2017-05-19 19:54:59,610 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_instance_method_as_string_uri_only(0xb6d665a0 [0xb4617e98]) 2017-05-19 19:54:59,612 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_instance_method_as_string_uri_only -> 0xb4f54200 2017-05-19 19:54:59,614 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-05-19 19:54:59,619 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,621 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8bc 2017-05-19 19:54:59,623 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,627 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-05-19 19:54:59,625 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8bc 2017-05-19 19:54:59,632 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_calls_nb(0xb4fd7920 [0x7fb558]) 2017-05-19 19:54:59,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_calls_nb -> 0x66a8bc 2017-05-19 19:54:59,636 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_call_state_changed 2017-05-19 19:54:59,638 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x7fb558] notifying [call_state_changed] 2017-05-19 19:54:59,640 [INFO] [doorpi.sipphone.from_linphone] op [0xb46167a8] : set_or_update_dialog() current=[(nil)] new=[(nil)] 2017-05-19 19:55:03,769 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x820710], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:55:03,777 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x820710] terminated 2017-05-19 19:55:31,663 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [INVITE] transaction [0xb4626270], from state [COMPLETED] to [TERMINATED] 2017-05-19 19:55:31,665 [INFO] [doorpi.sipphone.from_linphone] Client INVITE transaction [0xb4626270] terminated 2017-05-19 19:55:31,668 [INFO] [doorpi.sipphone.from_linphone] Destroying op [0xb46167a8] of type [SalOpCall]