2016-06-09 19:12:09,559 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.4 / /_// (_) | (_) | | / ___/| | 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-06-09 19:12:09,560 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-06-09 19:12:09,561 [DEBUG] [doorpi.doorpi] run 2016-06-09 19:12:09,562 [DEBUG] [doorpi.doorpi] prepare 2016-06-09 19:12:09,562 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-06-09 19:12:09,574 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-06-09 19:12:09,586 [DEBUG] [doorpi.conf.config_object] __init__ 2016-06-09 19:12:09,586 [TRACE] [doorpi.conf.config_object] get_from_config 2016-06-09 19:12:09,593 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-06-09 19:12:09,594 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-06-09 19:12:09,595 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-06-09 19:12:09,618 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-06-09 19:12:09,619 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-06-09 19:12:09,619 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-06-09 19:12:09,620 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-06-09 19:12:09,621 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-06-09 19:12:09,621 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-06-09 19:12:09,622 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-06-09 19:12:09,622 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-06-09 19:12:09,623 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-06-09 19:12:09,623 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-06-09 19:12:09,624 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-06-09 19:12:09,624 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-06-09 19:12:09,625 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-06-09 19:12:09,630 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-06-09 19:12:09,633 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-06-09 19:12:09,637 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-06-09 19:12:09,638 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-06-09 19:12:09,638 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-06-09 19:12:09,639 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-06-09 19:12:09,647 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-06-09 19:12:09,648 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-06-09 19:12:09,649 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-06-09 19:12:09,649 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-06-09 19:12:09,650 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-06-09 19:12:09,650 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-06-09 19:12:09,651 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-06-09 19:12:09,653 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-06-09 19:12:09,653 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-06-09 19:12:09,655 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-06-09 19:12:09,656 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-06-09 19:12:09,657 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-06-09 19:12:09,658 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-06-09 19:12:09,659 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-06-09 19:12:09,660 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-06-09 19:12:09,661 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-06-09 19:12:09,662 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-06-09 19:12:09,663 [WARNING] [root] section ReadPermission not found in configfile 2016-06-09 19:12:09,663 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-06-09 19:12:09,664 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-06-09 19:12:09,664 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-06-09 19:12:09,665 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-06-09 19:12:09,666 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'Group', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-06-09 19:12:09,667 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-06-09 19:12:09,667 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-06-09 19:12:09,668 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-06-09 19:12:09,668 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-06-09 19:12:09,669 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-06-09 19:12:09,671 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,672 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-06-09 19:12:09,673 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,674 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,675 [DEBUG] [doorpi.action.handler] [J1ZHYN] fire for event OnWebServerStart this actions [] 2016-06-09 19:12:09,675 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,676 [TRACE] [doorpi.action.handler] [J1ZHYN] try to fire action WebServerStartupAction with args () and kwargs {} 2016-06-09 19:12:09,676 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,677 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-06-09 19:12:09,678 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,679 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,680 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,680 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,681 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,681 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,682 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,682 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,683 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,683 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,684 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:09,684 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-06-09 19:12:09,685 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-06-09 19:12:09,686 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-06-09 19:12:09,686 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2016-06-09 19:12:09,687 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['7'] 2016-06-09 19:12:09,687 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11'] 2016-06-09 19:12:09,688 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2016-06-09 19:12:09,689 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-06-09 19:12:09,689 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-06-09 19:12:09,692 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-06-09 19:12:09,705 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['7'], output_pins = ['11'], bouncetime = 200.0, polarity = 0) 2016-06-09 19:12:09,707 [DEBUG] [doorpi.conf.config_object] create new key mode in section onboardpins_keyboard with value 'BOARD' 2016-06-09 19:12:09,707 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2016-06-09 19:12:09,708 [DEBUG] [doorpi.conf.config_object] create new key pull_up_down in section onboardpins_keyboard with value 'PUD_OFF' 2016-06-09 19:12:09,709 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-06-09 19:12:09,711 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,712 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-06-09 19:12:09,713 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,714 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,715 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,716 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,716 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,717 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,718 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,719 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,720 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,720 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,721 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,722 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,723 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,724 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,725 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,726 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:09,726 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.7 and registered source doorpi.keyboard.from_gpio 2016-06-09 19:12:09,729 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-06-09 19:12:09,730 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11'] 2016-06-09 19:12:09,731 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_OutputPins (default: ) returns Led 2016-06-09 19:12:09,737 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-06-09 19:12:09,743 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-06-09 19:12:09,748 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-06-09 19:12:09,752 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-06-09 19:12:09,983 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-06-09 19:12:09,988 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-06-09 19:12:09,994 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-06-09 19:12:10,137 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-06-09 19:12:10,150 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-06-09 19:12:10,152 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-06-09 19:12:10,155 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-06-09 19:12:10,162 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-06-09 19:12:10,166 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-06-09 19:12:10,172 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-06-09 19:12:10,175 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-06-09 19:12:10,176 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-06-09 19:12:10,176 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,177 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-06-09 19:12:10,178 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,178 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,179 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,179 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,180 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,180 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,181 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,181 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,182 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,183 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,183 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,184 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,184 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,185 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,185 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,186 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,186 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,187 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,187 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,188 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,188 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,189 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,190 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-06-09 19:12:10,190 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-06-09 19:12:10,191 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-06-09 19:12:10,191 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,192 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-06-09 19:12:10,192 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,193 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,194 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,194 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,195 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,195 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,196 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,196 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,197 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,197 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,198 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,198 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,199 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,199 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,200 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,200 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,201 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,201 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,202 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,203 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,203 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,204 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,204 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,205 [WARNING] [root] section DTMF not found in configfile 2016-06-09 19:12:10,206 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-06-09 19:12:10,206 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,207 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-06-09 19:12:10,207 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,208 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,208 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,209 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,209 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,210 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,210 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,211 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,211 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,212 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,212 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,213 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,213 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,214 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,214 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,215 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,216 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,216 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,217 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-06-09 19:12:10,219 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-06-09 19:12:10,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x7475c860 2016-06-09 19:12:10,221 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:10,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-06-09 19:12:10,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x7475c860 2016-06-09 19:12:10,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74779c00, "(null)", "(null)") 2016-06-09 19:12:10,231 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-06-09 19:12:10,232 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2016-06-09 19:12:10,232 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-06-09 19:12:10,238 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-06-09 19:12:10,292 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-06-09 19:12:10,461 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-06-09 19:12:10,507 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2016-06-09 19:12:10,527 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2016-06-09 19:12:10,528 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-06-09 19:12:10,529 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-06-09 19:12:10,530 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-06-09 19:12:10,530 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-06-09 19:12:10,531 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-06-09 19:12:10,532 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-06-09 19:12:10,535 [INFO] [doorpi.sipphone.from_linphone] Vtable [0xce3ce0] registered on core [0xce30b8] 2016-06-09 19:12:10,536 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xce3ce0, 1, "Starting up") 2016-06-09 19:12:10,536 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-06-09 19:12:10,537 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xce3ce0] notifying [global_state_changed] 2016-06-09 19:12:10,538 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-06-09 19:12:10,538 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-06-09 19:12:10,539 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-06-09 19:12:10,540 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-06-09 19:12:10,540 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2016-06-09 19:12:10,541 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-06-09 19:12:10,541 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-06-09 19:12:10,542 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-06-09 19:12:10,543 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-06-09 19:12:10,543 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-06-09 19:12:10,544 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-06-09 19:12:10,545 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-06-09 19:12:10,545 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-06-09 19:12:10,546 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-06-09 19:12:10,547 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-06-09 19:12:10,547 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-06-09 19:12:10,548 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-06-09 19:12:10,548 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-06-09 19:12:10,549 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-06-09 19:12:10,549 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-06-09 19:12:10,550 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-06-09 19:12:10,551 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-06-09 19:12:10,551 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-06-09 19:12:10,552 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-06-09 19:12:10,552 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-06-09 19:12:10,553 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-06-09 19:12:10,554 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-06-09 19:12:10,554 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-06-09 19:12:10,555 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-06-09 19:12:10,555 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-06-09 19:12:10,556 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-06-09 19:12:10,557 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-06-09 19:12:10,557 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-06-09 19:12:10,558 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-06-09 19:12:10,558 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-06-09 19:12:10,559 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-06-09 19:12:10,560 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-06-09 19:12:10,560 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-06-09 19:12:10,561 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-06-09 19:12:10,561 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-06-09 19:12:10,562 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-06-09 19:12:10,562 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-06-09 19:12:10,563 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-06-09 19:12:10,564 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-06-09 19:12:10,564 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-06-09 19:12:10,565 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-06-09 19:12:10,565 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-06-09 19:12:10,566 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-06-09 19:12:10,567 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-06-09 19:12:10,567 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-06-09 19:12:10,568 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-06-09 19:12:10,568 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-06-09 19:12:10,569 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-06-09 19:12:10,570 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-06-09 19:12:10,570 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-06-09 19:12:10,571 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-06-09 19:12:10,571 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-06-09 19:12:10,572 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-06-09 19:12:10,572 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-06-09 19:12:10,573 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-06-09 19:12:10,574 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-06-09 19:12:10,574 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-06-09 19:12:10,575 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-06-09 19:12:10,576 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-06-09 19:12:10,576 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-06-09 19:12:10,577 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-06-09 19:12:10,577 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-06-09 19:12:10,578 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-06-09 19:12:10,578 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-06-09 19:12:10,579 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-06-09 19:12:10,580 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-06-09 19:12:10,580 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-06-09 19:12:10,581 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-06-09 19:12:10,581 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-06-09 19:12:10,582 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-06-09 19:12:10,583 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-06-09 19:12:10,583 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-06-09 19:12:10,584 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-06-09 19:12:10,584 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-06-09 19:12:10,585 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-06-09 19:12:10,586 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-06-09 19:12:10,586 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-06-09 19:12:10,587 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-06-09 19:12:10,598 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-06-09 19:12:10,598 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,599 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,600 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,600 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0xce3ce0, 2, "(null)") 2016-06-09 19:12:10,601 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-06-09 19:12:10,602 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xce3ce0] notifying [configuring_status] 2016-06-09 19:12:10,602 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-06-09 19:12:10,603 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-06-09 19:12:10,604 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-06-09 19:12:10,604 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-06-09 19:12:10,605 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-06-09 19:12:10,606 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-06-09 19:12:10,606 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-06-09 19:12:10,607 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-06-09 19:12:10,607 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-06-09 19:12:10,608 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-06-09 19:12:10,609 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,609 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,610 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,611 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,611 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-06-09 19:12:10,612 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-06-09 19:12:10,612 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-06-09 19:12:10,613 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,614 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,614 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-06-09 19:12:10,615 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-06-09 19:12:10,616 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,616 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-06-09 19:12:10,617 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xce34e8] on [sip:0.0.0.0:5060;transport=UDP] 2016-06-09 19:12:10,618 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xce2d18] on [sip:0.0.0.0:5060;transport=TCP] 2016-06-09 19:12:10,624 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xce3b80] on [sip:0.0.0.0:-1;transport=TLS] 2016-06-09 19:12:10,625 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 41979 2016-06-09 19:12:10,626 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,626 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0xce3ce0, 2, "Ready") 2016-06-09 19:12:10,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-06-09 19:12:10,668 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xce3ce0] notifying [global_state_changed] 2016-06-09 19:12:10,669 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x7475c860 2016-06-09 19:12:10,669 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-06-09 19:12:10,670 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x7475c860 [0xce3ce0], "DoorPi ") 2016-06-09 19:12:10,672 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-06-09 19:12:10,673 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-06-09 19:12:10,675 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-06-09 19:12:10,676 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x7475c860 [0xce3ce0], 2) 2016-06-09 19:12:10,676 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-06-09 19:12:10,677 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-06-09 19:12:10,678 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-06-09 19:12:10,679 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x7475c860 [0xce3ce0], 0x2e0614 [0]) 2016-06-09 19:12:10,680 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-06-09 19:12:10,680 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-06-09 19:12:10,681 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-06-09 19:12:10,682 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-06-09 19:12:10,682 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x7475c878 2016-06-09 19:12:10,683 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x7475c860 [0xce3ce0], 0x7475c878 [0x7475c880]) 2016-06-09 19:12:10,684 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xce34e8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-06-09 19:12:10,685 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xce2d18] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-06-09 19:12:10,685 [INFO] [doorpi.sipphone.from_linphone] Listening point [0xce3b80] on [sip:0.0.0.0:41979;transport=TLS] destroyed 2016-06-09 19:12:10,686 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-06-09 19:12:10,687 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xd22bb0] on [sip:0.0.0.0:5060;transport=UDP] 2016-06-09 19:12:10,687 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xd22d30] on [sip:0.0.0.0:5060;transport=TCP] 2016-06-09 19:12:10,688 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0xd22a80] on [sip:0.0.0.0:-1;transport=TLS] 2016-06-09 19:12:10,689 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 39795 2016-06-09 19:12:10,690 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,690 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-06-09 19:12:10,691 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-06-09 19:12:10,691 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x7475c878) 2016-06-09 19:12:10,692 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-06-09 19:12:10,693 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-06-09 19:12:10,693 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x7475c860 [0xce3ce0], 0x2e0614 [0]) 2016-06-09 19:12:10,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-06-09 19:12:10,695 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-06-09 19:12:10,695 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x7475c860 [0xce3ce0], "") 2016-06-09 19:12:10,698 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-06-09 19:12:10,699 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-06-09 19:12:10,701 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-06-09 19:12:10,701 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-06-09 19:12:10,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-06-09 19:12:10,703 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-06-09 19:12:10,704 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-06-09 19:12:10,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x7475c860 [0xce3ce0], 0) 2016-06-09 19:12:10,705 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-06-09 19:12:10,706 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-06-09 19:12:10,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-06-09 19:12:10,708 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-06-09 19:12:10,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x7475c860 [0xce3ce0], 120) 2016-06-09 19:12:10,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-06-09 19:12:10,710 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-06-09 19:12:10,711 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x7475c860 [0xce3ce0], 15) 2016-06-09 19:12:10,712 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-06-09 19:12:10,712 [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-06-09 19:12:10,714 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-06-09 19:12:10,715 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-06-09 19:12:10,718 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-06-09 19:12:10,719 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:10,720 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-06-09 19:12:10,720 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:10,721 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:10,722 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:10,722 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:10,723 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-06-09 19:12:10,723 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-06-09 19:12:10,724 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-06-09 19:12:10,725 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-06-09 19:12:10,726 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-06-09 19:12:10,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x7475c860 [0xce3ce0], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-06-09 19:12:10,729 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-06-09 19:12:10,729 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:10,730 [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-06-09 19:12:10,731 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-06-09 19:12:10,732 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,732 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-06-09 19:12:10,733 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,734 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,734 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,735 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,735 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,736 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-06-09 19:12:10,737 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-06-09 19:12:10,737 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-06-09 19:12:10,738 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-06-09 19:12:10,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,741 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x746adad0 2016-06-09 19:12:10,741 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:10,742 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x746ac770 2016-06-09 19:12:10,744 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: C-Media USB Headphone Set 2016-06-09 19:12:10,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x7475c860 [0xce3ce0], "ALSA: C-Media USB Headphone Set") 2016-06-09 19:12:10,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-06-09 19:12:10,746 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,747 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x746ac740 2016-06-09 19:12:10,747 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: C-Media USB Headphone Set 2016-06-09 19:12:10,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x7475c860 [0xce3ce0], "ALSA: C-Media USB Headphone Set") 2016-06-09 19:12:10,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-06-09 19:12:10,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x746ada80 2016-06-09 19:12:10,750 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2016-06-09 19:12:10,751 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-06-09 19:12:10,752 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-06-09 19:12:10,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,753 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x746ada80 2016-06-09 19:12:10,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7475c860 [0xce3ce0], "ALSA: default device") 2016-06-09 19:12:10,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-06-09 19:12:10,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7475c860 [0xce3ce0], "ALSA: default device") 2016-06-09 19:12:10,756 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-06-09 19:12:10,756 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-06-09 19:12:10,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7475c860 [0xce3ce0], "ALSA: C-Media USB Headphone Set") 2016-06-09 19:12:10,757 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-06-09 19:12:10,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7475c860 [0xce3ce0], "ALSA: C-Media USB Headphone Set") 2016-06-09 19:12:10,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-06-09 19:12:10,759 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2016-06-09 19:12:10,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x7475c860 [0xce3ce0], "ALSA: C-Media USB Headphone Set") 2016-06-09 19:12:10,760 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-06-09 19:12:10,761 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x7475c860 [0xce3ce0], "ALSA: C-Media USB Headphone Set") 2016-06-09 19:12:10,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-06-09 19:12:10,762 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2016-06-09 19:12:10,763 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-06-09 19:12:10,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7476fe58 2016-06-09 19:12:10,764 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: C-Media USB Headphone Set 2016-06-09 19:12:10,765 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7476fe58 2016-06-09 19:12:10,766 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: C-Media USB Headphone Set 2016-06-09 19:12:10,767 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-06-09 19:12:10,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1fd0) 2016-06-09 19:12:10,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,769 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95410 2016-06-09 19:12:10,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95410 2016-06-09 19:12:10,771 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95410 2016-06-09 19:12:10,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf2040) 2016-06-09 19:12:10,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95420 2016-06-09 19:12:10,773 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,774 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95420 2016-06-09 19:12:10,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95420 2016-06-09 19:12:10,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf20a8) 2016-06-09 19:12:10,776 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,776 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95430 2016-06-09 19:12:10,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,777 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95430 2016-06-09 19:12:10,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95430 2016-06-09 19:12:10,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1bd0) 2016-06-09 19:12:10,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95440 2016-06-09 19:12:10,780 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,781 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95440 2016-06-09 19:12:10,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95440 2016-06-09 19:12:10,782 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1c18) 2016-06-09 19:12:10,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,783 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95450 2016-06-09 19:12:10,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95450 2016-06-09 19:12:10,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95450 2016-06-09 19:12:10,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1c70) 2016-06-09 19:12:10,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95460 2016-06-09 19:12:10,787 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,788 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95460 2016-06-09 19:12:10,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95460 2016-06-09 19:12:10,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1cc8) 2016-06-09 19:12:10,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95470 2016-06-09 19:12:10,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95470 2016-06-09 19:12:10,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95470 2016-06-09 19:12:10,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1d20) 2016-06-09 19:12:10,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95480 2016-06-09 19:12:10,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95480 2016-06-09 19:12:10,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95480 2016-06-09 19:12:10,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1d88) 2016-06-09 19:12:10,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c95490 2016-06-09 19:12:10,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c95490 2016-06-09 19:12:10,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c95490 2016-06-09 19:12:10,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xce32f8) 2016-06-09 19:12:10,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c954a0 2016-06-09 19:12:10,801 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c954a0 2016-06-09 19:12:10,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c954a0 2016-06-09 19:12:10,803 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x746adb70 2016-06-09 19:12:10,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95410 [0xcf1fd0]) 2016-06-09 19:12:10,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95410 [0xcf1fd0]) 2016-06-09 19:12:10,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,806 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-06-09 19:12:10,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95410 [0xcf1fd0], 0x2e0614 [0]) 2016-06-09 19:12:10,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,808 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95420 [0xcf2040]) 2016-06-09 19:12:10,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab7a0 2016-06-09 19:12:10,809 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95420 [0xcf2040]) 2016-06-09 19:12:10,810 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab7a0 2016-06-09 19:12:10,810 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-06-09 19:12:10,811 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95420 [0xcf2040], 0x2e0614 [0]) 2016-06-09 19:12:10,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95430 [0xcf20a8]) 2016-06-09 19:12:10,813 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95430 [0xcf20a8]) 2016-06-09 19:12:10,814 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,815 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-06-09 19:12:10,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95430 [0xcf20a8], 0x2e0614 [0]) 2016-06-09 19:12:10,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95440 [0xcf1bd0]) 2016-06-09 19:12:10,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab7a0 2016-06-09 19:12:10,818 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95440 [0xcf1bd0]) 2016-06-09 19:12:10,819 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab7a0 2016-06-09 19:12:10,819 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-06-09 19:12:10,820 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95440 [0xcf1bd0], 0x2e0620 [1]) 2016-06-09 19:12:10,821 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,821 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95450 [0xcf1c18]) 2016-06-09 19:12:10,822 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,822 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95450 [0xcf1c18]) 2016-06-09 19:12:10,823 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,823 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-06-09 19:12:10,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95450 [0xcf1c18], 0x2e0620 [1]) 2016-06-09 19:12:10,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95460 [0xcf1c70]) 2016-06-09 19:12:10,826 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,827 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95460 [0xcf1c70]) 2016-06-09 19:12:10,827 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,828 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-06-09 19:12:10,828 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95460 [0xcf1c70], 0x2e0614 [0]) 2016-06-09 19:12:10,829 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,830 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95470 [0xcf1cc8]) 2016-06-09 19:12:10,830 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,831 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95470 [0xcf1cc8]) 2016-06-09 19:12:10,832 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab720 2016-06-09 19:12:10,832 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-06-09 19:12:10,833 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95470 [0xcf1cc8], 0x2e0614 [0]) 2016-06-09 19:12:10,834 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,834 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95480 [0xcf1d20]) 2016-06-09 19:12:10,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab7a0 2016-06-09 19:12:10,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95480 [0xcf1d20]) 2016-06-09 19:12:10,836 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x746ab7a0 2016-06-09 19:12:10,836 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-06-09 19:12:10,837 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95480 [0xcf1d20], 0x2e0614 [0]) 2016-06-09 19:12:10,838 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,838 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95490 [0xcf1d88]) 2016-06-09 19:12:10,839 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,840 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c95490 [0xcf1d88]) 2016-06-09 19:12:10,840 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,841 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-06-09 19:12:10,841 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c95490 [0xcf1d88], 0x2e0614 [0]) 2016-06-09 19:12:10,842 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,843 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c954a0 [0xce32f8]) 2016-06-09 19:12:10,843 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c954a0 [0xce32f8]) 2016-06-09 19:12:10,845 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,845 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-06-09 19:12:10,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c954a0 [0xce32f8], 0x2e0614 [0]) 2016-06-09 19:12:10,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746adad0 2016-06-09 19:12:10,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,849 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746adad0 2016-06-09 19:12:10,849 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-06-09 19:12:10,850 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-06-09 19:12:10,850 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-06-09 19:12:10,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746adad0 2016-06-09 19:12:10,852 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-06-09 19:12:10,853 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-06-09 19:12:10,853 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,854 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746adad0 2016-06-09 19:12:10,855 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-06-09 19:12:10,855 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746adad0 2016-06-09 19:12:10,856 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x7475c860 [0xce3ce0], 0x2e0620 [1]) 2016-06-09 19:12:10,857 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-06-09 19:12:10,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x7475c860 [0xce3ce0], "StaticImage: Static picture") 2016-06-09 19:12:10,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-06-09 19:12:10,859 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-06-09 19:12:10,859 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x7475c860 [0xce3ce0], "vga") 2016-06-09 19:12:10,860 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-06-09 19:12:10,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,861 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x746ac920 2016-06-09 19:12:10,862 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-06-09 19:12:10,862 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-06-09 19:12:10,863 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0xcf1f80) 2016-06-09 19:12:10,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-06-09 19:12:10,865 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c954b0 2016-06-09 19:12:10,865 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-06-09 19:12:10,866 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c954b0 2016-06-09 19:12:10,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c954b0 2016-06-09 19:12:10,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x746adb20 2016-06-09 19:12:10,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c954b0 [0xcf1f80]) 2016-06-09 19:12:10,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-06-09 19:12:10,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c954b0 [0xcf1f80]) 2016-06-09 19:12:10,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7475c878 2016-06-09 19:12:10,871 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-06-09 19:12:10,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x7475c860 [0xce3ce0], 0x76c954b0 [0xcf1f80], 0x2e0620 [1]) 2016-06-09 19:12:10,873 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0xb528bc 2016-06-09 19:12:10,873 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2016-06-09 19:12:10,874 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 623 2016-06-09 19:12:10,875 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 623) returns ******* 2016-06-09 19:12:10,875 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2016-06-09 19:12:10,876 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-06-09 19:12:10,876 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:10,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0xd27ca0) 2016-06-09 19:12:10,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-06-09 19:12:10,878 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76c954c0 2016-06-09 19:12:10,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-06-09 19:12:10,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76c954c0 2016-06-09 19:12:10,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76c954c0 2016-06-09 19:12:10,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76c954c0 2016-06-09 19:12:10,881 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-06-09 19:12:10,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-06-09 19:12:10,883 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0xd21b60) 2016-06-09 19:12:10,884 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-06-09 19:12:10,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76c954d0 2016-06-09 19:12:10,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-06-09 19:12:10,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76c954d0 2016-06-09 19:12:10,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76c954d0 2016-06-09 19:12:10,887 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76c954d0 2016-06-09 19:12:10,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76c954c0 [0xd27ca0], 0x76c954d0 [0xd21b60]) 2016-06-09 19:12:10,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-06-09 19:12:10,889 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76c954c0 [0xd27ca0], "sip:192.168.178.1") 2016-06-09 19:12:10,891 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-06-09 19:12:10,891 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76c954c0 [0xd27ca0], 0x2e0620 [1]) 2016-06-09 19:12:10,892 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-06-09 19:12:10,892 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x7475c860 [0xce3ce0], 0x76c954c0 [0xd27ca0]) 2016-06-09 19:12:10,894 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0xb528bc 2016-06-09 19:12:10,895 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x7475c860 [0xce3ce0], 0x76c954c0 [0xd27ca0]) 2016-06-09 19:12:10,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-06-09 19:12:10,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x7475c860 [0xce3ce0], "623", "(null)", "raspberry", "(null)", "(null)", "fritz.box") 2016-06-09 19:12:10,897 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0xce4e10) 2016-06-09 19:12:10,897 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-06-09 19:12:10,898 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76c954e0 2016-06-09 19:12:10,898 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-06-09 19:12:10,899 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76c954e0 2016-06-09 19:12:10,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76c954e0 2016-06-09 19:12:10,900 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76c954e0 2016-06-09 19:12:10,901 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x7475c860 [0xce3ce0], 0x76c954e0 [0xce4e10]) 2016-06-09 19:12:10,901 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-06-09 19:12:10,902 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-06-09 19:12:10,902 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-06-09 19:12:10,903 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-06-09 19:12:10,904 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-06-09 19:12:10,904 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-06-09 19:12:10,905 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-06-09 19:12:10,908 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-06-09 19:12:10,909 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-06-09 19:12:10,910 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_InputPins (default: ) returns call:11 2016-06-09 19:12:10,912 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-06-09 19:12:10,914 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '11'} was added to new evententry OnKeyPressed_onboardpins.7 2016-06-09 19:12:10,915 [WARNING] [root] section DTMF not found in configfile 2016-06-09 19:12:10,916 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-06-09 19:12:10,917 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-06-09 19:12:10,917 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-06-09 19:12:10,920 [DEBUG] [doorpi.action.handler] [W13T77] fire for event OnStartup this actions [] 2016-06-09 19:12:10,920 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-06-09 19:12:10,921 [TRACE] [doorpi.action.handler] [W13T77] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-06-09 19:12:10,922 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-06-09 19:12:11,925 [TRACE] [doorpi.action.handler] [W13T77] finished fire_event for event_name OnStartup 2016-06-09 19:12:11,926 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-06-09 19:12:11,928 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-06-09 19:12:11,929 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-06-09 19:12:11,929 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-06-09 19:12:11,930 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-06-09 19:12:11,932 [ERROR] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() error: Unknown error 1993387544 (1993387544) 2016-06-09 19:12:11,932 [WARNING] [doorpi.sipphone.from_linphone] Stun server resolution failed. 2016-06-09 19:12:11,933 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.1.121 2016-06-09 19:12:11,934 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-06-09 19:12:11,936 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-06-09 19:12:11,936 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-06-09 19:12:11,938 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-06-09 19:12:11,938 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-06-09 19:12:11,940 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0xd27ca0] about to register (LinphoneCore version: 3.9.0) 2016-06-09 19:12:11,950 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-06-09 19:12:11,950 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0]: starting resolution of 192.168.178.1 2016-06-09 19:12:11,951 [INFO] [doorpi.sipphone.from_linphone] channel 0xcfd1e0: state RES_IN_PROGRESS 2016-06-09 19:12:11,952 [INFO] [doorpi.sipphone.from_linphone] transaction [0xd2beb8] channel state changed to [RES_IN_PROGRESS] 2016-06-09 19:12:11,952 [INFO] [doorpi.sipphone.from_linphone] channel 0xcfd1e0: state RES_DONE 2016-06-09 19:12:11,953 [INFO] [doorpi.sipphone.from_linphone] transaction [0xd2beb8] channel state changed to [RES_DONE] 2016-06-09 19:12:11,954 [INFO] [doorpi.sipphone.from_linphone] channel 0xcfd1e0: state CONNECTING 2016-06-09 19:12:11,954 [INFO] [doorpi.sipphone.from_linphone] transaction [0xd2beb8] channel state changed to [CONNECTING] 2016-06-09 19:12:11,955 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2016-06-09 19:12:11,956 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-06-09 19:12:11,956 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.1.121:5060 2016-06-09 19:12:11,957 [INFO] [doorpi.sipphone.from_linphone] channel 0xcfd1e0: state READY 2016-06-09 19:12:11,958 [INFO] [doorpi.sipphone.from_linphone] transaction [0xd2beb8] channel state changed to [READY] 2016-06-09 19:12:11,958 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xd2beb8], from state [INIT] to [TRYING] 2016-06-09 19:12:11,960 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0]: message sent to [UDP://192.168.178.1:5060], size: [512] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.121:5060;branch=z9hG4bK.yKzwKtDtD;rport From: "DoorPi" ;tag=0vz5Qfr2p To: "DoorPi" CSeq: 20 REGISTER Call-ID: rn66Vwz7gW 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-06-09 19:12:11,960 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-06-09 19:12:11,961 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xd2d838] takes ownership of transaction [0xd2beb8] 2016-06-09 19:12:11,962 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xd27ca0] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-06-09 19:12:11,962 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xce3ce0, 0xd27ca0, 1, "Registration in progress") 2016-06-09 19:12:11,963 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-06-09 19:12:11,964 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xce3ce0] notifying [registration_state_changed] 2016-06-09 19:12:12,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0xce3ce0, 0x1) 2016-06-09 19:12:12,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-06-09 19:12:12,016 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xce3ce0] notifying [network_reachable] 2016-06-09 19:12:12,017 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0]: received [389] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.121:5060;branch=z9hG4bK.yKzwKtDtD;rport=5060;received=192.168.178.35 From: "DoorPi" ;tag=0vz5Qfr2p To: "DoorPi" ;tag=F1EEEDCD4447AB5C Call-ID: rn66Vwz7gW CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="98DA431FDDA45399" User-Agent: FRITZ!OS Content-Length: 0 2016-06-09 19:12:12,045 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0] [389] bytes parsed 2016-06-09 19:12:12,046 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0]: discovered public ip and port are [192.168.178.35:5060] 2016-06-09 19:12:12,047 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-06-09 19:12:12,048 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xd2beb8], from state [TRYING] to [COMPLETED] 2016-06-09 19:12:12,049 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=623, realm= 2016-06-09 19:12:12,049 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [623] realm [fritz.box] 2016-06-09 19:12:12,050 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xd16958], from state [INIT] to [TRYING] 2016-06-09 19:12:12,052 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0]: message sent to [UDP://192.168.178.1:5060], size: [668] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.121:5060;branch=z9hG4bK.vhQcXTP5P;rport From: "DoorPi" ;tag=0vz5Qfr2p To: "DoorPi" CSeq: 21 REGISTER Call-ID: rn66Vwz7gW 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="98DA431FDDA45399", username="623", uri="sip:192.168.178.1", response="ee27579a9f4121f718de39bd455e2244" 2016-06-09 19:12:12,053 [ERROR] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() error: Unknown error 1993387544 (1993387544) 2016-06-09 19:12:12,054 [WARNING] [doorpi.sipphone.from_linphone] Stun server resolution failed. 2016-06-09 19:12:12,106 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0]: received [693] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.121:5060;branch=z9hG4bK.vhQcXTP5P;rport=5060;received=192.168.178.35 From: "DoorPi" ;tag=0vz5Qfr2p To: "DoorPi" ;tag=750AF44A9842A19C Call-ID: rn66Vwz7gW 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-06-09 19:12:12,122 [INFO] [doorpi.sipphone.from_linphone] channel [0xcfd1e0] [693] bytes parsed 2016-06-09 19:12:12,123 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-06-09 19:12:12,124 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xd16958], from state [TRYING] to [COMPLETED] 2016-06-09 19:12:12,125 [INFO] [doorpi.sipphone.from_linphone] Refresher [0xd2d838]: has no contact for request [0xd2c630]. 2016-06-09 19:12:12,125 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2016-06-09 19:12:12,126 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.178.1] 2016-06-09 19:12:12,127 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0xd27ca0] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2016-06-09 19:12:12,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0xce3ce0, 0xd27ca0, 2, "Registration successful") 2016-06-09 19:12:12,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-06-09 19:12:12,129 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0xce3ce0] notifying [registration_state_changed] 2016-06-09 19:12:17,093 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xd2beb8], from state [COMPLETED] to [TERMINATED] 2016-06-09 19:12:17,094 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xd2beb8] terminated 2016-06-09 19:12:17,145 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0xd16958], from state [COMPLETED] to [TERMINATED] 2016-06-09 19:12:17,146 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0xd16958] terminated 2016-06-09 19:12:28,481 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2016-06-09 19:12:28,482 [DEBUG] [doorpi.doorpi] destroy doorpi 2016-06-09 19:12:28,483 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1995542528)>, ] 2016-06-09 19:12:28,484 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2016-06-09 19:12:28,487 [DEBUG] [doorpi.action.handler] [FP2FK3] fire for event OnShutdown this actions [, , , ] 2016-06-09 19:12:28,487 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2016-06-09 19:12:28,488 [TRACE] [doorpi.action.handler] [FP2FK3] try to fire action TimeTickDestroyAction with args () and kwargs {} 2016-06-09 19:12:28,489 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2016-06-09 19:12:28,490 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2016-06-09 19:12:28,491 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,492 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2016-06-09 19:12:28,492 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2016-06-09 19:12:28,493 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,493 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2016-06-09 19:12:28,494 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2016-06-09 19:12:28,495 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,495 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2016-06-09 19:12:28,496 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2016-06-09 19:12:28,496 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,497 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2016-06-09 19:12:28,497 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2016-06-09 19:12:28,498 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,499 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2016-06-09 19:12:28,499 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2016-06-09 19:12:28,500 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,500 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2016-06-09 19:12:28,501 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2016-06-09 19:12:28,501 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,502 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2016-06-09 19:12:28,502 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2016-06-09 19:12:28,503 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,503 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2016-06-09 19:12:28,504 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2016-06-09 19:12:28,505 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,505 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2016-06-09 19:12:28,506 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2016-06-09 19:12:28,506 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,507 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2016-06-09 19:12:28,507 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2016-06-09 19:12:28,508 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,509 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2016-06-09 19:12:28,509 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2016-06-09 19:12:28,510 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,510 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2016-06-09 19:12:28,511 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2016-06-09 19:12:28,511 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,512 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2016-06-09 19:12:28,512 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2016-06-09 19:12:28,513 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,513 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2016-06-09 19:12:28,514 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2016-06-09 19:12:28,515 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,515 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2016-06-09 19:12:28,516 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2016-06-09 19:12:28,516 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,517 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2016-06-09 19:12:28,517 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2016-06-09 19:12:28,518 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,518 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2016-06-09 19:12:28,519 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2016-06-09 19:12:28,519 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,520 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2016-06-09 19:12:28,521 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2016-06-09 19:12:28,521 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,522 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2016-06-09 19:12:28,522 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2016-06-09 19:12:28,523 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,523 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2016-06-09 19:12:28,524 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2016-06-09 19:12:28,525 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,525 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2016-06-09 19:12:28,526 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2016-06-09 19:12:28,526 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,527 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2016-06-09 19:12:28,527 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2016-06-09 19:12:28,528 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,528 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2016-06-09 19:12:28,529 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2016-06-09 19:12:28,529 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,530 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2016-06-09 19:12:28,531 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2016-06-09 19:12:28,531 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,532 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2016-06-09 19:12:28,532 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2016-06-09 19:12:28,533 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,533 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2016-06-09 19:12:28,534 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2016-06-09 19:12:28,535 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,535 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2016-06-09 19:12:28,536 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2016-06-09 19:12:28,536 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,537 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2016-06-09 19:12:28,537 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2016-06-09 19:12:28,538 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,538 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2016-06-09 19:12:28,538 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2016-06-09 19:12:28,539 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,539 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2016-06-09 19:12:28,539 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2016-06-09 19:12:28,540 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,540 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2016-06-09 19:12:28,541 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2016-06-09 19:12:28,541 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,541 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2016-06-09 19:12:28,542 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2016-06-09 19:12:28,542 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,542 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2016-06-09 19:12:28,543 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2016-06-09 19:12:28,543 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,543 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2016-06-09 19:12:28,544 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2016-06-09 19:12:28,544 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,545 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2016-06-09 19:12:28,545 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2016-06-09 19:12:28,545 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,546 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2016-06-09 19:12:28,546 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2016-06-09 19:12:28,547 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,547 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2016-06-09 19:12:28,547 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2016-06-09 19:12:28,548 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,548 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2016-06-09 19:12:28,548 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2016-06-09 19:12:28,549 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,549 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2016-06-09 19:12:28,549 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2016-06-09 19:12:28,550 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,552 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2016-06-09 19:12:28,553 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2016-06-09 19:12:28,554 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,554 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2016-06-09 19:12:28,555 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2016-06-09 19:12:28,556 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,557 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2016-06-09 19:12:28,557 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2016-06-09 19:12:28,558 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,558 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2016-06-09 19:12:28,559 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2016-06-09 19:12:28,559 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,559 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2016-06-09 19:12:28,560 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2016-06-09 19:12:28,560 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,561 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2016-06-09 19:12:28,561 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2016-06-09 19:12:28,562 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,562 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2016-06-09 19:12:28,562 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2016-06-09 19:12:28,563 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,564 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2016-06-09 19:12:28,565 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2016-06-09 19:12:28,566 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,567 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2016-06-09 19:12:28,568 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2016-06-09 19:12:28,569 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,570 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2016-06-09 19:12:28,571 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2016-06-09 19:12:28,572 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,573 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2016-06-09 19:12:28,574 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2016-06-09 19:12:28,574 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,575 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2016-06-09 19:12:28,576 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2016-06-09 19:12:28,576 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,577 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2016-06-09 19:12:28,578 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2016-06-09 19:12:28,578 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,579 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2016-06-09 19:12:28,579 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2016-06-09 19:12:28,580 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,581 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2016-06-09 19:12:28,582 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2016-06-09 19:12:28,583 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,584 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2016-06-09 19:12:28,585 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2016-06-09 19:12:28,585 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,586 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2016-06-09 19:12:28,587 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2016-06-09 19:12:28,588 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,589 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2016-06-09 19:12:28,590 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2016-06-09 19:12:28,591 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,591 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2016-06-09 19:12:28,592 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2016-06-09 19:12:28,593 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,594 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2016-06-09 19:12:28,595 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2016-06-09 19:12:28,596 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,597 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2016-06-09 19:12:28,598 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2016-06-09 19:12:28,598 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,599 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2016-06-09 19:12:28,600 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2016-06-09 19:12:28,601 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,602 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2016-06-09 19:12:28,602 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2016-06-09 19:12:28,603 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,604 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2016-06-09 19:12:28,605 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2016-06-09 19:12:28,606 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,607 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2016-06-09 19:12:28,608 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2016-06-09 19:12:28,609 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,610 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2016-06-09 19:12:28,612 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2016-06-09 19:12:28,613 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,614 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2016-06-09 19:12:28,615 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2016-06-09 19:12:28,616 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,618 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2016-06-09 19:12:28,619 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2016-06-09 19:12:28,619 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,620 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2016-06-09 19:12:28,621 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2016-06-09 19:12:28,621 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,622 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2016-06-09 19:12:28,623 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2016-06-09 19:12:28,624 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,625 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2016-06-09 19:12:28,625 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2016-06-09 19:12:28,626 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,627 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2016-06-09 19:12:28,627 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2016-06-09 19:12:28,628 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,629 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2016-06-09 19:12:28,630 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2016-06-09 19:12:28,631 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,632 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2016-06-09 19:12:28,633 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2016-06-09 19:12:28,634 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,635 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2016-06-09 19:12:28,636 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2016-06-09 19:12:28,637 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,638 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2016-06-09 19:12:28,639 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2016-06-09 19:12:28,640 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,641 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2016-06-09 19:12:28,642 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2016-06-09 19:12:28,643 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,644 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2016-06-09 19:12:28,645 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2016-06-09 19:12:28,646 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,647 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2016-06-09 19:12:28,647 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2016-06-09 19:12:28,648 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,649 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2016-06-09 19:12:28,650 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2016-06-09 19:12:28,651 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,651 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2016-06-09 19:12:28,652 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2016-06-09 19:12:28,653 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,654 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2016-06-09 19:12:28,655 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2016-06-09 19:12:28,656 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,656 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2016-06-09 19:12:28,657 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2016-06-09 19:12:28,658 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,658 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2016-06-09 19:12:28,659 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2016-06-09 19:12:28,660 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,660 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2016-06-09 19:12:28,661 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2016-06-09 19:12:28,662 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,662 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2016-06-09 19:12:28,663 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2016-06-09 19:12:28,663 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,664 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2016-06-09 19:12:28,665 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2016-06-09 19:12:28,665 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,666 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2016-06-09 19:12:28,667 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2016-06-09 19:12:28,667 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,668 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2016-06-09 19:12:28,669 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2016-06-09 19:12:28,669 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,670 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2016-06-09 19:12:28,671 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2016-06-09 19:12:28,671 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,672 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2016-06-09 19:12:28,673 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2016-06-09 19:12:28,673 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,674 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2016-06-09 19:12:28,675 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2016-06-09 19:12:28,675 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,676 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2016-06-09 19:12:28,677 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2016-06-09 19:12:28,677 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,678 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2016-06-09 19:12:28,679 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2016-06-09 19:12:28,679 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,680 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2016-06-09 19:12:28,681 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2016-06-09 19:12:28,681 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,682 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2016-06-09 19:12:28,682 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2016-06-09 19:12:28,683 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,684 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2016-06-09 19:12:28,685 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2016-06-09 19:12:28,685 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,686 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2016-06-09 19:12:28,686 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2016-06-09 19:12:28,687 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,688 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2016-06-09 19:12:28,688 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2016-06-09 19:12:28,689 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,690 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2016-06-09 19:12:28,690 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2016-06-09 19:12:28,691 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,691 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2016-06-09 19:12:28,692 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2016-06-09 19:12:28,693 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,693 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2016-06-09 19:12:28,694 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2016-06-09 19:12:28,695 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,695 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2016-06-09 19:12:28,696 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2016-06-09 19:12:28,697 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,697 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2016-06-09 19:12:28,698 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2016-06-09 19:12:28,699 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,699 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2016-06-09 19:12:28,700 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2016-06-09 19:12:28,701 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2016-06-09 19:12:28,701 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2016-06-09 19:12:28,702 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2016-06-09 19:12:28,703 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2016-06-09 19:12:28,704 [TRACE] [doorpi.action.handler] [FP2FK3] try to fire action WebServerShutdownAction with args () and kwargs {} 2016-06-09 19:12:28,705 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2016-06-09 19:12:28,706 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2016-06-09 19:12:28,708 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2016-06-09 19:12:28,709 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,709 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2016-06-09 19:12:28,710 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2016-06-09 19:12:28,712 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2016-06-09 19:12:28,713 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,715 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2016-06-09 19:12:28,716 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2016-06-09 19:12:28,718 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,719 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2016-06-09 19:12:28,720 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2016-06-09 19:12:28,721 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,722 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2016-06-09 19:12:28,724 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2016-06-09 19:12:28,725 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,726 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2016-06-09 19:12:28,732 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2016-06-09 19:12:28,734 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,735 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2016-06-09 19:12:28,736 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2016-06-09 19:12:28,737 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,738 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2016-06-09 19:12:28,739 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2016-06-09 19:12:28,740 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-06-09 19:12:28,740 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2016-06-09 19:12:28,741 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2016-06-09 19:12:28,742 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2016-06-09 19:12:28,754 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2016-06-09 19:12:28,756 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2016-06-09 19:12:28,757 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2016-06-09 19:12:28,759 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2016-06-09 19:12:28,760 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2016-06-09 19:12:28,761 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2016-06-09 19:12:28,762 [TRACE] [doorpi.action.handler] [J1ZHYN] finished fire_event for event_name OnWebServerStart 2016-06-09 19:12:28,762 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2016-06-09 19:12:28,764 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2016-06-09 19:12:28,765 [TRACE] [doorpi.action.handler] [FP2FK3] try to fire action KeyboardDestroyAction with args () and kwargs {} 2016-06-09 19:12:28,766 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2016-06-09 19:12:28,767 [DEBUG] [doorpi.keyboard.from_gpio] destroy 2016-06-09 19:12:28,770 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_gpio and force_unregister is True 2016-06-09 19:12:28,771 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,772 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2016-06-09 19:12:28,773 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed 2016-06-09 19:12:28,774 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,775 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2016-06-09 19:12:28,776 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp 2016-06-09 19:12:28,777 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,778 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.7 - remove event too 2016-06-09 19:12:28,778 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.7 2016-06-09 19:12:28,779 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,780 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_7 - remove event too 2016-06-09 19:12:28,781 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_7 2016-06-09 19:12:28,782 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,783 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.7 - remove event too 2016-06-09 19:12:28,784 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.7 2016-06-09 19:12:28,785 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,785 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2016-06-09 19:12:28,786 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown 2016-06-09 19:12:28,787 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,788 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.7 - remove event too 2016-06-09 19:12:28,789 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.7 2016-06-09 19:12:28,789 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,790 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_7 - remove event too 2016-06-09 19:12:28,791 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_7 2016-06-09 19:12:28,792 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_7 from doorpi.keyboard.from_gpio 2016-06-09 19:12:28,793 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_7 - remove event too 2016-06-09 19:12:28,794 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_7 2016-06-09 19:12:28,795 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed 2016-06-09 19:12:28,797 [TRACE] [doorpi.action.handler] [FP2FK3] try to fire action > with args () and kwargs {} 2016-06-09 19:12:28,798 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-06-09 19:12:28,798 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2016-06-09 19:12:28,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x7475c860 [0xce3ce0]) 2016-06-09 19:12:28,800 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0xb528bc 2016-06-09 19:12:28,802 [DEBUG] [doorpi.action.handler] [PQHF8H] fire for event OnSipPhoneDestroy this actions [, , ] 2016-06-09 19:12:28,804 [TRACE] [doorpi.action.handler] [PQHF8H] try to fire action > with args () and kwargs {} 2016-06-09 19:12:28,805 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-06-09 19:12:28,805 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2016-06-09 19:12:28,806 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2016-06-09 19:12:28,807 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,808 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2016-06-09 19:12:28,809 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2016-06-09 19:12:28,810 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,811 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2016-06-09 19:12:28,811 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2016-06-09 19:12:28,812 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,813 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2016-06-09 19:12:28,814 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2016-06-09 19:12:28,815 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,816 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2016-06-09 19:12:28,816 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2016-06-09 19:12:28,817 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,818 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2016-06-09 19:12:28,819 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2016-06-09 19:12:28,819 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,820 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2016-06-09 19:12:28,820 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2016-06-09 19:12:28,821 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,821 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2016-06-09 19:12:28,822 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2016-06-09 19:12:28,822 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,823 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2016-06-09 19:12:28,824 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2016-06-09 19:12:28,824 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,825 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2016-06-09 19:12:28,825 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2016-06-09 19:12:28,826 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,826 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2016-06-09 19:12:28,827 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2016-06-09 19:12:28,827 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,828 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2016-06-09 19:12:28,829 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2016-06-09 19:12:28,829 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,830 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2016-06-09 19:12:28,830 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2016-06-09 19:12:28,831 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,831 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2016-06-09 19:12:28,832 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2016-06-09 19:12:28,832 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,833 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2016-06-09 19:12:28,833 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2016-06-09 19:12:28,834 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,835 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2016-06-09 19:12:28,835 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2016-06-09 19:12:28,836 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,836 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2016-06-09 19:12:28,837 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2016-06-09 19:12:28,837 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,838 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2016-06-09 19:12:28,838 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2016-06-09 19:12:28,839 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,839 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2016-06-09 19:12:28,840 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2016-06-09 19:12:28,840 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,841 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2016-06-09 19:12:28,842 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2016-06-09 19:12:28,842 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,843 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2016-06-09 19:12:28,843 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2016-06-09 19:12:28,844 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-06-09 19:12:28,844 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2016-06-09 19:12:28,845 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2016-06-09 19:12:28,846 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2016-06-09 19:12:28,847 [TRACE] [doorpi.action.handler] [PQHF8H] try to fire action > with args () and kwargs {} 2016-06-09 19:12:28,847 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-06-09 19:12:28,848 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2016-06-09 19:12:28,850 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2016-06-09 19:12:28,851 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:28,851 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:28,852 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2016-06-09 19:12:28,853 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:28,854 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2016-06-09 19:12:28,855 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2016-06-09 19:12:28,855 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-06-09 19:12:28,856 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2016-06-09 19:12:28,856 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2016-06-09 19:12:28,857 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2016-06-09 19:12:28,858 [TRACE] [doorpi.action.handler] [PQHF8H] try to fire action > with args () and kwargs {} 2016-06-09 19:12:28,859 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2016-06-09 19:12:28,860 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2016-06-09 19:12:28,860 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:28,861 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2016-06-09 19:12:28,862 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2016-06-09 19:12:28,862 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:28,863 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2016-06-09 19:12:28,863 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2016-06-09 19:12:28,864 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-06-09 19:12:28,864 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2016-06-09 19:12:28,865 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2016-06-09 19:12:28,866 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2016-06-09 19:12:28,867 [TRACE] [doorpi.action.handler] [PQHF8H] finished fire_event for event_name OnSipPhoneDestroy 2016-06-09 19:12:28,867 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2016-06-09 19:12:28,868 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,869 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2016-06-09 19:12:28,869 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2016-06-09 19:12:28,870 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,870 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2016-06-09 19:12:28,871 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2016-06-09 19:12:28,871 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,872 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2016-06-09 19:12:28,872 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2016-06-09 19:12:28,873 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,873 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2016-06-09 19:12:28,874 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2016-06-09 19:12:28,875 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,875 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2016-06-09 19:12:28,876 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2016-06-09 19:12:28,876 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,877 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2016-06-09 19:12:28,877 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2016-06-09 19:12:28,878 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,878 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2016-06-09 19:12:28,879 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2016-06-09 19:12:28,879 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,880 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2016-06-09 19:12:28,881 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2016-06-09 19:12:28,881 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,882 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2016-06-09 19:12:28,882 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2016-06-09 19:12:28,883 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,883 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2016-06-09 19:12:28,884 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2016-06-09 19:12:28,884 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,885 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2016-06-09 19:12:28,886 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2016-06-09 19:12:28,886 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-06-09 19:12:28,887 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2016-06-09 19:12:28,887 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2016-06-09 19:12:28,888 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2016-06-09 19:12:28,889 [TRACE] [doorpi.action.handler] [FP2FK3] finished fire_event for event_name OnShutdown 2016-06-09 19:12:28,890 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2016-06-09 19:12:28,892 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2016-06-09 19:12:29,392 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ========