2016-03-25 22:54:33,101 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.0 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-03-25 22:54:33,110 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-03-25 22:54:33,117 [DEBUG] [doorpi.doorpi] run 2016-03-25 22:54:33,122 [DEBUG] [doorpi.doorpi] prepare 2016-03-25 22:54:33,127 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-03-25 22:54:33,140 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi. ini 2016-03-25 22:54:33,175 [DEBUG] [doorpi.conf.config_object] __init__ 2016-03-25 22:54:33,179 [TRACE] [doorpi.conf.config_object] get_from_config 2016-03-25 22:54:33,202 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (de fault: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-03-25 22:54:33,207 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (def ault: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-03-25 22:54:33,214 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/us r/local/etc/DoorPi/conf/eventlog.db" 2016-03-25 22:54:33,226 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-03-25 22:54:33,231 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-03-25 22:54:33,236 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source d oorpi.doorpi 2016-03-25 22:54:33,241 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-03-25 22:54:33,246 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorp i.doorpi 2016-03-25 22:54:33,251 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-03-25 22:54:33,256 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source do orpi.doorpi 2016-03-25 22:54:33,260 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-03-25 22:54:33,266 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-03-25 22:54:33,271 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-03-25 22:54:33,276 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source door pi.doorpi 2016-03-25 22:54:33,281 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-03-25 22:54:33,286 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source d oorpi.doorpi 2016-03-25 22:54:33,296 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-03-25 22:54:33,303 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-03-25 22:54:33,320 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs { } was added to new evententry OnShutdown 2016-03-25 22:54:33,325 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and k wargs {} was added to new evententry OnTimeTick 2016-03-25 22:54:33,330 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (defaul t: ) returns 2016-03-25 22:54:33,336 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (def ault: 80) returns 80 2016-03-25 22:54:33,347 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-03-25 22:54:33,352 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.web server 2016-03-25 22:54:33,357 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-03-25 22:54:33,362 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered sourc e doorpi.status.webserver 2016-03-25 22:54:33,367 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webs erver 2016-03-25 22:54:33,372 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-03-25 22:54:33,378 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (defau lt: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-03-25 22:54:33,384 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/lo cal/etc/DoorPi/../DoorPiWeb" 2016-03-25 22:54:33,389 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-03-25 22:54:33,396 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-03-25 22:54:33,400 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-03-25 22:54:33,407 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-03-25 22:54:33,412 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (de fault: AREA_public) returns AREA_public 2016-03-25 22:54:33,418 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-03-25 22:54:33,423 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section Door PiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-03-25 22:54:33,430 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-03-25 22:54:33,435 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['adm inistrator'] 2016-03-25 22:54:33,440 [WARNING] [root] section ReadPermission not found in configfile 2016-03-25 22:54:33,445 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-03-25 22:54:33,450 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator '] 2016-03-25 22:54:33,455 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-03-25 22:54:33,461 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (d efault: []) returns ['door'] 2016-03-25 22:54:33,466 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_keyboard', 'Gro up', 'keyboards', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpi ns_InputPins', 'DoorPi'] 2016-03-25 22:54:33,473 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePer mission (default: []) returns ['installer'] 2016-03-25 22:54:33,477 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-03-25 22:54:33,483 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-03-25 22:54:33,488 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-03-25 22:54:33,493 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserv er asyncron 2016-03-25 22:54:33,501 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.w ebserver_lib.request_handler 2016-03-25 22:54:33,508 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handl er was added 2016-03-25 22:54:33,517 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered sou rce doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,523 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.statu s.webserver_lib.request_handler 2016-03-25 22:54:33,514 [DEBUG] [doorpi.action.handler] [626ZCP] fire for event OnWebServerStart this actions [] 2016-03-25 22:54:33,531 [TRACE] [doorpi.action.handler] [626ZCP] try to fire action WebServerStartupAction wit h args () and kwargs {} 2016-03-25 22:54:33,534 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,543 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.stat us.webserver_lib.request_handler 2016-03-25 22:54:33,545 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-03-25 22:54:33,553 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,559 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi. status.webserver_lib.request_handler 2016-03-25 22:54:33,564 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and regist ered source doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,569 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.sta tus.webserver_lib.request_handler 2016-03-25 22:54:33,575 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registere d source doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,580 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserve r_lib.request_handler 2016-03-25 22:54:33,586 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doo rpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,591 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webse rver_lib.request_handler 2016-03-25 22:54:33,596 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,601 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.we bserver_lib.request_handler 2016-03-25 22:54:33,606 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered sour ce doorpi.status.webserver_lib.request_handler 2016-03-25 22:54:33,611 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpi ns'] 2016-03-25 22:54:33,617 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onbo ardpins) 2016-03-25 22:54:33,622 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to ha ndler 2016-03-25 22:54:33,627 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboard s (default: dummy) returns gpio 2016-03-25 22:54:33,633 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['11'] 2016-03-25 22:54:33,637 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-03-25 22:54:33,643 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins return s [] 2016-03-25 22:54:33,648 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardp ins_keyboard (default: 2000) returns 200.0 2016-03-25 22:54:33,654 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpin s_keyboard (default: 0) returns 0 2016-03-25 22:54:33,659 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-03-25 22:54:33,667 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-03-25 22:54:33,680 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['11'], output_pins = [], bo uncetime = 200.0, polarity = 0) 2016-03-25 22:54:33,685 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_key board (default: BOARD) returns BOARD 2016-03-25 22:54:33,691 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboard pins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-03-25 22:54:33,706 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_ gpio 2016-03-25 22:54:33,714 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-03-25 22:54:33,719 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source do orpi.keyboard.from_gpio 2016-03-25 22:54:33,735 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_11 from doorpi.keyboard.fr om_gpio 2016-03-25 22:54:33,740 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_11 and registered source doorpi.keyboard.from_gpio 2016-03-25 22:54:33,745 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.11 from doorpi .keyboard.from_gpio 2016-03-25 22:54:33,750 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.11 and regis tered source doorpi.keyboard.from_gpio 2016-03-25 22:54:33,765 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-25 22:54:33,770 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi. keyboard.from_gpio 2016-03-25 22:54:33,775 [TRACE] [doorpi.action.handler] register Event OnKeyUp_11 from doorpi.keyboard.from_gp io 2016-03-25 22:54:33,780 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_11 and registered source door pi.keyboard.from_gpio 2016-03-25 22:54:33,790 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.11 from doorpi.keyb oard.from_gpio 2016-03-25 22:54:33,794 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-25 22:54:33,805 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpi o 2016-03-25 22:54:33,810 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorp i.keyboard.from_gpio 2016-03-25 22:54:33,819 [TRACE] [doorpi.action.handler] register Event OnKeyDown_11 from doorpi.keyboard.from_ gpio 2016-03-25 22:54:33,824 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_11 and registered source do orpi.keyboard.from_gpio 2016-03-25 22:54:33,834 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.11 from doorpi.ke yboard.from_gpio 2016-03-25 22:54:33,839 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.11 and register ed source doorpi.keyboard.from_gpio 2016-03-25 22:54:33,844 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs { } was added to event OnShutdown 2016-03-25 22:54:33,855 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-03-25 22:54:33,860 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins return s [] 2016-03-25 22:54:33,883 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment lo aded 2016-03-25 22:54:33,892 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req _config loaded 2016-03-25 22:54:33,909 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.r eq_sipphone loaded 2016-03-25 22:54:33,927 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-03-25 22:54:34,044 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_ lib.req_event_handler loaded 2016-03-25 22:54:34,054 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib. req_webserver loaded 2016-03-25 22:54:34,068 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.r eq_keyboard loaded 2016-03-25 22:54:34,239 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req _system loaded 2016-03-25 22:54:34,262 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and us e this as default 2016-03-25 22:54:34,267 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phon e (default: linphone) returns linphone 2016-03-25 22:54:34,276 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-03-25 22:54:34,287 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBack s loaded 2016-03-25 22:54:34,297 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player load ed 2016-03-25 22:54:34,307 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-03-25 22:54:34,315 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-03-25 22:54:34,320 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-03-25 22:54:34,326 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.f rom_linphone 2016-03-25 22:54:34,331 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-03-25 22:54:34,336 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered sourc e doorpi.sipphone.from_linphone 2016-03-25 22:54:34,342 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.fr om_linphone 2016-03-25 22:54:34,347 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,352 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone. from_linphone 2016-03-25 22:54:34,357 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered sour ce doorpi.sipphone.from_linphone 2016-03-25 22:54:34,362 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.si pphone.from_linphone 2016-03-25 22:54:34,367 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and register ed source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,372 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.s ipphone.from_linphone 2016-03-25 22:54:34,377 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registe red source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,383 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipp hone.from_linphone 2016-03-25 22:54:34,388 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,393 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone .from_linphone 2016-03-25 22:54:34,398 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered sou rce doorpi.sipphone.from_linphone 2016-03-25 22:54:34,403 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.si pphone.from_linphone 2016-03-25 22:54:34,408 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and register ed source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,413 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipph one.from_linphone 2016-03-25 22:54:34,419 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,424 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from do orpi.sipphone.from_linphone 2016-03-25 22:54:34,429 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and r egistered source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,434 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from d oorpi.sipphone.from_linphone 2016-03-25 22:54:34,439 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,444 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.fr om_linphone 2016-03-25 22:54:34,449 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-03-25 22:54:34,454 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-03-25 22:54:34,460 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-03-25 22:54:34,465 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipp hone.linphone_lib.CallBacks 2016-03-25 22:54:34,470 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks wa s added 2016-03-25 22:54:34,475 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,481 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.li nphone_lib.CallBacks 2016-03-25 22:54:34,485 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,490 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone .linphone_lib.CallBacks 2016-03-25 22:54:34,495 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered sou rce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,500 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone. linphone_lib.CallBacks 2016-03-25 22:54:34,506 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered sour ce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,511 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone .linphone_lib.CallBacks 2016-03-25 22:54:34,516 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered sou rce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,521 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipph one.linphone_lib.CallBacks 2016-03-25 22:54:34,526 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,531 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipph one.linphone_lib.CallBacks 2016-03-25 22:54:34,536 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,541 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.si pphone.linphone_lib.CallBacks 2016-03-25 22:54:34,546 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and register ed source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,551 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sippho ne.linphone_lib.CallBacks 2016-03-25 22:54:34,557 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered s ource doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,562 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone. linphone_lib.CallBacks 2016-03-25 22:54:34,567 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered sour ce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,572 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linpho ne_lib.CallBacks 2016-03-25 22:54:34,577 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doo rpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,583 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_li b.CallBacks 2016-03-25 22:54:34,587 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.s ipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,593 [WARNING] [root] section DTMF not found in configfile 2016-03-25 22:54:34,598 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-25 22:54:34,603 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linpho ne_lib.CallBacks 2016-03-25 22:54:34,608 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.s ipphone.linphone_lib.CallBacks is already known 2016-03-25 22:54:34,613 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone .linphone_lib.CallBacks 2016-03-25 22:54:34,618 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered sou rce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,623 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.li nphone_lib.CallBacks 2016-03-25 22:54:34,628 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,633 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone .linphone_lib.CallBacks 2016-03-25 22:54:34,640 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered sou rce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,645 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphon e_lib.CallBacks 2016-03-25 22:54:34,650 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source door pi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,655 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linp hone_lib.CallBacks 2016-03-25 22:54:34,661 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source d oorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,665 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.lin phone_lib.CallBacks 2016-03-25 22:54:34,671 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,675 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone. linphone_lib.CallBacks 2016-03-25 22:54:34,681 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered sour ce doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,686 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linph one_lib.CallBacks 2016-03-25 22:54:34,691 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source do orpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,696 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.li nphone_lib.CallBacks 2016-03-25 22:54:34,701 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,706 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_l ib.CallBacks asyncron 2016-03-25 22:54:34,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-03-25 22:54:34,721 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new - > 0xb4779890 2016-03-25 22:54:34,726 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnC allStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:54:34,731 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init( ) 2016-03-25 22:54:34,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb4779890 2016-03-25 22:54:34,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class _method_new(0xb4798a50, "(null)", "(null)") 2016-03-25 22:54:34,755 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) ini tialized. 2016-03-25 22:54:34,761 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2016-03-25 22:54:34,767 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,ar m,embedded 2016-03-25 22:54:34,772 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-03-25 22:54:34,833 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-03-25 22:54:34,990 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-03-25 22:54:35,025 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:768 - cannot find car d '0' 2016-03-25 22:54:35,030 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4259 - function snd_func_ card_driver returned error: No such file or directory 2016-03-25 22:54:35,035 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:392 - error evaluatin g strings 2016-03-25 22:54:35,040 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4259 - function snd_func_ concat returned error: No such file or directory 2016-03-25 22:54:35,046 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:1251 - error evaluati ng name 2016-03-25 22:54:35,051 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4259 - function snd_func_ refer returned error: No such file or directory 2016-03-25 22:54:35,057 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4738 - Evaluate error: No such file or directory 2016-03-25 22:54:35,062 [INFO] [doorpi.sipphone.from_linphone] also error in pcm.c:2239 - Unknown PCM sysdefa ult 2016-03-25 22:54:35,069 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:768 - cannot find car d '0' 2016-03-25 22:54:35,075 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4259 - function snd_func_ card_driver returned error: No such file or directory 2016-03-25 22:54:35,080 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:392 - error evaluatin g strings 2016-03-25 22:54:35,085 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4259 - function snd_func_ concat returned error: No such file or directory 2016-03-25 22:54:35,091 [INFO] [doorpi.sipphone.from_linphone] also error in confmisc.c:1251 - error evaluati ng name 2016-03-25 22:54:35,096 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4259 - function snd_func_ refer returned error: No such file or directory 2016-03-25 22:54:35,102 [INFO] [doorpi.sipphone.from_linphone] also error in conf.c:4738 - Evaluate error: No such file or directory 2016-03-25 22:54:35,107 [INFO] [doorpi.sipphone.from_linphone] also error in pcm.c:2239 - Unknown PCM sysdefa ult 2016-03-25 22:54:35,112 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-03-25 22:54:35,118 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-03-25 22:54:35,124 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-03-25 22:54:35,129 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/p lugins] 2016-03-25 22:54:35,135 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plug ins: No such file or directory 2016-03-25 22:54:35,140 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-03-25 22:54:35,145 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x16b77a0] registered on core [0x16b7a 70] 2016-03-25 22:54:35,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Co re_callback_global_state_changed(0x16b77a0, 1, "Starting up") 2016-03-25 22:54:35,159 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Co re_callback_global_state_changed 2016-03-25 22:54:35,165 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x16b77a0] notifying [global_st ate_changed] 2016-03-25 22:54:35,170 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-03-25 22:54:35,175 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-03-25 22:54:35,180 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-03-25 22:54:35,186 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-03-25 22:54:35,191 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number= -1, enabled=0) added to default capabilities. 2016-03-25 22:54:35,196 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 22:54:35,201 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 22:54:35,207 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, ena bled=1) added to default capabilities. 2016-03-25 22:54:35,212 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enab led=1) added to default capabilities. 2016-03-25 22:54:35,218 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) a dded to default capabilities. 2016-03-25 22:54:35,223 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) a dded to default capabilities. 2016-03-25 22:54:35,230 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-03-25 22:54:35,235 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) a dded to default capabilities. 2016-03-25 22:54:35,241 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) ad ded to default capabilities. 2016-03-25 22:54:35,246 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) a dded to default capabilities. 2016-03-25 22:54:35,252 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-03-25 22:54:35,257 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-03-25 22:54:35,263 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-03-25 22:54:35,268 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-03-25 22:54:35,273 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-03-25 22:54:35,279 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-03-25 22:54:35,284 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-25 22:54:35,289 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-25 22:54:35,295 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 22:54:35,300 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 22:54:35,306 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 22:54:35,311 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 22:54:35,316 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 22:54:35,323 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 22:54:35,328 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 22:54:35,333 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 22:54:35,338 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 22:54:35,343 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 22:54:35,349 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-03-25 22:54:35,354 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-03-25 22:54:35,359 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-03-25 22:54:35,365 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 22:54:35,370 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 22:54:35,375 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 22:54:35,380 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 22:54:35,386 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 22:54:35,391 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 22:54:35,396 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-03-25 22:54:35,402 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-03-25 22:54:35,407 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-03-25 22:54:35,412 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-03-25 22:54:35,418 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-03-25 22:54:35,423 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-03-25 22:54:35,428 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-03-25 22:54:35,434 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-03-25 22:54:35,439 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-03-25 22:54:35,445 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-03-25 22:54:35,449 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-03-25 22:54:35,455 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-03-25 22:54:35,461 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-03-25 22:54:35,466 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-03-25 22:54:35,472 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-03-25 22:54:35,477 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-03-25 22:54:35,482 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-03-25 22:54:35,488 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-03-25 22:54:35,493 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-03-25 22:54:35,499 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-03-25 22:54:35,503 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-03-25 22:54:35,509 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-03-25 22:54:35,514 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-03-25 22:54:35,520 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-25 22:54:35,525 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-03-25 22:54:35,530 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-03-25 22:54:35,536 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-03-25 22:54:35,541 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-03-25 22:54:35,546 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-03-25 22:54:35,551 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-03-25 22:54:35,557 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-03-25 22:54:35,563 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-03-25 22:54:35,568 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-03-25 22:54:35,573 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-03-25 22:54:35,578 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-25 22:54:35,584 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-25 22:54:35,589 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-03-25 22:54:35,595 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-03-25 22:54:35,600 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-03-25 22:54:35,605 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-03-25 22:54:35,610 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-25 22:54:35,616 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-25 22:54:35,621 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,627 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,632 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,637 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x16b77a0, 2, "(null)") 2016-03-25 22:54:35,644 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-03-25 22:54:35,650 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x16b77a0] notifying [configuring_status] 2016-03-25 22:54:35,657 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-03-25 22:54:35,661 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-03-25 22:54:35,667 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-03-25 22:54:35,672 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-03-25 22:54:35,677 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-03-25 22:54:35,683 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-25 22:54:35,688 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-25 22:54:35,693 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-03-25 22:54:35,699 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-03-25 22:54:35,704 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-03-25 22:54:35,708 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,714 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,719 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,724 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,730 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-03-25 22:54:35,735 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-25 22:54:35,740 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-25 22:54:35,746 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,752 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,756 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-03-25 22:54:35,762 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-25 22:54:35,767 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,773 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-25 22:54:35,778 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x16c0808] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-25 22:54:35,784 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x16c0a38] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-25 22:54:35,790 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x16c0bf0] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-25 22:54:35,795 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 48128 2016-03-25 22:54:35,801 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,806 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x16b77a0, 2, "Ready") 2016-03-25 22:54:35,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-25 22:54:35,831 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x16b77a0] notifying [global_state_changed] 2016-03-25 22:54:35,836 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb4779890 2016-03-25 22:54:35,841 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns 621 2016-03-25 22:54:35,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb4779890 [0x16b77a0], "621 ") 2016-03-25 22:54:35,855 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-03-25 22:54:35,862 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-03-25 22:54:35,869 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-03-25 22:54:35,874 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb4779890 [0x16b77a0], 2) 2016-03-25 22:54:35,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-03-25 22:54:35,879 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-25 22:54:35,896 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-03-25 22:54:35,902 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb4779890 [0x16b77a0], 0x2e0614 [0]) 2016-03-25 22:54:35,909 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-03-25 22:54:35,915 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-25 22:54:35,920 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-25 22:54:35,925 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-03-25 22:54:35,932 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb47798a8 2016-03-25 22:54:35,938 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb4779890 [0x16b77a0], 0xb47798a8 [0xb47798b0]) 2016-03-25 22:54:35,944 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x16c0808] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-03-25 22:54:35,949 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x16c0a38] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-25 22:54:35,955 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x16c0bf0] on [sip:0.0.0.0:48128;transport=TLS] destroyed 2016-03-25 22:54:35,960 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-25 22:54:35,966 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x16c41f0] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-25 22:54:35,972 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x16c0768] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-25 22:54:35,977 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x16c0880] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-25 22:54:35,983 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 44456 2016-03-25 22:54:35,988 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,994 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 22:54:35,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-03-25 22:54:36,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb47798a8) 2016-03-25 22:54:36,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-03-25 22:54:36,018 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-03-25 22:54:36,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb4779890 [0x16b77a0], 0x2e0614 [0]) 2016-03-25 22:54:36,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-03-25 22:54:36,037 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-03-25 22:54:36,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb4779890 [0x16b77a0], "") 2016-03-25 22:54:36,050 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-25 22:54:36,055 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-25 22:54:36,063 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-25 22:54:36,068 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-25 22:54:36,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-03-25 22:54:36,080 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-03-25 22:54:36,085 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-03-25 22:54:36,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb4779890 [0x16b77a0], 0) 2016-03-25 22:54:36,096 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-25 22:54:36,101 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-25 22:54:36,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-03-25 22:54:36,115 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-03-25 22:54:36,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb4779890 [0x16b77a0], 120) 2016-03-25 22:54:36,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-03-25 22:54:36,134 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-03-25 22:54:36,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb4779890 [0x16b77a0], 15) 2016-03-25 22:54:36,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-03-25 22:54:36,153 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2016-03-25 22:54:36,160 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-03-25 22:54:36,165 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-25 22:54:36,174 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-03-25 22:54:36,178 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:54:36,184 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-03-25 22:54:36,189 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-03-25 22:54:36,194 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:54:36,199 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-03-25 22:54:36,204 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:54:36,209 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-03-25 22:54:36,215 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-03-25 22:54:36,220 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-03-25 22:54:36,226 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-03-25 22:54:36,231 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-25 22:54:36,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb4779890 [0x16b77a0], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-03-25 22:54:36,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-03-25 22:54:36,255 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2016-03-25 22:54:36,250 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:54:36,266 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-25 22:54:36,271 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,277 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-03-25 22:54:36,282 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,287 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,293 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,298 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,303 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,308 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-03-25 22:54:36,314 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-03-25 22:54:36,319 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-03-25 22:54:36,325 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-03-25 22:54:36,334 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,340 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb46cba08 2016-03-25 22:54:36,350 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,344 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:54:36,356 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb46ca7d0 2016-03-25 22:54:36,368 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-03-25 22:54:36,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb4779890 [0x16b77a0], "ALSA: USB PnP Sound Device") 2016-03-25 22:54:36,380 [WARNING] [doorpi.sipphone.from_linphone] no card with id ALSA: USB PnP Sound Device 2016-03-25 22:54:36,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-03-25 22:54:36,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb46ca7d0 2016-03-25 22:54:36,403 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-03-25 22:54:36,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb4779890 [0x16b77a0], "ALSA: USB PnP Sound Device") 2016-03-25 22:54:36,415 [WARNING] [doorpi.sipphone.from_linphone] no card with id ALSA: USB PnP Sound Device 2016-03-25 22:54:36,419 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-03-25 22:54:36,427 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,432 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb46cb8f0 2016-03-25 22:54:36,438 [INFO] [doorpi.sipphone.from_linphone] found 1 possible sounddevices: 2016-03-25 22:54:36,444 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-03-25 22:54:36,449 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 22:54:36,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,461 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb46cb8f0 2016-03-25 22:54:36,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4779890 [0x16b77a0], "ALSA: default device") 2016-03-25 22:54:36,473 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-25 22:54:36,478 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4779890 [0x16b77a0], "ALSA: default device") 2016-03-25 22:54:36,484 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-25 22:54:36,489 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-03-25 22:54:36,495 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 22:54:36,500 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,506 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb46ca860 2016-03-25 22:54:36,512 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-03-25 22:54:36,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,524 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb46ca890 2016-03-25 22:54:36,530 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-03-25 22:54:36,535 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-03-25 22:54:36,541 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:36,547 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1c38) 2016-03-25 22:54:36,553 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,558 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b410 2016-03-25 22:54:36,564 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,569 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b410 2016-03-25 22:54:36,574 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b410 2016-03-25 22:54:36,580 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1ca8) 2016-03-25 22:54:36,586 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,591 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b420 2016-03-25 22:54:36,598 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b420 2016-03-25 22:54:36,610 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b420 2016-03-25 22:54:36,616 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1d10) 2016-03-25 22:54:36,623 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,628 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b430 2016-03-25 22:54:36,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,640 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b430 2016-03-25 22:54:36,645 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b430 2016-03-25 22:54:36,652 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1d78) 2016-03-25 22:54:36,658 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,664 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b440 2016-03-25 22:54:36,670 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,676 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b440 2016-03-25 22:54:36,683 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b440 2016-03-25 22:54:36,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1dd0) 2016-03-25 22:54:36,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,700 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b450 2016-03-25 22:54:36,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b450 2016-03-25 22:54:36,718 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b450 2016-03-25 22:54:36,725 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1e28) 2016-03-25 22:54:36,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,737 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b460 2016-03-25 22:54:36,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b460 2016-03-25 22:54:36,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b460 2016-03-25 22:54:36,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16c04d8) 2016-03-25 22:54:36,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b470 2016-03-25 22:54:36,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b470 2016-03-25 22:54:36,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b470 2016-03-25 22:54:36,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16c0530) 2016-03-25 22:54:36,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,806 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b480 2016-03-25 22:54:36,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b480 2016-03-25 22:54:36,823 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b480 2016-03-25 22:54:36,829 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16c0598) 2016-03-25 22:54:36,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,841 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b490 2016-03-25 22:54:36,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,854 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b490 2016-03-25 22:54:36,860 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b490 2016-03-25 22:54:36,866 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16c05f0) 2016-03-25 22:54:36,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:36,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b4a0 2016-03-25 22:54:36,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:36,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b4a0 2016-03-25 22:54:36,894 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b4a0 2016-03-25 22:54:36,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb46cba30 2016-03-25 22:54:36,906 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b410 [0x16b1c38]) 2016-03-25 22:54:36,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:36,917 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b410 [0x16b1c38]) 2016-03-25 22:54:36,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:36,929 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-03-25 22:54:36,935 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b410 [0x16b1c38], 0x2e0614 [0]) 2016-03-25 22:54:36,941 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:36,953 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b420 [0x16b1ca8]) 2016-03-25 22:54:36,960 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c9520 2016-03-25 22:54:36,966 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b420 [0x16b1ca8]) 2016-03-25 22:54:36,972 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c9520 2016-03-25 22:54:36,977 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-25 22:54:36,982 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b420 [0x16b1ca8], 0x2e0614 [0]) 2016-03-25 22:54:36,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:36,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b430 [0x16b1d10]) 2016-03-25 22:54:37,001 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:37,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b430 [0x16b1d10]) 2016-03-25 22:54:37,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:37,018 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-25 22:54:37,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b430 [0x16b1d10], 0x2e0614 [0]) 2016-03-25 22:54:37,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b440 [0x16b1d78]) 2016-03-25 22:54:37,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c9520 2016-03-25 22:54:37,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b440 [0x16b1d78]) 2016-03-25 22:54:37,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c9520 2016-03-25 22:54:37,060 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-03-25 22:54:37,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b440 [0x16b1d78], 0x2e0620 [1]) 2016-03-25 22:54:37,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b450 [0x16b1dd0]) 2016-03-25 22:54:37,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:37,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b450 [0x16b1dd0]) 2016-03-25 22:54:37,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:37,100 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-03-25 22:54:37,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b450 [0x16b1dd0], 0x2e0620 [1]) 2016-03-25 22:54:37,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b460 [0x16b1e28]) 2016-03-25 22:54:37,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b460 [0x16b1e28]) 2016-03-25 22:54:37,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,141 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-03-25 22:54:37,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b460 [0x16b1e28], 0x2e0614 [0]) 2016-03-25 22:54:37,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b470 [0x16c04d8]) 2016-03-25 22:54:37,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:37,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b470 [0x16c04d8]) 2016-03-25 22:54:37,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c96e0 2016-03-25 22:54:37,184 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-03-25 22:54:37,189 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b470 [0x16c04d8], 0x2e0614 [0]) 2016-03-25 22:54:37,196 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b480 [0x16c0530]) 2016-03-25 22:54:37,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c9520 2016-03-25 22:54:37,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b480 [0x16c0530]) 2016-03-25 22:54:37,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46c9520 2016-03-25 22:54:37,226 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-25 22:54:37,232 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b480 [0x16c0530], 0x2e0614 [0]) 2016-03-25 22:54:37,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b490 [0x16c0598]) 2016-03-25 22:54:37,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b490 [0x16c0598]) 2016-03-25 22:54:37,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,269 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-25 22:54:37,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b490 [0x16c0598], 0x2e0614 [0]) 2016-03-25 22:54:37,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b4a0 [0x16c05f0]) 2016-03-25 22:54:37,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b4a0 [0x16c05f0]) 2016-03-25 22:54:37,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,310 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-25 22:54:37,316 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b4a0 [0x16c05f0], 0x2e0614 [0]) 2016-03-25 22:54:37,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,329 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,333 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb46cba08 2016-03-25 22:54:37,339 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,345 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb46cba08 2016-03-25 22:54:37,350 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-03-25 22:54:37,355 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-03-25 22:54:37,360 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 22:54:37,365 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb46cba08 2016-03-25 22:54:37,377 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-03-25 22:54:37,382 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 22:54:37,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb46cba08 2016-03-25 22:54:37,400 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-03-25 22:54:37,406 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,412 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb46cba08 2016-03-25 22:54:37,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb4779890 [0x16b77a0], 0x2e0620 [1]) 2016-03-25 22:54:37,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-03-25 22:54:37,430 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb4779890 [0x16b77a0], "StaticImage: Static picture") 2016-03-25 22:54:37,436 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-03-25 22:54:37,442 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-03-25 22:54:37,448 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb4779890 [0x16b77a0], "vga") 2016-03-25 22:54:37,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-03-25 22:54:37,460 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb46ca980 2016-03-25 22:54:37,472 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-03-25 22:54:37,477 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-03-25 22:54:37,482 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,488 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x16b1b98) 2016-03-25 22:54:37,494 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 22:54:37,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6d2b4b0 2016-03-25 22:54:37,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 22:54:37,514 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6d2b4b0 2016-03-25 22:54:37,520 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6d2b4b0 2016-03-25 22:54:37,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb46cbaa8 2016-03-25 22:54:37,532 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b4b0 [0x16b1b98]) 2016-03-25 22:54:37,538 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,544 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,550 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-03-25 22:54:37,557 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6d2b4b0 [0x16b1b98]) 2016-03-25 22:54:37,563 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb47798a8 2016-03-25 22:54:37,567 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-03-25 22:54:37,573 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4779890 [0x16b77a0], 0xb6d2b4b0 [0x16b1b98], 0x2e0620 [1]) 2016-03-25 22:54:37,579 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x151f8bc 2016-03-25 22:54:37,585 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2016-03-25 22:54:37,590 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 621 2016-03-25 22:54:37,595 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 621) returns ******* 2016-03-25 22:54:37,600 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns 2016-03-25 22:54:37,605 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-03-25 22:54:37,611 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb4779890 [0x16b77a0]) 2016-03-25 22:54:37,617 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x16ec800) 2016-03-25 22:54:37,623 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-03-25 22:54:37,629 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6d2b4c0 2016-03-25 22:54:37,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-03-25 22:54:37,639 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6d2b4c0 2016-03-25 22:54:37,645 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6d2b4c0 2016-03-25 22:54:37,652 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6d2b4c0 2016-03-25 22:54:37,658 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns 621 2016-03-25 22:54:37,663 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("621 ") 2016-03-25 22:54:37,672 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x16c1bb0) 2016-03-25 22:54:37,678 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-03-25 22:54:37,684 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6d2b4d0 2016-03-25 22:54:37,690 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-03-25 22:54:37,696 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6d2b4d0 2016-03-25 22:54:37,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6d2b4d0 2016-03-25 22:54:37,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6d2b4d0 2016-03-25 22:54:37,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6d2b4c0 [0x16ec800], 0xb6d2b4d0 [0x16c1bb0]) 2016-03-25 22:54:37,720 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-03-25 22:54:37,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6d2b4c0 [0x16ec800], "sip:192.168.1.1") 2016-03-25 22:54:37,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-03-25 22:54:37,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6d2b4c0 [0x16ec800], 0x2e0620 [1]) 2016-03-25 22:54:37,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-03-25 22:54:37,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb4779890 [0x16b77a0], 0xb6d2b4c0 [0x16ec800]) 2016-03-25 22:54:37,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x151f8bc 2016-03-25 22:54:37,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb4779890 [0x16b77a0], 0xb6d2b4c0 [0x16ec800]) 2016-03-25 22:54:37,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-03-25 22:54:37,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb4779890 [0x16b77a0], "621", "(null)", "bandit1100", "(null)", "(null)", "") 2016-03-25 22:54:37,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x16ebe70) 2016-03-25 22:54:37,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-03-25 22:54:37,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6d2b4e0 2016-03-25 22:54:37,803 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-03-25 22:54:37,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6d2b4e0 2016-03-25 22:54:37,815 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6d2b4e0 2016-03-25 22:54:37,820 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6d2b4e0 2016-03-25 22:54:37,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb4779890 [0x16b77a0], 0xb6d2b4e0 [0x16ebe70]) 2016-03-25 22:54:37,833 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-03-25 22:54:37,839 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-03-25 22:54:37,843 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-03-25 22:54:37,849 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-03-25 22:54:37,854 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-03-25 22:54:37,859 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-03-25 22:54:37,865 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-03-25 22:54:37,872 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-03-25 22:54:37,877 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-03-25 22:54:37,883 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_InputPins (default: ) returns call:11 2016-03-25 22:54:37,891 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-03-25 22:54:37,896 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '11'} was added to new evententry OnKeyPressed_onboardpins.11 2016-03-25 22:54:37,902 [WARNING] [root] section DTMF not found in configfile 2016-03-25 22:54:37,907 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-25 22:54:37,913 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-03-25 22:54:37,918 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-03-25 22:54:37,927 [DEBUG] [doorpi.action.handler] [F52NL0] fire for event OnStartup this actions [] 2016-03-25 22:54:37,934 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-03-25 22:54:37,943 [TRACE] [doorpi.action.handler] [F52NL0] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-03-25 22:54:37,947 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-03-25 22:54:38,956 [TRACE] [doorpi.action.handler] [F52NL0] finished fire_event for event_name OnStartup 2016-03-25 22:54:38,960 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-03-25 22:54:38,967 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-03-25 22:54:38,972 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-03-25 22:54:38,980 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-03-25 22:54:38,977 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-03-25 22:54:38,993 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-03-25 22:54:38,998 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-25 22:54:39,004 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.40 2016-03-25 22:54:39,009 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-03-25 22:54:39,016 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-25 22:54:39,020 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-25 22:54:39,028 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-25 22:54:39,033 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-25 22:54:39,040 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x16ec800] about to register (LinphoneCore version: 3.9.0) 2016-03-25 22:54:39,059 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-03-25 22:54:39,064 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98]: starting resolution of 192.168.1.1 2016-03-25 22:54:39,069 [INFO] [doorpi.sipphone.from_linphone] channel 0x16c6e98: state RES_IN_PROGRESS 2016-03-25 22:54:39,075 [INFO] [doorpi.sipphone.from_linphone] transaction [0x16ee960] channel state changed to [RES_IN_PROGRESS] 2016-03-25 22:54:39,080 [INFO] [doorpi.sipphone.from_linphone] channel 0x16c6e98: state RES_DONE 2016-03-25 22:54:39,086 [INFO] [doorpi.sipphone.from_linphone] transaction [0x16ee960] channel state changed to [RES_DONE] 2016-03-25 22:54:39,090 [INFO] [doorpi.sipphone.from_linphone] channel 0x16c6e98: state CONNECTING 2016-03-25 22:54:39,096 [INFO] [doorpi.sipphone.from_linphone] transaction [0x16ee960] channel state changed to [CONNECTING] 2016-03-25 22:54:39,101 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.1.1:5060] 2016-03-25 22:54:39,106 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-03-25 22:54:39,112 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.40:5060 2016-03-25 22:54:39,117 [INFO] [doorpi.sipphone.from_linphone] channel 0x16c6e98: state READY 2016-03-25 22:54:39,122 [INFO] [doorpi.sipphone.from_linphone] transaction [0x16ee960] channel state changed to [READY] 2016-03-25 22:54:39,128 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x16ee960], from state [INIT] to [TRYING] 2016-03-25 22:54:39,134 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98]: message sent to [UDP://192.168.1.1:5060], size: [498] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.tcReC9Gcf;rport From: "621" ;tag=tcKPc~8eN To: "621" CSeq: 20 REGISTER Call-ID: acl2TX7qAW Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-03-25 22:54:39,143 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-03-25 22:54:39,148 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x16f2610] takes ownership of transaction [0x16ee960] 2016-03-25 22:54:39,153 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x16ec800] for identity ["621" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-03-25 22:54:39,158 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x16b77a0, 0x16ec800, 1, "Registration in progress") 2016-03-25 22:54:39,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-25 22:54:39,170 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x16b77a0] notifying [registration_state_changed] 2016-03-25 22:54:39,226 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x16b77a0, 0x1) 2016-03-25 22:54:39,233 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-03-25 22:54:39,237 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x16b77a0] notifying [network_reachable] 2016-03-25 22:54:39,243 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98]: received [354] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.tcReC9Gcf;rport=5060 From: "621" ;tag=tcKPc~8eN To: "621" ;tag=E088703717C5C037 Call-ID: acl2TX7qAW CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="B4A981960BD31BA4" User-Agent: FRITZ!OS Content-Length: 0 2016-03-25 22:54:39,267 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98] [354] bytes parsed 2016-03-25 22:54:39,272 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98]: discovered public ip and port are [192.168.1.40:5060] 2016-03-25 22:54:39,277 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-25 22:54:39,283 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x16ee960], from state [TRYING] to [COMPLETED] 2016-03-25 22:54:39,289 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=621, realm= 2016-03-25 22:54:39,295 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [621] realm [fritz.box] 2016-03-25 22:54:39,301 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x16ee7c8], from state [INIT] to [TRYING] 2016-03-25 22:54:39,308 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98]: message sent to [UDP://192.168.1.1:5060], size: [651] bytes REGISTER sip:192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.WkbAlIcbz;rport From: "621" ;tag=tcKPc~8eN To: "621" CSeq: 21 REGISTER Call-ID: acl2TX7qAW Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="B4A981960BD31BA4", username="621", uri="sip:192.168.1.1", response="4fa1dfb60b82fd3f376b52bc193fc413" 2016-03-25 22:54:39,316 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.1.1 2016-03-25 22:54:39,321 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-03-25 22:54:39,378 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98]: received [656] new bytes from [UDP://192.168.1.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.40:5060;branch=z9hG4bK.WkbAlIcbz;rport=5060 From: "621" ;tag=tcKPc~8eN To: "621" ;tag=9D283756471200BC Call-ID: acl2TX7qAW CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7490 113.06.51 (Feb 3 2016) Supported: 100rel,replaces,timer Allow-Events: telephone-event,refer,reg Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH Accept: application/sdp, multipart/mixed Accept-Encoding: identity Content-Length: 0 2016-03-25 22:54:39,411 [INFO] [doorpi.sipphone.from_linphone] channel [0x16c6e98] [656] bytes parsed 2016-03-25 22:54:39,416 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-03-25 22:54:39,421 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x16ee7c8], from state [TRYING] to [COMPLETED] 2016-03-25 22:54:39,426 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x16f2610]: has no contact for request [0x16e9f10]. 2016-03-25 22:54:39,432 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2016-03-25 22:54:39,437 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.1.1] 2016-03-25 22:54:39,443 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x16ec800] for identity ["621" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2016-03-25 22:54:39,448 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x16b77a0, 0x16ec800, 2, "Registration successful") 2016-03-25 22:54:39,455 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-03-25 22:54:39,460 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x16b77a0] notifying [registration_state_changed] 2016-03-25 22:54:44,360 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x16ee960], from state [COMPLETED] to [TERMINATED] 2016-03-25 22:54:44,365 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x16ee960] terminated 2016-03-25 22:54:44,475 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x16ee7c8], from state [COMPLETED] to [TERMINATED] 2016-03-25 22:54:44,480 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x16ee7c8] terminated ^C2016-03-25 22:55:40,224 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2016-03-25 22:55:40,229 [DEBUG] [doorpi.doorpi] destroy doorpi 2016-03-25 22:55:40,235 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started -1225064448)>, ] 2016-03-25 22:55:40,240 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2016-03-25 22:55:40,249 [DEBUG] [doorpi.action.handler] [GZ5OSR] fire for event OnShutdown this actions [, , , ] 2016-03-25 22:55:40,257 [TRACE] [doorpi.action.handler] [GZ5OSR] try to fire action TimeTickDestroyAction with args () and kwargs {} 2016-03-25 22:55:40,263 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2016-03-25 22:55:40,254 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2016-03-25 22:55:40,274 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2016-03-25 22:55:40,278 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,284 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2016-03-25 22:55:40,289 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2016-03-25 22:55:40,296 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,301 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2016-03-25 22:55:40,306 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2016-03-25 22:55:40,310 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,315 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2016-03-25 22:55:40,320 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2016-03-25 22:55:40,326 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,331 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2016-03-25 22:55:40,336 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2016-03-25 22:55:40,341 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,346 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2016-03-25 22:55:40,352 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2016-03-25 22:55:40,357 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,362 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2016-03-25 22:55:40,367 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2016-03-25 22:55:40,372 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,377 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2016-03-25 22:55:40,383 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2016-03-25 22:55:40,387 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,393 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2016-03-25 22:55:40,398 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2016-03-25 22:55:40,403 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,408 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2016-03-25 22:55:40,413 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2016-03-25 22:55:40,418 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,424 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2016-03-25 22:55:40,429 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2016-03-25 22:55:40,434 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,438 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2016-03-25 22:55:40,444 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2016-03-25 22:55:40,448 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,454 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2016-03-25 22:55:40,459 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2016-03-25 22:55:40,464 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,470 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2016-03-25 22:55:40,474 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2016-03-25 22:55:40,480 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,485 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2016-03-25 22:55:40,490 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2016-03-25 22:55:40,495 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,499 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2016-03-25 22:55:40,505 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2016-03-25 22:55:40,510 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,514 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2016-03-25 22:55:40,519 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2016-03-25 22:55:40,524 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,529 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2016-03-25 22:55:40,534 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2016-03-25 22:55:40,539 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,544 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2016-03-25 22:55:40,550 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2016-03-25 22:55:40,554 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,560 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2016-03-25 22:55:40,565 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2016-03-25 22:55:40,569 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,574 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2016-03-25 22:55:40,579 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2016-03-25 22:55:40,584 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,589 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2016-03-25 22:55:40,594 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2016-03-25 22:55:40,599 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,604 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2016-03-25 22:55:40,609 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2016-03-25 22:55:40,615 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,620 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2016-03-25 22:55:40,625 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2016-03-25 22:55:40,630 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,635 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2016-03-25 22:55:40,641 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2016-03-25 22:55:40,646 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,651 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2016-03-25 22:55:40,656 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2016-03-25 22:55:40,661 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,666 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2016-03-25 22:55:40,671 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2016-03-25 22:55:40,677 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,681 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2016-03-25 22:55:40,687 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2016-03-25 22:55:40,692 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,697 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2016-03-25 22:55:40,702 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2016-03-25 22:55:40,707 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,713 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2016-03-25 22:55:40,717 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2016-03-25 22:55:40,722 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,727 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2016-03-25 22:55:40,732 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2016-03-25 22:55:40,737 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,742 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2016-03-25 22:55:40,747 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2016-03-25 22:55:40,752 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,757 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2016-03-25 22:55:40,763 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2016-03-25 22:55:40,768 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,773 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2016-03-25 22:55:40,778 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2016-03-25 22:55:40,783 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,788 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2016-03-25 22:55:40,793 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2016-03-25 22:55:40,798 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,803 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2016-03-25 22:55:40,809 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2016-03-25 22:55:40,814 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,819 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2016-03-25 22:55:40,824 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2016-03-25 22:55:40,829 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,834 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2016-03-25 22:55:40,839 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2016-03-25 22:55:40,845 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,849 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2016-03-25 22:55:40,855 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2016-03-25 22:55:40,860 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,865 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2016-03-25 22:55:40,870 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2016-03-25 22:55:40,875 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,880 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2016-03-25 22:55:40,886 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2016-03-25 22:55:40,891 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,896 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2016-03-25 22:55:40,901 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2016-03-25 22:55:40,906 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,911 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2016-03-25 22:55:40,917 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2016-03-25 22:55:40,922 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,928 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2016-03-25 22:55:40,933 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2016-03-25 22:55:40,938 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,943 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2016-03-25 22:55:40,948 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2016-03-25 22:55:40,953 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,958 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2016-03-25 22:55:40,964 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2016-03-25 22:55:40,969 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,974 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2016-03-25 22:55:40,979 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2016-03-25 22:55:40,983 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:40,989 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2016-03-25 22:55:40,993 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2016-03-25 22:55:40,999 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,004 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2016-03-25 22:55:41,009 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2016-03-25 22:55:41,014 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,019 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2016-03-25 22:55:41,024 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2016-03-25 22:55:41,030 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,035 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2016-03-25 22:55:41,040 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2016-03-25 22:55:41,045 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,050 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2016-03-25 22:55:41,055 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2016-03-25 22:55:41,061 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,066 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2016-03-25 22:55:41,071 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2016-03-25 22:55:41,076 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,081 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2016-03-25 22:55:41,086 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2016-03-25 22:55:41,091 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,096 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2016-03-25 22:55:41,101 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2016-03-25 22:55:41,106 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,111 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2016-03-25 22:55:41,116 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2016-03-25 22:55:41,121 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,126 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2016-03-25 22:55:41,131 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2016-03-25 22:55:41,136 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,141 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2016-03-25 22:55:41,147 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2016-03-25 22:55:41,152 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,157 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2016-03-25 22:55:41,162 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2016-03-25 22:55:41,167 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,172 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2016-03-25 22:55:41,177 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2016-03-25 22:55:41,183 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,187 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2016-03-25 22:55:41,193 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2016-03-25 22:55:41,198 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,203 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2016-03-25 22:55:41,208 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2016-03-25 22:55:41,213 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,218 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2016-03-25 22:55:41,224 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2016-03-25 22:55:41,229 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,234 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2016-03-25 22:55:41,241 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2016-03-25 22:55:41,246 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,251 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2016-03-25 22:55:41,256 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2016-03-25 22:55:41,261 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,266 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2016-03-25 22:55:41,271 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2016-03-25 22:55:41,277 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,282 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2016-03-25 22:55:41,287 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2016-03-25 22:55:41,292 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,297 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2016-03-25 22:55:41,302 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2016-03-25 22:55:41,307 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,313 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2016-03-25 22:55:41,318 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2016-03-25 22:55:41,323 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,328 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2016-03-25 22:55:41,333 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2016-03-25 22:55:41,337 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,343 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2016-03-25 22:55:41,348 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2016-03-25 22:55:41,353 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,358 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2016-03-25 22:55:41,363 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2016-03-25 22:55:41,368 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,373 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2016-03-25 22:55:41,378 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2016-03-25 22:55:41,384 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,389 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2016-03-25 22:55:41,393 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2016-03-25 22:55:41,398 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,403 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2016-03-25 22:55:41,408 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2016-03-25 22:55:41,413 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,417 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2016-03-25 22:55:41,423 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2016-03-25 22:55:41,429 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,434 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2016-03-25 22:55:41,439 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2016-03-25 22:55:41,443 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,449 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2016-03-25 22:55:41,453 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2016-03-25 22:55:41,458 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,463 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2016-03-25 22:55:41,468 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2016-03-25 22:55:41,472 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,477 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2016-03-25 22:55:41,482 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2016-03-25 22:55:41,487 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,493 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2016-03-25 22:55:41,498 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2016-03-25 22:55:41,503 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,508 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2016-03-25 22:55:41,513 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2016-03-25 22:55:41,518 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,523 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2016-03-25 22:55:41,528 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2016-03-25 22:55:41,533 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,538 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2016-03-25 22:55:41,544 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2016-03-25 22:55:41,549 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,554 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2016-03-25 22:55:41,560 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2016-03-25 22:55:41,566 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,570 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2016-03-25 22:55:41,576 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2016-03-25 22:55:41,581 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,586 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2016-03-25 22:55:41,591 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2016-03-25 22:55:41,596 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,601 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2016-03-25 22:55:41,606 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2016-03-25 22:55:41,612 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,617 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2016-03-25 22:55:41,622 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2016-03-25 22:55:41,627 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,632 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2016-03-25 22:55:41,638 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2016-03-25 22:55:41,643 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,648 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2016-03-25 22:55:41,653 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2016-03-25 22:55:41,658 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,663 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2016-03-25 22:55:41,668 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2016-03-25 22:55:41,674 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,678 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2016-03-25 22:55:41,684 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2016-03-25 22:55:41,689 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,694 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2016-03-25 22:55:41,699 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2016-03-25 22:55:41,705 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,709 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2016-03-25 22:55:41,715 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2016-03-25 22:55:41,720 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,725 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2016-03-25 22:55:41,730 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2016-03-25 22:55:41,735 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,740 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2016-03-25 22:55:41,745 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2016-03-25 22:55:41,750 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,755 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2016-03-25 22:55:41,761 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2016-03-25 22:55:41,766 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,771 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2016-03-25 22:55:41,776 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2016-03-25 22:55:41,781 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,786 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2016-03-25 22:55:41,791 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2016-03-25 22:55:41,796 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,802 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2016-03-25 22:55:41,807 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2016-03-25 22:55:41,812 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,817 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2016-03-25 22:55:41,822 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2016-03-25 22:55:41,827 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,833 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2016-03-25 22:55:41,838 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2016-03-25 22:55:41,843 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,848 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2016-03-25 22:55:41,854 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2016-03-25 22:55:41,858 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,864 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2016-03-25 22:55:41,871 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2016-03-25 22:55:41,875 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2016-03-25 22:55:41,881 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2016-03-25 22:55:41,886 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2016-03-25 22:55:41,890 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2016-03-25 22:55:41,897 [TRACE] [doorpi.action.handler] [GZ5OSR] try to fire action WebServerShutdownAction with args () and kwargs {} 2016-03-25 22:55:41,902 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2016-03-25 22:55:41,907 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2016-03-25 22:55:41,915 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2016-03-25 22:55:41,919 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:41,927 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2016-03-25 22:55:41,924 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2016-03-25 22:55:41,933 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2016-03-25 22:55:41,943 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:41,948 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2016-03-25 22:55:41,954 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2016-03-25 22:55:41,959 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:41,964 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2016-03-25 22:55:41,969 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2016-03-25 22:55:41,974 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:41,979 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2016-03-25 22:55:41,985 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2016-03-25 22:55:41,989 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:41,994 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2016-03-25 22:55:41,999 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2016-03-25 22:55:42,005 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:42,010 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2016-03-25 22:55:42,015 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2016-03-25 22:55:42,020 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:42,025 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2016-03-25 22:55:42,031 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2016-03-25 22:55:42,036 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-03-25 22:55:42,041 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2016-03-25 22:55:42,047 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2016-03-25 22:55:42,052 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2016-03-25 22:55:42,085 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2016-03-25 22:55:42,089 [TRACE] [doorpi.action.handler] [626ZCP] finished fire_event for event_name OnWebServerStart 2016-03-25 22:55:42,101 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2016-03-25 22:55:42,106 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2016-03-25 22:55:42,111 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2016-03-25 22:55:42,117 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2016-03-25 22:55:42,122 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2016-03-25 22:55:42,127 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2016-03-25 22:55:42,133 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2016-03-25 22:55:42,139 [TRACE] [doorpi.action.handler] [GZ5OSR] try to fire action KeyboardDestroyAction with args () and kwargs {} 2016-03-25 22:55:42,144 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2016-03-25 22:55:42,150 [DEBUG] [doorpi.keyboard.from_gpio] destroy 2016-03-25 22:55:42,162 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_gpio and force_unregister is True 2016-03-25 22:55:42,167 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_11 from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,183 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_11 - remove event too 2016-03-25 22:55:42,189 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_11 2016-03-25 22:55:42,193 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,199 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.11 - remove event too 2016-03-25 22:55:42,204 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.11 2016-03-25 22:55:42,215 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,220 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2016-03-25 22:55:42,233 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed 2016-03-25 22:55:42,238 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,243 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2016-03-25 22:55:42,255 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp 2016-03-25 22:55:42,259 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,264 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2016-03-25 22:55:42,274 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown 2016-03-25 22:55:42,285 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,288 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.11 - remove event too 2016-03-25 22:55:42,294 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.11 2016-03-25 22:55:42,304 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,309 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.11 - remove event too 2016-03-25 22:55:42,317 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.11 2016-03-25 22:55:42,324 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_11 from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,335 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_11 - remove event too 2016-03-25 22:55:42,340 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_11 2016-03-25 22:55:42,355 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2016-03-25 22:55:42,359 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_11 - remove event too 2016-03-25 22:55:42,365 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_11 2016-03-25 22:55:42,369 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed 2016-03-25 22:55:42,376 [TRACE] [doorpi.action.handler] [GZ5OSR] try to fire action > with args () and kwargs {} 2016-03-25 22:55:42,391 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-03-25 22:55:42,396 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2016-03-25 22:55:42,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0xb4779890 [0x16b77a0]) 2016-03-25 22:55:42,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x151f8bc 2016-03-25 22:55:42,419 [DEBUG] [doorpi.action.handler] [4S2304] fire for event OnSipPhoneDestroy this actions [, , ] 2016-03-25 22:55:42,424 [TRACE] [doorpi.action.handler] [4S2304] try to fire action > with args () and kwargs {} 2016-03-25 22:55:42,430 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-03-25 22:55:42,435 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2016-03-25 22:55:42,441 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2016-03-25 22:55:42,446 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,451 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2016-03-25 22:55:42,456 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2016-03-25 22:55:42,461 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,466 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2016-03-25 22:55:42,471 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2016-03-25 22:55:42,476 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,481 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2016-03-25 22:55:42,487 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2016-03-25 22:55:42,492 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,497 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2016-03-25 22:55:42,503 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2016-03-25 22:55:42,507 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,512 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2016-03-25 22:55:42,518 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2016-03-25 22:55:42,523 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,528 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2016-03-25 22:55:42,533 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2016-03-25 22:55:42,538 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,543 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2016-03-25 22:55:42,549 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2016-03-25 22:55:42,554 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,559 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2016-03-25 22:55:42,565 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2016-03-25 22:55:42,569 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,574 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2016-03-25 22:55:42,579 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2016-03-25 22:55:42,584 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,588 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2016-03-25 22:55:42,593 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2016-03-25 22:55:42,599 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,604 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2016-03-25 22:55:42,609 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2016-03-25 22:55:42,614 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,619 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2016-03-25 22:55:42,624 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2016-03-25 22:55:42,630 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,635 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2016-03-25 22:55:42,640 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2016-03-25 22:55:42,645 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,650 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2016-03-25 22:55:42,656 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2016-03-25 22:55:42,661 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,666 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2016-03-25 22:55:42,671 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2016-03-25 22:55:42,676 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,681 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2016-03-25 22:55:42,686 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2016-03-25 22:55:42,691 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,697 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2016-03-25 22:55:42,702 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2016-03-25 22:55:42,707 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,712 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2016-03-25 22:55:42,717 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2016-03-25 22:55:42,723 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,728 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2016-03-25 22:55:42,733 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2016-03-25 22:55:42,738 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,743 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2016-03-25 22:55:42,749 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2016-03-25 22:55:42,754 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 22:55:42,759 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2016-03-25 22:55:42,764 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2016-03-25 22:55:42,769 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2016-03-25 22:55:42,776 [TRACE] [doorpi.action.handler] [4S2304] try to fire action > with args () and kwargs {} 2016-03-25 22:55:42,781 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-03-25 22:55:42,787 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-25 22:55:42,794 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2016-03-25 22:55:42,799 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:55:42,807 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2016-03-25 22:55:42,803 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:55:42,814 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:55:42,823 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2016-03-25 22:55:42,828 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2016-03-25 22:55:42,833 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-25 22:55:42,838 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2016-03-25 22:55:42,843 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2016-03-25 22:55:42,849 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2016-03-25 22:55:42,855 [TRACE] [doorpi.action.handler] [4S2304] try to fire action > with args () and kwargs {} 2016-03-25 22:55:42,860 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-03-25 22:55:42,866 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2016-03-25 22:55:42,871 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:55:42,876 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2016-03-25 22:55:42,881 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2016-03-25 22:55:42,887 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:55:42,892 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2016-03-25 22:55:42,897 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2016-03-25 22:55:42,902 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 22:55:42,907 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2016-03-25 22:55:42,912 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2016-03-25 22:55:42,917 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2016-03-25 22:55:42,924 [TRACE] [doorpi.action.handler] [4S2304] finished fire_event for event_name OnSipPhoneDestroy 2016-03-25 22:55:42,929 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2016-03-25 22:55:42,934 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-03-25 22:55:42,940 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2016-03-25 22:55:42,945 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2016-03-25 22:55:42,951 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-25 22:55:42,956 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2016-03-25 22:55:42,961 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2016-03-25 22:55:42,966 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-03-25 22:55:42,971 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2016-03-25 22:55:42,977 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2016-03-25 22:55:42,982 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-25 22:55:42,987 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2016-03-25 22:55:42,992 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2016-03-25 22:55:42,997 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,002 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2016-03-25 22:55:43,007 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2016-03-25 22:55:43,013 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,017 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2016-03-25 22:55:43,023 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2016-03-25 22:55:43,027 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,033 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2016-03-25 22:55:43,038 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2016-03-25 22:55:43,044 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,049 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2016-03-25 22:55:43,054 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2016-03-25 22:55:43,059 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,064 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2016-03-25 22:55:43,069 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2016-03-25 22:55:43,074 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,079 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2016-03-25 22:55:43,085 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2016-03-25 22:55:43,090 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,095 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2016-03-25 22:55:43,100 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2016-03-25 22:55:43,105 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-25 22:55:43,110 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2016-03-25 22:55:43,115 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2016-03-25 22:55:43,121 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2016-03-25 22:55:43,127 [TRACE] [doorpi.action.handler] [GZ5OSR] finished fire_event for event_name OnShutdown 2016-03-25 22:55:43,133 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2016-03-25 22:55:43,142 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2016-03-25 22:55:43,641 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ========