2019-07-19 01:44:22,770 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2019-07-19 01:44:22,771 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2019-07-19 01:44:22,771 [DEBUG] [doorpi.doorpi] run 2019-07-19 01:44:22,771 [DEBUG] [doorpi.doorpi] prepare 2019-07-19 01:44:22,772 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2019-07-19 01:44:22,774 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2019-07-19 01:44:22,778 [DEBUG] [doorpi.conf.config_object] __init__ 2019-07-19 01:44:22,778 [TRACE] [doorpi.conf.config_object] get_from_config 2019-07-19 01:44:22,781 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2019-07-19 01:44:22,781 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2019-07-19 01:44:22,782 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2019-07-19 01:44:22,784 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2019-07-19 01:44:22,784 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2019-07-19 01:44:22,785 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2019-07-19 01:44:22,785 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2019-07-19 01:44:22,785 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2019-07-19 01:44:22,785 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2019-07-19 01:44:22,785 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2019-07-19 01:44:22,786 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2019-07-19 01:44:22,786 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2019-07-19 01:44:22,786 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2019-07-19 01:44:22,786 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2019-07-19 01:44:22,786 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2019-07-19 01:44:22,787 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2019-07-19 01:44:22,787 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2019-07-19 01:44:22,788 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2019-07-19 01:44:22,790 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2019-07-19 01:44:22,790 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2019-07-19 01:44:22,790 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2019-07-19 01:44:22,790 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2019-07-19 01:44:22,792 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2019-07-19 01:44:22,793 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2019-07-19 01:44:22,793 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2019-07-19 01:44:22,793 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2019-07-19 01:44:22,793 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2019-07-19 01:44:22,793 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2019-07-19 01:44:22,794 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2019-07-19 01:44:22,794 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2019-07-19 01:44:22,795 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2019-07-19 01:44:22,795 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2019-07-19 01:44:22,795 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2019-07-19 01:44:22,796 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2019-07-19 01:44:22,796 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2019-07-19 01:44:22,797 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2019-07-19 01:44:22,797 [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 2019-07-19 01:44:22,797 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2019-07-19 01:44:22,798 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2019-07-19 01:44:22,798 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrator'] 2019-07-19 01:44:22,798 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2019-07-19 01:44:22,798 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2019-07-19 01:44:22,799 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2019-07-19 01:44:22,799 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_keyboard', 'Group', 'ReadPermission', 'AdminNumbers', 'keyboards', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2019-07-19 01:44:22,799 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2019-07-19 01:44:22,800 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section ReadPermission (default: []) returns ['installer'] 2019-07-19 01:44:22,800 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2019-07-19 01:44:22,800 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2019-07-19 01:44:22,800 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2019-07-19 01:44:22,801 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,802 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2019-07-19 01:44:22,802 [DEBUG] [doorpi.action.handler] [DB0N2H] fire for event OnWebServerStart this actions [] 2019-07-19 01:44:22,802 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,803 [TRACE] [doorpi.action.handler] [DB0N2H] try to fire action WebServerStartupAction with args () and kwargs {} 2019-07-19 01:44:22,803 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,803 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2019-07-19 01:44:22,803 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,804 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,804 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,804 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,805 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,805 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,805 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,805 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,805 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,806 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,806 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,806 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,806 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:22,806 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2019-07-19 01:44:22,807 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2019-07-19 01:44:22,807 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2019-07-19 01:44:22,807 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2019-07-19 01:44:22,807 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['11'] 2019-07-19 01:44:22,808 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2019-07-19 01:44:22,808 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2019-07-19 01:44:22,808 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 500.0 2019-07-19 01:44:22,808 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2019-07-19 01:44:22,809 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2019-07-19 01:44:22,809 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2019-07-19 01:44:22,812 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['11'], output_pins = [], bouncetime = 500.0, polarity = 0) 2019-07-19 01:44:22,812 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2019-07-19 01:44:22,812 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_DOWN 2019-07-19 01:44:22,813 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,814 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2019-07-19 01:44:22,814 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,815 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,815 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_11 and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,815 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,815 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,816 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,816 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,816 [TRACE] [doorpi.action.handler] register Event OnKeyUp_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,816 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_11 and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,816 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,817 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,817 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,817 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,818 [TRACE] [doorpi.action.handler] register Event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,818 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_11 and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,818 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:22,818 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2019-07-19 01:44:22,819 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2019-07-19 01:44:22,819 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2019-07-19 01:44:22,819 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2019-07-19 01:44:22,821 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2019-07-19 01:44:22,822 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2019-07-19 01:44:22,823 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2019-07-19 01:44:22,824 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2019-07-19 01:44:22,858 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2019-07-19 01:44:22,859 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2019-07-19 01:44:22,861 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2019-07-19 01:44:22,894 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2019-07-19 01:44:22,898 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2019-07-19 01:44:22,898 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2019-07-19 01:44:22,899 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2019-07-19 01:44:22,901 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2019-07-19 01:44:22,902 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2019-07-19 01:44:22,903 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2019-07-19 01:44:22,903 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2019-07-19 01:44:22,904 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2019-07-19 01:44:22,904 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,904 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2019-07-19 01:44:22,904 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,905 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,905 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,905 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,905 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,906 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,906 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,906 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,906 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,906 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,907 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,907 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,907 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,907 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,907 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,908 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,908 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,908 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,908 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,908 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,909 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,909 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2019-07-19 01:44:22,909 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2019-07-19 01:44:22,909 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2019-07-19 01:44:22,910 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2019-07-19 01:44:22,911 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,911 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2019-07-19 01:44:22,911 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,911 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,912 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,912 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,912 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,912 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,912 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,913 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,913 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,913 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,913 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,913 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,914 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,914 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,914 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,914 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,914 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,915 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,915 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,915 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,915 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,915 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,916 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,916 [WARNING] [root] section DTMF not found in configfile 2019-07-19 01:44:22,916 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2019-07-19 01:44:22,916 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,917 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2019-07-19 01:44:22,917 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,917 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,917 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,917 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,918 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,918 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,918 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,918 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,918 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,919 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,919 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,919 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,920 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,920 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,920 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,920 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,920 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,921 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,921 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2019-07-19 01:44:22,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2019-07-19 01:44:22,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x74737590 2019-07-19 01:44:22,923 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:22,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2019-07-19 01:44:22,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x74737590 2019-07-19 01:44:22,924 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74658db0, "(null)", "(null)") 2019-07-19 01:44:22,924 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2019-07-19 01:44:22,924 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2019-07-19 01:44:22,924 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2019-07-19 01:44:22,925 [INFO] [doorpi.sipphone.from_linphone] srtp init 2019-07-19 01:44:22,932 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2019-07-19 01:44:22,956 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2019-07-19 01:44:22,960 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1711 - open '/dev/snd/pcmC0D0c' failed (-2) 2019-07-19 01:44:22,962 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2019-07-19 01:44:22,962 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2019-07-19 01:44:22,963 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2019-07-19 01:44:22,963 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2019-07-19 01:44:22,963 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2019-07-19 01:44:22,963 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2019-07-19 01:44:22,964 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2019-07-19 01:44:22,964 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x238ff18] registered on core [0x238fc30] 2019-07-19 01:44:22,964 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x238ff18, 1, "Starting up") 2019-07-19 01:44:22,964 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2019-07-19 01:44:22,964 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x238ff18] notifying [global_state_changed] 2019-07-19 01:44:22,965 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2019-07-19 01:44:22,965 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2019-07-19 01:44:22,965 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2019-07-19 01:44:22,965 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2019-07-19 01:44:22,966 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2019-07-19 01:44:22,966 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-07-19 01:44:22,966 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-07-19 01:44:22,966 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2019-07-19 01:44:22,967 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2019-07-19 01:44:22,967 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2019-07-19 01:44:22,967 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2019-07-19 01:44:22,967 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2019-07-19 01:44:22,968 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2019-07-19 01:44:22,968 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2019-07-19 01:44:22,968 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2019-07-19 01:44:22,968 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2019-07-19 01:44:22,969 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2019-07-19 01:44:22,969 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2019-07-19 01:44:22,969 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2019-07-19 01:44:22,969 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2019-07-19 01:44:22,970 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2019-07-19 01:44:22,970 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2019-07-19 01:44:22,970 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2019-07-19 01:44:22,970 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-07-19 01:44:22,971 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-07-19 01:44:22,971 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-07-19 01:44:22,971 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-07-19 01:44:22,971 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-07-19 01:44:22,971 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-07-19 01:44:22,972 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-07-19 01:44:22,972 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-07-19 01:44:22,972 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2019-07-19 01:44:22,972 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2019-07-19 01:44:22,973 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2019-07-19 01:44:22,973 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2019-07-19 01:44:22,973 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2019-07-19 01:44:22,973 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-07-19 01:44:22,974 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-07-19 01:44:22,974 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-07-19 01:44:22,974 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-07-19 01:44:22,974 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2019-07-19 01:44:22,974 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2019-07-19 01:44:22,975 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2019-07-19 01:44:22,975 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2019-07-19 01:44:22,975 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2019-07-19 01:44:22,975 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2019-07-19 01:44:22,976 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2019-07-19 01:44:22,976 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2019-07-19 01:44:22,976 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2019-07-19 01:44:22,976 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2019-07-19 01:44:22,977 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2019-07-19 01:44:22,977 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2019-07-19 01:44:22,977 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2019-07-19 01:44:22,977 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2019-07-19 01:44:22,977 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2019-07-19 01:44:22,978 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2019-07-19 01:44:22,978 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2019-07-19 01:44:22,978 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2019-07-19 01:44:22,978 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2019-07-19 01:44:22,979 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2019-07-19 01:44:22,979 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2019-07-19 01:44:22,979 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2019-07-19 01:44:22,980 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2019-07-19 01:44:22,980 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2019-07-19 01:44:22,980 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2019-07-19 01:44:22,980 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2019-07-19 01:44:22,981 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2019-07-19 01:44:22,981 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2019-07-19 01:44:22,981 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2019-07-19 01:44:22,981 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2019-07-19 01:44:22,982 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2019-07-19 01:44:22,982 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2019-07-19 01:44:22,982 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2019-07-19 01:44:22,982 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2019-07-19 01:44:22,982 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2019-07-19 01:44:22,983 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2019-07-19 01:44:22,983 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2019-07-19 01:44:22,983 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2019-07-19 01:44:22,983 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2019-07-19 01:44:22,984 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2019-07-19 01:44:22,984 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2019-07-19 01:44:22,984 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2019-07-19 01:44:22,984 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2019-07-19 01:44:22,985 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2019-07-19 01:44:22,985 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:22,985 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:22,985 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:22,986 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x238ff18, 2, "(null)") 2019-07-19 01:44:22,986 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2019-07-19 01:44:22,986 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x238ff18] notifying [configuring_status] 2019-07-19 01:44:22,986 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2019-07-19 01:44:22,987 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2019-07-19 01:44:22,987 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2019-07-19 01:44:22,987 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2019-07-19 01:44:22,987 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2019-07-19 01:44:22,988 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2019-07-19 01:44:22,988 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2019-07-19 01:44:22,988 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2019-07-19 01:44:22,988 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2019-07-19 01:44:22,988 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2019-07-19 01:44:22,989 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,989 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,989 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,989 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,990 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2019-07-19 01:44:22,990 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2019-07-19 01:44:22,990 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2019-07-19 01:44:22,990 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,991 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,991 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2019-07-19 01:44:22,991 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2019-07-19 01:44:22,991 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:22,992 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2019-07-19 01:44:22,992 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x2391570] on [sip:0.0.0.0:5060;transport=UDP] 2019-07-19 01:44:22,992 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x2342140] on [sip:0.0.0.0:5060;transport=TCP] 2019-07-19 01:44:22,993 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x23915f0] on [sip:0.0.0.0:-1;transport=TLS] 2019-07-19 01:44:22,993 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 45251 2019-07-19 01:44:22,993 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:22,994 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:22,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x238ff18, 2, "Ready") 2019-07-19 01:44:22,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2019-07-19 01:44:22,996 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x238ff18] notifying [global_state_changed] 2019-07-19 01:44:22,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x74737590 2019-07-19 01:44:22,997 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2019-07-19 01:44:22,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x74737590 [0x238ff18], "DoorPi ") 2019-07-19 01:44:22,998 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2019-07-19 01:44:22,998 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:22,999 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2019-07-19 01:44:23,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x74737590 [0x238ff18], 2) 2019-07-19 01:44:23,000 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2019-07-19 01:44:23,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2019-07-19 01:44:23,001 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2019-07-19 01:44:23,001 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x74737590 [0x238ff18], 0x2a311c [0]) 2019-07-19 01:44:23,001 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2019-07-19 01:44:23,001 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2019-07-19 01:44:23,002 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2019-07-19 01:44:23,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2019-07-19 01:44:23,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x747375a8 2019-07-19 01:44:23,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x74737590 [0x238ff18], 0x747375a8 [0x747375b0]) 2019-07-19 01:44:23,003 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x2391570] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2019-07-19 01:44:23,003 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x2342140] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2019-07-19 01:44:23,003 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x23915f0] on [sip:0.0.0.0:45251;transport=TLS] destroyed 2019-07-19 01:44:23,003 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2019-07-19 01:44:23,004 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x2391570] on [sip:0.0.0.0:5060;transport=UDP] 2019-07-19 01:44:23,004 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x23915b0] on [sip:0.0.0.0:5060;transport=TCP] 2019-07-19 01:44:23,004 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x23695d8] on [sip:0.0.0.0:-1;transport=TLS] 2019-07-19 01:44:23,005 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 34605 2019-07-19 01:44:23,005 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:23,005 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2019-07-19 01:44:23,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2019-07-19 01:44:23,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x747375a8) 2019-07-19 01:44:23,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2019-07-19 01:44:23,006 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2019-07-19 01:44:23,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x74737590 [0x238ff18], 0x2a311c [0]) 2019-07-19 01:44:23,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2019-07-19 01:44:23,007 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2019-07-19 01:44:23,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x74737590 [0x238ff18], "") 2019-07-19 01:44:23,008 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2019-07-19 01:44:23,008 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2019-07-19 01:44:23,008 [INFO] [doorpi.sipphone.from_linphone] fd00::e228:6dff:feea:a171 2019-07-19 01:44:23,009 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2019-07-19 01:44:23,009 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2019-07-19 01:44:23,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2019-07-19 01:44:23,010 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2019-07-19 01:44:23,010 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2019-07-19 01:44:23,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x74737590 [0x238ff18], 0) 2019-07-19 01:44:23,011 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2019-07-19 01:44:23,011 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2019-07-19 01:44:23,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2019-07-19 01:44:23,012 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2019-07-19 01:44:23,012 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x74737590 [0x238ff18], 120) 2019-07-19 01:44:23,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2019-07-19 01:44:23,013 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2019-07-19 01:44:23,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x74737590 [0x238ff18], 15) 2019-07-19 01:44:23,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2019-07-19 01:44:23,014 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2019-07-19 01:44:23,015 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2019-07-19 01:44:23,015 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2019-07-19 01:44:23,016 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2019-07-19 01:44:23,016 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:23,016 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2019-07-19 01:44:23,017 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:23,017 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:23,017 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:23,017 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:23,017 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2019-07-19 01:44:23,018 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2019-07-19 01:44:23,018 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2019-07-19 01:44:23,018 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2019-07-19 01:44:23,018 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2019-07-19 01:44:23,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x74737590 [0x238ff18], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2019-07-19 01:44:23,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2019-07-19 01:44:23,020 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:23,021 [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 2019-07-19 01:44:23,021 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2019-07-19 01:44:23,021 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,022 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2019-07-19 01:44:23,022 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,022 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,022 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,022 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,023 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,023 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2019-07-19 01:44:23,023 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2019-07-19 01:44:23,023 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2019-07-19 01:44:23,024 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2019-07-19 01:44:23,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x746628f0 2019-07-19 01:44:23,025 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:23,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7465e800 2019-07-19 01:44:23,026 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2019-07-19 01:44:23,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x74737590 [0x238ff18], "ALSA: default device") 2019-07-19 01:44:23,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2019-07-19 01:44:23,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7465e7d0 2019-07-19 01:44:23,027 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2019-07-19 01:44:23,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x74737590 [0x238ff18], "ALSA: default device") 2019-07-19 01:44:23,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2019-07-19 01:44:23,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74662828 2019-07-19 01:44:23,028 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2019-07-19 01:44:23,029 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2019-07-19 01:44:23,029 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-07-19 01:44:23,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74662828 2019-07-19 01:44:23,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x74737590 [0x238ff18], "ALSA: default device") 2019-07-19 01:44:23,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3128 2019-07-19 01:44:23,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x74737590 [0x238ff18], "ALSA: default device") 2019-07-19 01:44:23,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2019-07-19 01:44:23,031 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2019-07-19 01:44:23,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x74737590 [0x238ff18], "ALSA: bcm2835 ALSA") 2019-07-19 01:44:23,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a311c 2019-07-19 01:44:23,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x74737590 [0x238ff18], "ALSA: bcm2835 ALSA") 2019-07-19 01:44:23,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2019-07-19 01:44:23,032 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2019-07-19 01:44:23,032 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-07-19 01:44:23,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7465e8c0 2019-07-19 01:44:23,033 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2019-07-19 01:44:23,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7465e890 2019-07-19 01:44:23,033 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2019-07-19 01:44:23,034 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2019-07-19 01:44:23,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c5d0) 2019-07-19 01:44:23,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98420 2019-07-19 01:44:23,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98420 2019-07-19 01:44:23,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98420 2019-07-19 01:44:23,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c628) 2019-07-19 01:44:23,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98430 2019-07-19 01:44:23,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98430 2019-07-19 01:44:23,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98430 2019-07-19 01:44:23,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c690) 2019-07-19 01:44:23,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98440 2019-07-19 01:44:23,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98440 2019-07-19 01:44:23,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98440 2019-07-19 01:44:23,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c6f8) 2019-07-19 01:44:23,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98450 2019-07-19 01:44:23,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98450 2019-07-19 01:44:23,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98450 2019-07-19 01:44:23,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c750) 2019-07-19 01:44:23,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98460 2019-07-19 01:44:23,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98460 2019-07-19 01:44:23,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98460 2019-07-19 01:44:23,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c7a8) 2019-07-19 01:44:23,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98470 2019-07-19 01:44:23,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98470 2019-07-19 01:44:23,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98470 2019-07-19 01:44:23,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c800) 2019-07-19 01:44:23,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98480 2019-07-19 01:44:23,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98480 2019-07-19 01:44:23,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98480 2019-07-19 01:44:23,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x237c858) 2019-07-19 01:44:23,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c98490 2019-07-19 01:44:23,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c98490 2019-07-19 01:44:23,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c98490 2019-07-19 01:44:23,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x236a048) 2019-07-19 01:44:23,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c984a0 2019-07-19 01:44:23,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c984a0 2019-07-19 01:44:23,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c984a0 2019-07-19 01:44:23,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x236a0a0) 2019-07-19 01:44:23,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c984b0 2019-07-19 01:44:23,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c984b0 2019-07-19 01:44:23,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c984b0 2019-07-19 01:44:23,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x74662990 2019-07-19 01:44:23,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98420 [0x237c5d0]) 2019-07-19 01:44:23,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98420 [0x237c5d0]) 2019-07-19 01:44:23,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,049 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2019-07-19 01:44:23,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98420 [0x237c5d0], 0x2a311c [0]) 2019-07-19 01:44:23,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98430 [0x237c628]) 2019-07-19 01:44:23,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c980 2019-07-19 01:44:23,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98430 [0x237c628]) 2019-07-19 01:44:23,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c980 2019-07-19 01:44:23,051 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2019-07-19 01:44:23,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98430 [0x237c628], 0x2a311c [0]) 2019-07-19 01:44:23,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98440 [0x237c690]) 2019-07-19 01:44:23,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98440 [0x237c690]) 2019-07-19 01:44:23,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,053 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2019-07-19 01:44:23,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98440 [0x237c690], 0x2a311c [0]) 2019-07-19 01:44:23,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98450 [0x237c6f8]) 2019-07-19 01:44:23,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c980 2019-07-19 01:44:23,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98450 [0x237c6f8]) 2019-07-19 01:44:23,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c980 2019-07-19 01:44:23,055 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2019-07-19 01:44:23,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98450 [0x237c6f8], 0x2a3128 [1]) 2019-07-19 01:44:23,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98460 [0x237c750]) 2019-07-19 01:44:23,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98460 [0x237c750]) 2019-07-19 01:44:23,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,056 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2019-07-19 01:44:23,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98460 [0x237c750], 0x2a3128 [1]) 2019-07-19 01:44:23,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98470 [0x237c7a8]) 2019-07-19 01:44:23,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98470 [0x237c7a8]) 2019-07-19 01:44:23,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,058 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2019-07-19 01:44:23,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98470 [0x237c7a8], 0x2a311c [0]) 2019-07-19 01:44:23,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98480 [0x237c800]) 2019-07-19 01:44:23,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98480 [0x237c800]) 2019-07-19 01:44:23,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c7e0 2019-07-19 01:44:23,060 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2019-07-19 01:44:23,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98480 [0x237c800], 0x2a311c [0]) 2019-07-19 01:44:23,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98490 [0x237c858]) 2019-07-19 01:44:23,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c980 2019-07-19 01:44:23,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c98490 [0x237c858]) 2019-07-19 01:44:23,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x7465c980 2019-07-19 01:44:23,061 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2019-07-19 01:44:23,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c98490 [0x237c858], 0x2a311c [0]) 2019-07-19 01:44:23,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c984a0 [0x236a048]) 2019-07-19 01:44:23,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c984a0 [0x236a048]) 2019-07-19 01:44:23,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,063 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2019-07-19 01:44:23,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c984a0 [0x236a048], 0x2a311c [0]) 2019-07-19 01:44:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c984b0 [0x236a0a0]) 2019-07-19 01:44:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c984b0 [0x236a0a0]) 2019-07-19 01:44:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,065 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2019-07-19 01:44:23,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c984b0 [0x236a0a0], 0x2a311c [0]) 2019-07-19 01:44:23,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746628f0 2019-07-19 01:44:23,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746628f0 2019-07-19 01:44:23,066 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2019-07-19 01:44:23,067 [DEBUG] [doorpi.sipphone.from_linphone] | name 2019-07-19 01:44:23,067 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-07-19 01:44:23,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746628f0 2019-07-19 01:44:23,067 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2019-07-19 01:44:23,068 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2019-07-19 01:44:23,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746628f0 2019-07-19 01:44:23,068 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2019-07-19 01:44:23,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x746628f0 2019-07-19 01:44:23,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x74737590 [0x238ff18], 0x2a3128 [1]) 2019-07-19 01:44:23,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2019-07-19 01:44:23,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x74737590 [0x238ff18], "StaticImage: Static picture") 2019-07-19 01:44:23,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2019-07-19 01:44:23,070 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2019-07-19 01:44:23,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x74737590 [0x238ff18], "vga") 2019-07-19 01:44:23,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2019-07-19 01:44:23,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x7465e9b0 2019-07-19 01:44:23,071 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2019-07-19 01:44:23,072 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2019-07-19 01:44:23,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x236a0f8) 2019-07-19 01:44:23,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2019-07-19 01:44:23,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c984c0 2019-07-19 01:44:23,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2019-07-19 01:44:23,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c984c0 2019-07-19 01:44:23,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c984c0 2019-07-19 01:44:23,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x74662940 2019-07-19 01:44:23,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c984c0 [0x236a0f8]) 2019-07-19 01:44:23,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2a3128 2019-07-19 01:44:23,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c984c0 [0x236a0f8]) 2019-07-19 01:44:23,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x747375a8 2019-07-19 01:44:23,075 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2019-07-19 01:44:23,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x74737590 [0x238ff18], 0x76c984c0 [0x236a0f8], 0x2a3128 [1]) 2019-07-19 01:44:23,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x21c076c 2019-07-19 01:44:23,076 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2019-07-19 01:44:23,076 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns Türsprechanlage 2019-07-19 01:44:23,076 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: Türsprechanlage) returns ******* 2019-07-19 01:44:23,076 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2019-07-19 01:44:23,077 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2019-07-19 01:44:23,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x74737590 [0x238ff18]) 2019-07-19 01:44:23,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x2322328) 2019-07-19 01:44:23,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2019-07-19 01:44:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76c984d0 2019-07-19 01:44:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2019-07-19 01:44:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76c984d0 2019-07-19 01:44:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76c984d0 2019-07-19 01:44:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76c984d0 2019-07-19 01:44:23,079 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2019-07-19 01:44:23,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2019-07-19 01:44:23,080 [INFO] [doorpi.sipphone.from_linphone] [org.antlr.runtime.MismatchedTokenException] reason [1530:33: ( ( ( userinfo[NULL] )=> userinfo[$uri::current] hostport[$uri::current] ) | hostport[$uri::current] )] 2019-07-19 01:44:23,080 [ERROR] [doorpi.sipphone.from_linphone] header_address parser error for [DoorPi ] 2019-07-19 01:44:23,080 [ERROR] [doorpi.sipphone.from_linphone] Cannot create LinphoneAddress, bad uri [DoorPi ] 2019-07-19 01:44:23,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr((nil)) 2019-07-19 01:44:23,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> None 2019-07-19 01:44:23,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x2a2810 2019-07-19 01:44:23,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76c984d0 [0x2322328], 0x2a2810 [(nil)]) 2019-07-19 01:44:23,081 [WARNING] [doorpi.sipphone.from_linphone] Invalid sip identity: NULL 2019-07-19 01:44:23,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2019-07-19 01:44:23,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76c984d0 [0x2322328], "sip:192.168.178.1") 2019-07-19 01:44:23,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2019-07-19 01:44:23,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76c984d0 [0x2322328], 0x2a3128 [1]) 2019-07-19 01:44:23,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2019-07-19 01:44:23,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x74737590 [0x238ff18], 0x76c984d0 [0x2322328]) 2019-07-19 01:44:23,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x21c0778 2019-07-19 01:44:23,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x74737590 [0x238ff18], 0x76c984d0 [0x2322328]) 2019-07-19 01:44:23,084 [WARNING] [doorpi.sipphone.from_linphone] Bad proxy address: it is not in the list ! 2019-07-19 01:44:23,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2019-07-19 01:44:23,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x74737590 [0x238ff18], "Türsprechanlage", "(null)", "Trabzon61", "(null)", "(null)", "fritz.box") 2019-07-19 01:44:23,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x2358a40) 2019-07-19 01:44:23,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2019-07-19 01:44:23,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76c984e0 2019-07-19 01:44:23,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2019-07-19 01:44:23,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76c984e0 2019-07-19 01:44:23,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76c984e0 2019-07-19 01:44:23,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76c984e0 2019-07-19 01:44:23,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x74737590 [0x238ff18], 0x76c984e0 [0x2358a40]) 2019-07-19 01:44:23,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2019-07-19 01:44:23,087 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2019-07-19 01:44:23,087 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2019-07-19 01:44:23,087 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2019-07-19 01:44:23,087 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2019-07-19 01:44:23,088 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2019-07-19 01:44:23,088 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2019-07-19 01:44:23,088 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2019-07-19 01:44:23,089 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2019-07-19 01:44:23,089 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_InputPins (default: ) returns call:11 2019-07-19 01:44:23,090 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2019-07-19 01:44:23,090 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '11'} was added to new evententry OnKeyPressed_onboardpins.11 2019-07-19 01:44:23,090 [WARNING] [root] section DTMF not found in configfile 2019-07-19 01:44:23,091 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2019-07-19 01:44:23,091 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2019-07-19 01:44:23,091 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2019-07-19 01:44:23,092 [DEBUG] [doorpi.action.handler] [3AY4E6] fire for event OnStartup this actions [] 2019-07-19 01:44:23,092 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2019-07-19 01:44:23,093 [TRACE] [doorpi.action.handler] [3AY4E6] try to fire action SleepAction with args (1.0,) and kwargs {} 2019-07-19 01:44:23,093 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2019-07-19 01:44:24,095 [TRACE] [doorpi.action.handler] [3AY4E6] finished fire_event for event_name OnStartup 2019-07-19 01:44:24,096 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2019-07-19 01:44:24,097 [INFO] [doorpi.doorpi] DoorPi started successfully 2019-07-19 01:44:24,098 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2019-07-19 01:44:24,099 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2019-07-19 01:44:24,099 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2019-07-19 01:44:24,100 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2019-07-19 01:44:24,101 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2019-07-19 01:44:24,102 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.100 2019-07-19 01:44:24,102 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2019-07-19 01:44:24,103 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2019-07-19 01:44:24,103 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2019-07-19 01:44:24,104 [INFO] [doorpi.sipphone.from_linphone] fd00::e228:6dff:feea:a171 2019-07-19 01:44:24,105 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2019-07-19 01:44:24,106 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2019-07-19 01:44:24,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x238ff18, 0x1) 2019-07-19 01:44:24,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2019-07-19 01:44:24,158 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x238ff18] notifying [network_reachable] 2019-07-19 01:44:24,158 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2019-07-19 01:44:24,159 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2019-07-19 01:44:30,824 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:30,825 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:30,827 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:30,828 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:30,831 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:30,832 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_11 - skip fire_event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:30,833 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:30,834 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_onboardpins.11 - skip fire_event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:30,835 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:30,836 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:30,838 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_11 - skip fire_event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:30,839 [DEBUG] [doorpi.action.handler] [O3U2H0] fire for event OnKeyPressed_onboardpins.11 this actions [] 2019-07-19 01:44:30,840 [TRACE] [doorpi.action.handler] [O3U2H0] try to fire action CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:30,841 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:30,841 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:30,843 [DEBUG] [doorpi.sipphone.from_linphone] call (11) 2019-07-19 01:44:30,843 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2019-07-19 01:44:30,844 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:30,844 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2019-07-18 23:44:30.844630 2019-07-19 01:44:30,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x74737590 [0x238ff18], 0x2a2810 [(nil)]) 2019-07-19 01:44:30,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x733029d8) 2019-07-19 01:44:30,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2019-07-19 01:44:30,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0x76c98540 2019-07-19 01:44:30,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2019-07-19 01:44:30,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0x76c98540 2019-07-19 01:44:30,848 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0x76c98540 2019-07-19 01:44:30,849 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0x76c98540 2019-07-19 01:44:30,851 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0x76c98540 [0x733029d8], "/usr/local/etc/DoorPi/records/2019-07-19_01-44-30.wav") 2019-07-19 01:44:30,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2019-07-19 01:44:30,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0x76c98540 [0x733029d8], 0x2a3128 [1]) 2019-07-19 01:44:30,853 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2019-07-19 01:44:30,853 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x74737590 [0x238ff18], "11", 0x76c98540 [0x733029d8]) 2019-07-19 01:44:30,854 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0x2a2810 2019-07-19 01:44:30,854 [DEBUG] [doorpi.action.handler] request event logs count with filter OnSipPhoneMakeCallFailed 2019-07-19 01:44:30,855 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:30,857 [TRACE] [doorpi.action.handler] [O3U2H0] finished fire_event for event_name OnKeyPressed_onboardpins.11 2019-07-19 01:44:30,858 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneMakeCallFailed - skip fire_event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:32,257 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:32,259 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:32,261 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:32,263 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_gpio 2019-07-19 01:44:32,263 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.11 - skip fire_event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:32,264 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_11 - skip fire_event OnKeyUp_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,109 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,110 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,112 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,112 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,114 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_11 - skip fire_event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,115 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,116 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_onboardpins.11 - skip fire_event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,118 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,119 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,121 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_11 - skip fire_event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,122 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,123 [DEBUG] [doorpi.action.handler] [RC1Y7Q] fire for event OnKeyPressed_onboardpins.11 this actions [] 2019-07-19 01:44:33,124 [TRACE] [doorpi.action.handler] [RC1Y7Q] try to fire action CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:33,124 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:33,125 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:33,126 [DEBUG] [doorpi.sipphone.from_linphone] call (11) 2019-07-19 01:44:33,127 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2019-07-19 01:44:33,127 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:33,128 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2019-07-18 23:44:33.128193 2019-07-19 01:44:33,129 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x74737590 [0x238ff18], 0x2a2810 [(nil)]) 2019-07-19 01:44:33,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x727029e0) 2019-07-19 01:44:33,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2019-07-19 01:44:33,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0x76c98550 2019-07-19 01:44:33,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2019-07-19 01:44:33,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0x76c98550 2019-07-19 01:44:33,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0x76c98550 2019-07-19 01:44:33,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0x76c98550 2019-07-19 01:44:33,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0x76c98550 [0x727029e0], "/usr/local/etc/DoorPi/records/2019-07-19_01-44-33.wav") 2019-07-19 01:44:33,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2019-07-19 01:44:33,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0x76c98550 [0x727029e0], 0x2a3128 [1]) 2019-07-19 01:44:33,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2019-07-19 01:44:33,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x74737590 [0x238ff18], "11", 0x76c98550 [0x727029e0]) 2019-07-19 01:44:33,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0x2a2810 2019-07-19 01:44:33,138 [DEBUG] [doorpi.action.handler] request event logs count with filter OnSipPhoneMakeCallFailed 2019-07-19 01:44:33,139 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:33,141 [TRACE] [doorpi.action.handler] [RC1Y7Q] finished fire_event for event_name OnKeyPressed_onboardpins.11 2019-07-19 01:44:33,141 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneMakeCallFailed - skip fire_event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:33,843 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,845 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,846 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,847 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,849 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_11 - skip fire_event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,850 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,851 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_onboardpins.11 - skip fire_event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,852 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,853 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,855 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:33,857 [DEBUG] [doorpi.action.handler] [68Z9PS] fire for event OnKeyPressed_onboardpins.11 this actions [] 2019-07-19 01:44:33,857 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_11 - skip fire_event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:33,858 [TRACE] [doorpi.action.handler] [68Z9PS] try to fire action CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:33,859 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:33,859 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:33,861 [DEBUG] [doorpi.sipphone.from_linphone] call (11) 2019-07-19 01:44:33,862 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2019-07-19 01:44:33,862 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:33,863 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2019-07-18 23:44:33.863244 2019-07-19 01:44:33,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x74737590 [0x238ff18], 0x2a2810 [(nil)]) 2019-07-19 01:44:33,864 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x73307588) 2019-07-19 01:44:33,865 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2019-07-19 01:44:33,865 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0x76c98560 2019-07-19 01:44:33,866 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2019-07-19 01:44:33,866 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0x76c98560 2019-07-19 01:44:33,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0x76c98560 2019-07-19 01:44:33,867 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0x76c98560 2019-07-19 01:44:33,869 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0x76c98560 [0x73307588], "/usr/local/etc/DoorPi/records/2019-07-19_01-44-33.wav") 2019-07-19 01:44:33,870 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2019-07-19 01:44:33,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0x76c98560 [0x73307588], 0x2a3128 [1]) 2019-07-19 01:44:33,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2019-07-19 01:44:33,871 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x74737590 [0x238ff18], "11", 0x76c98560 [0x73307588]) 2019-07-19 01:44:33,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0x2a2810 2019-07-19 01:44:33,872 [DEBUG] [doorpi.action.handler] request event logs count with filter OnSipPhoneMakeCallFailed 2019-07-19 01:44:33,874 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:33,875 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneMakeCallFailed - skip fire_event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:33,876 [TRACE] [doorpi.action.handler] [68Z9PS] finished fire_event for event_name OnKeyPressed_onboardpins.11 2019-07-19 01:44:36,676 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:36,682 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:36,683 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:36,684 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:36,685 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_11 - skip fire_event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:36,686 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:36,688 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:36,691 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_onboardpins.11 - skip fire_event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:36,692 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:36,692 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:36,693 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_11 - skip fire_event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:36,695 [DEBUG] [doorpi.action.handler] [YRHBPN] fire for event OnKeyPressed_onboardpins.11 this actions [] 2019-07-19 01:44:36,696 [TRACE] [doorpi.action.handler] [YRHBPN] try to fire action CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:36,696 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:36,697 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:36,698 [DEBUG] [doorpi.sipphone.from_linphone] call (11) 2019-07-19 01:44:36,699 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2019-07-19 01:44:36,699 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:36,700 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2019-07-18 23:44:36.700230 2019-07-19 01:44:36,701 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x74737590 [0x238ff18], 0x2a2810 [(nil)]) 2019-07-19 01:44:36,701 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x73309470) 2019-07-19 01:44:36,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2019-07-19 01:44:36,702 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0x76c98570 2019-07-19 01:44:36,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2019-07-19 01:44:36,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0x76c98570 2019-07-19 01:44:36,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0x76c98570 2019-07-19 01:44:36,704 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0x76c98570 2019-07-19 01:44:36,706 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0x76c98570 [0x73309470], "/usr/local/etc/DoorPi/records/2019-07-19_01-44-36.wav") 2019-07-19 01:44:36,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2019-07-19 01:44:36,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0x76c98570 [0x73309470], 0x2a3128 [1]) 2019-07-19 01:44:36,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2019-07-19 01:44:36,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x74737590 [0x238ff18], "11", 0x76c98570 [0x73309470]) 2019-07-19 01:44:36,709 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0x2a2810 2019-07-19 01:44:36,709 [DEBUG] [doorpi.action.handler] request event logs count with filter OnSipPhoneMakeCallFailed 2019-07-19 01:44:36,711 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:36,712 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneMakeCallFailed - skip fire_event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:36,713 [TRACE] [doorpi.action.handler] [YRHBPN] finished fire_event for event_name OnKeyPressed_onboardpins.11 2019-07-19 01:44:37,332 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:37,334 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:37,335 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:37,336 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:37,338 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_11 - skip fire_event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:37,339 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:37,341 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:37,342 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_onboardpins.11 - skip fire_event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:37,343 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio asyncron 2019-07-19 01:44:37,343 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:37,345 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_11 - skip fire_event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:37,347 [DEBUG] [doorpi.action.handler] [Q1B5NQ] fire for event OnKeyPressed_onboardpins.11 this actions [] 2019-07-19 01:44:37,348 [TRACE] [doorpi.action.handler] [Q1B5NQ] try to fire action CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:37,348 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '11'} 2019-07-19 01:44:37,349 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:37,350 [DEBUG] [doorpi.sipphone.from_linphone] call (11) 2019-07-19 01:44:37,351 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2019-07-19 01:44:37,351 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:37,352 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2019-07-18 23:44:37.352089 2019-07-19 01:44:37,353 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_call_params(0x74737590 [0x238ff18], 0x2a2810 [(nil)]) 2019-07-19 01:44:37,353 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_from_native_ptr(0x73309e08) 2019-07-19 01:44:37,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_new() 2019-07-19 01:44:37,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_new -> 0x76c98520 2019-07-19 01:44:37,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_init() 2019-07-19 01:44:37,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_init -> 0x76c98520 2019-07-19 01:44:37,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_from_native_ptr -> 0x76c98520 2019-07-19 01:44:37,356 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_call_params -> 0x76c98520 2019-07-19 01:44:37,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_set_record_file(0x76c98520 [0x73309e08], "/usr/local/etc/DoorPi/records/2019-07-19_01-44-37.wav") 2019-07-19 01:44:37,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_set_record_file -> 0 2019-07-19 01:44:37,359 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_CallParams_enable_video(0x76c98520 [0x73309e08], 0x2a3128 [1]) 2019-07-19 01:44:37,360 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_CallParams_enable_video -> 0 2019-07-19 01:44:37,360 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_invite_with_params(0x74737590 [0x238ff18], "11", 0x76c98520 [0x73309e08]) 2019-07-19 01:44:37,361 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_invite_with_params -> 0x2a2810 2019-07-19 01:44:37,361 [DEBUG] [doorpi.action.handler] request event logs count with filter OnSipPhoneMakeCallFailed 2019-07-19 01:44:37,363 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone asyncron 2019-07-19 01:44:37,364 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneMakeCallFailed - skip fire_event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:37,365 [TRACE] [doorpi.action.handler] [Q1B5NQ] finished fire_event for event_name OnKeyPressed_onboardpins.11 2019-07-19 01:44:39,724 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2019-07-19 01:44:39,725 [DEBUG] [doorpi.doorpi] destroy doorpi 2019-07-19 01:44:39,726 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [, <_DummyThread(Dummy-1, started daemon 1975514208)>, <_MainThread(MainThread, started 1995677712)>] 2019-07-19 01:44:39,726 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2019-07-19 01:44:39,728 [DEBUG] [doorpi.action.handler] [LRN0WE] fire for event OnShutdown this actions [, , , ] 2019-07-19 01:44:39,729 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2019-07-19 01:44:39,729 [TRACE] [doorpi.action.handler] [LRN0WE] try to fire action TimeTickDestroyAction with args () and kwargs {} 2019-07-19 01:44:39,730 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2019-07-19 01:44:39,731 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2019-07-19 01:44:39,731 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,732 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2019-07-19 01:44:39,732 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2019-07-19 01:44:39,733 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,733 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2019-07-19 01:44:39,733 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2019-07-19 01:44:39,734 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,734 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2019-07-19 01:44:39,735 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2019-07-19 01:44:39,735 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,736 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2019-07-19 01:44:39,736 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2019-07-19 01:44:39,736 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,737 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2019-07-19 01:44:39,737 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2019-07-19 01:44:39,738 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,738 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2019-07-19 01:44:39,739 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2019-07-19 01:44:39,739 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,740 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2019-07-19 01:44:39,740 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2019-07-19 01:44:39,740 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,741 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2019-07-19 01:44:39,741 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2019-07-19 01:44:39,742 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,742 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2019-07-19 01:44:39,743 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2019-07-19 01:44:39,743 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,743 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2019-07-19 01:44:39,744 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2019-07-19 01:44:39,744 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,745 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2019-07-19 01:44:39,745 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2019-07-19 01:44:39,745 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,746 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2019-07-19 01:44:39,746 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2019-07-19 01:44:39,747 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,747 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2019-07-19 01:44:39,748 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2019-07-19 01:44:39,748 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,748 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2019-07-19 01:44:39,749 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2019-07-19 01:44:39,749 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,750 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2019-07-19 01:44:39,750 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2019-07-19 01:44:39,751 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,751 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2019-07-19 01:44:39,752 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2019-07-19 01:44:39,752 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,752 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2019-07-19 01:44:39,753 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2019-07-19 01:44:39,753 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,754 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2019-07-19 01:44:39,754 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2019-07-19 01:44:39,754 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,755 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2019-07-19 01:44:39,755 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2019-07-19 01:44:39,756 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,756 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2019-07-19 01:44:39,757 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2019-07-19 01:44:39,757 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,757 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2019-07-19 01:44:39,758 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2019-07-19 01:44:39,758 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,759 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2019-07-19 01:44:39,759 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2019-07-19 01:44:39,760 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,760 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2019-07-19 01:44:39,760 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2019-07-19 01:44:39,761 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,761 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2019-07-19 01:44:39,762 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2019-07-19 01:44:39,762 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,763 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2019-07-19 01:44:39,763 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2019-07-19 01:44:39,763 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,764 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2019-07-19 01:44:39,764 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2019-07-19 01:44:39,765 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,765 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2019-07-19 01:44:39,766 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2019-07-19 01:44:39,766 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,766 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2019-07-19 01:44:39,767 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2019-07-19 01:44:39,767 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,768 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2019-07-19 01:44:39,768 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2019-07-19 01:44:39,768 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,769 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2019-07-19 01:44:39,769 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2019-07-19 01:44:39,770 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,770 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2019-07-19 01:44:39,771 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2019-07-19 01:44:39,771 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,772 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2019-07-19 01:44:39,772 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2019-07-19 01:44:39,773 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,773 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2019-07-19 01:44:39,773 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2019-07-19 01:44:39,774 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,775 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2019-07-19 01:44:39,775 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2019-07-19 01:44:39,775 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,776 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2019-07-19 01:44:39,776 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2019-07-19 01:44:39,777 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,777 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2019-07-19 01:44:39,778 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2019-07-19 01:44:39,778 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,778 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2019-07-19 01:44:39,779 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2019-07-19 01:44:39,779 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,780 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2019-07-19 01:44:39,780 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2019-07-19 01:44:39,781 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,781 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2019-07-19 01:44:39,781 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2019-07-19 01:44:39,782 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,782 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2019-07-19 01:44:39,783 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2019-07-19 01:44:39,783 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,784 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2019-07-19 01:44:39,784 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2019-07-19 01:44:39,784 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,785 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2019-07-19 01:44:39,785 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2019-07-19 01:44:39,786 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,786 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2019-07-19 01:44:39,786 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2019-07-19 01:44:39,787 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,787 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2019-07-19 01:44:39,788 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2019-07-19 01:44:39,788 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,789 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2019-07-19 01:44:39,789 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2019-07-19 01:44:39,790 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,790 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2019-07-19 01:44:39,790 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2019-07-19 01:44:39,791 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,791 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2019-07-19 01:44:39,792 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2019-07-19 01:44:39,792 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,792 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2019-07-19 01:44:39,793 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2019-07-19 01:44:39,793 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,794 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2019-07-19 01:44:39,794 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2019-07-19 01:44:39,795 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,795 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2019-07-19 01:44:39,795 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2019-07-19 01:44:39,796 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,796 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2019-07-19 01:44:39,797 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2019-07-19 01:44:39,797 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,798 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2019-07-19 01:44:39,798 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2019-07-19 01:44:39,798 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,799 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2019-07-19 01:44:39,799 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2019-07-19 01:44:39,800 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,800 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2019-07-19 01:44:39,801 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2019-07-19 01:44:39,801 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,801 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2019-07-19 01:44:39,802 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2019-07-19 01:44:39,802 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,803 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2019-07-19 01:44:39,803 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2019-07-19 01:44:39,804 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,804 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2019-07-19 01:44:39,804 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2019-07-19 01:44:39,805 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,805 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2019-07-19 01:44:39,806 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2019-07-19 01:44:39,806 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,806 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2019-07-19 01:44:39,807 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2019-07-19 01:44:39,807 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,808 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2019-07-19 01:44:39,808 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2019-07-19 01:44:39,809 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,809 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2019-07-19 01:44:39,810 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2019-07-19 01:44:39,810 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,810 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2019-07-19 01:44:39,811 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2019-07-19 01:44:39,811 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,812 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2019-07-19 01:44:39,812 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2019-07-19 01:44:39,812 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,813 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2019-07-19 01:44:39,813 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2019-07-19 01:44:39,814 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,814 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2019-07-19 01:44:39,815 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2019-07-19 01:44:39,815 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,815 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2019-07-19 01:44:39,816 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2019-07-19 01:44:39,816 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,817 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2019-07-19 01:44:39,817 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2019-07-19 01:44:39,818 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,818 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2019-07-19 01:44:39,818 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2019-07-19 01:44:39,819 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,819 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2019-07-19 01:44:39,820 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2019-07-19 01:44:39,820 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,821 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2019-07-19 01:44:39,821 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2019-07-19 01:44:39,821 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,822 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2019-07-19 01:44:39,822 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2019-07-19 01:44:39,823 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,823 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2019-07-19 01:44:39,824 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2019-07-19 01:44:39,824 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,824 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2019-07-19 01:44:39,825 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2019-07-19 01:44:39,825 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,826 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2019-07-19 01:44:39,826 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2019-07-19 01:44:39,826 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,827 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2019-07-19 01:44:39,827 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2019-07-19 01:44:39,828 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,828 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2019-07-19 01:44:39,829 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2019-07-19 01:44:39,829 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,830 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2019-07-19 01:44:39,830 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2019-07-19 01:44:39,830 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,831 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2019-07-19 01:44:39,831 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2019-07-19 01:44:39,831 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,831 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2019-07-19 01:44:39,831 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2019-07-19 01:44:39,832 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,832 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2019-07-19 01:44:39,832 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2019-07-19 01:44:39,832 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,832 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2019-07-19 01:44:39,833 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2019-07-19 01:44:39,833 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,833 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2019-07-19 01:44:39,833 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2019-07-19 01:44:39,833 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,834 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2019-07-19 01:44:39,834 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2019-07-19 01:44:39,834 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,834 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2019-07-19 01:44:39,835 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2019-07-19 01:44:39,835 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,835 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2019-07-19 01:44:39,835 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2019-07-19 01:44:39,835 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,836 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2019-07-19 01:44:39,836 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2019-07-19 01:44:39,836 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,836 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2019-07-19 01:44:39,836 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2019-07-19 01:44:39,837 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,837 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2019-07-19 01:44:39,837 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2019-07-19 01:44:39,837 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,837 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2019-07-19 01:44:39,838 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2019-07-19 01:44:39,838 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,838 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2019-07-19 01:44:39,838 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2019-07-19 01:44:39,839 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,839 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2019-07-19 01:44:39,839 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2019-07-19 01:44:39,839 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,839 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2019-07-19 01:44:39,840 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2019-07-19 01:44:39,840 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,840 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2019-07-19 01:44:39,840 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2019-07-19 01:44:39,840 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,841 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2019-07-19 01:44:39,841 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2019-07-19 01:44:39,841 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,841 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2019-07-19 01:44:39,842 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2019-07-19 01:44:39,842 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,842 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2019-07-19 01:44:39,842 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2019-07-19 01:44:39,842 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,843 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2019-07-19 01:44:39,843 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2019-07-19 01:44:39,843 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,843 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2019-07-19 01:44:39,843 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2019-07-19 01:44:39,844 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,844 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2019-07-19 01:44:39,844 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2019-07-19 01:44:39,844 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,844 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2019-07-19 01:44:39,845 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2019-07-19 01:44:39,845 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,845 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2019-07-19 01:44:39,845 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2019-07-19 01:44:39,846 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,846 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2019-07-19 01:44:39,846 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2019-07-19 01:44:39,846 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,846 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2019-07-19 01:44:39,847 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2019-07-19 01:44:39,847 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,847 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2019-07-19 01:44:39,847 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2019-07-19 01:44:39,847 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2019-07-19 01:44:39,848 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2019-07-19 01:44:39,848 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2019-07-19 01:44:39,848 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2019-07-19 01:44:39,849 [TRACE] [doorpi.action.handler] [LRN0WE] try to fire action WebServerShutdownAction with args () and kwargs {} 2019-07-19 01:44:39,849 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2019-07-19 01:44:39,849 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2019-07-19 01:44:39,850 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2019-07-19 01:44:39,850 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,851 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2019-07-19 01:44:39,851 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2019-07-19 01:44:39,851 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2019-07-19 01:44:39,851 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,852 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2019-07-19 01:44:39,852 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2019-07-19 01:44:39,852 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,852 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2019-07-19 01:44:39,853 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2019-07-19 01:44:39,853 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,853 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2019-07-19 01:44:39,853 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2019-07-19 01:44:39,853 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,854 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2019-07-19 01:44:39,854 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2019-07-19 01:44:39,854 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,854 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2019-07-19 01:44:39,854 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2019-07-19 01:44:39,855 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,855 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2019-07-19 01:44:39,855 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2019-07-19 01:44:39,855 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2019-07-19 01:44:39,856 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2019-07-19 01:44:39,856 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2019-07-19 01:44:39,856 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2019-07-19 01:44:39,861 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2019-07-19 01:44:39,861 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2019-07-19 01:44:39,862 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2019-07-19 01:44:39,863 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2019-07-19 01:44:39,863 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2019-07-19 01:44:39,863 [TRACE] [doorpi.action.handler] [DB0N2H] finished fire_event for event_name OnWebServerStart 2019-07-19 01:44:39,864 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2019-07-19 01:44:39,864 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2019-07-19 01:44:39,864 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2019-07-19 01:44:39,865 [TRACE] [doorpi.action.handler] [LRN0WE] try to fire action KeyboardDestroyAction with args () and kwargs {} 2019-07-19 01:44:39,865 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2019-07-19 01:44:39,865 [DEBUG] [doorpi.keyboard.from_gpio] destroy 2019-07-19 01:44:39,866 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_gpio and force_unregister is True 2019-07-19 01:44:39,866 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,866 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_11 - remove event too 2019-07-19 01:44:39,867 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_11 2019-07-19 01:44:39,867 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,867 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.11 - remove event too 2019-07-19 01:44:39,868 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.11 2019-07-19 01:44:39,868 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,868 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2019-07-19 01:44:39,868 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed 2019-07-19 01:44:39,869 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,869 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2019-07-19 01:44:39,869 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp 2019-07-19 01:44:39,870 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,870 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2019-07-19 01:44:39,870 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown 2019-07-19 01:44:39,870 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,871 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.11 - remove event too 2019-07-19 01:44:39,871 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.11 2019-07-19 01:44:39,871 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,872 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.11 - remove event too 2019-07-19 01:44:39,872 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.11 2019-07-19 01:44:39,872 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,872 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_11 - remove event too 2019-07-19 01:44:39,873 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_11 2019-07-19 01:44:39,873 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2019-07-19 01:44:39,873 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_11 - remove event too 2019-07-19 01:44:39,874 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_11 2019-07-19 01:44:39,874 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed 2019-07-19 01:44:39,874 [TRACE] [doorpi.action.handler] [LRN0WE] try to fire action > with args () and kwargs {} 2019-07-19 01:44:39,875 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2019-07-19 01:44:39,875 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2019-07-19 01:44:39,876 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x74737590 [0x238ff18]) 2019-07-19 01:44:39,876 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x21c076c 2019-07-19 01:44:39,877 [DEBUG] [doorpi.action.handler] [MJU7FP] fire for event OnSipPhoneDestroy this actions [, , ] 2019-07-19 01:44:39,877 [TRACE] [doorpi.action.handler] [MJU7FP] try to fire action > with args () and kwargs {} 2019-07-19 01:44:39,877 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2019-07-19 01:44:39,878 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2019-07-19 01:44:39,878 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2019-07-19 01:44:39,878 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,879 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2019-07-19 01:44:39,879 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2019-07-19 01:44:39,879 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,880 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2019-07-19 01:44:39,880 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2019-07-19 01:44:39,880 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,880 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2019-07-19 01:44:39,881 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2019-07-19 01:44:39,881 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,881 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2019-07-19 01:44:39,882 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2019-07-19 01:44:39,882 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,882 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2019-07-19 01:44:39,882 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2019-07-19 01:44:39,883 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,883 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2019-07-19 01:44:39,883 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2019-07-19 01:44:39,884 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,884 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2019-07-19 01:44:39,884 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2019-07-19 01:44:39,884 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,885 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2019-07-19 01:44:39,885 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2019-07-19 01:44:39,885 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,886 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2019-07-19 01:44:39,886 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2019-07-19 01:44:39,886 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,886 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2019-07-19 01:44:39,887 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2019-07-19 01:44:39,887 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,887 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2019-07-19 01:44:39,888 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2019-07-19 01:44:39,888 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,888 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2019-07-19 01:44:39,888 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2019-07-19 01:44:39,889 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,889 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2019-07-19 01:44:39,889 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2019-07-19 01:44:39,889 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,890 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2019-07-19 01:44:39,890 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2019-07-19 01:44:39,890 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,890 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2019-07-19 01:44:39,891 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2019-07-19 01:44:39,891 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,891 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2019-07-19 01:44:39,891 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2019-07-19 01:44:39,891 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,892 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2019-07-19 01:44:39,892 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2019-07-19 01:44:39,892 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,892 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2019-07-19 01:44:39,893 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2019-07-19 01:44:39,893 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,893 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2019-07-19 01:44:39,893 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2019-07-19 01:44:39,893 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,894 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2019-07-19 01:44:39,894 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2019-07-19 01:44:39,894 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2019-07-19 01:44:39,894 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2019-07-19 01:44:39,894 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2019-07-19 01:44:39,895 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2019-07-19 01:44:39,895 [TRACE] [doorpi.action.handler] [MJU7FP] try to fire action > with args () and kwargs {} 2019-07-19 01:44:39,895 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2019-07-19 01:44:39,896 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2019-07-19 01:44:39,896 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2019-07-19 01:44:39,897 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:39,897 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:39,897 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2019-07-19 01:44:39,898 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:39,898 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2019-07-19 01:44:39,898 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2019-07-19 01:44:39,898 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2019-07-19 01:44:39,899 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2019-07-19 01:44:39,899 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2019-07-19 01:44:39,899 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2019-07-19 01:44:39,900 [TRACE] [doorpi.action.handler] [MJU7FP] try to fire action > with args () and kwargs {} 2019-07-19 01:44:39,900 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2019-07-19 01:44:39,900 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2019-07-19 01:44:39,900 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:39,901 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2019-07-19 01:44:39,901 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2019-07-19 01:44:39,901 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:39,901 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2019-07-19 01:44:39,902 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2019-07-19 01:44:39,902 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2019-07-19 01:44:39,902 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2019-07-19 01:44:39,902 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2019-07-19 01:44:39,902 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2019-07-19 01:44:39,903 [TRACE] [doorpi.action.handler] [MJU7FP] finished fire_event for event_name OnSipPhoneDestroy 2019-07-19 01:44:39,903 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2019-07-19 01:44:39,903 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,903 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2019-07-19 01:44:39,904 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2019-07-19 01:44:39,904 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,904 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2019-07-19 01:44:39,904 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2019-07-19 01:44:39,905 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,905 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2019-07-19 01:44:39,905 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2019-07-19 01:44:39,905 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,905 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2019-07-19 01:44:39,906 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2019-07-19 01:44:39,906 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,906 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2019-07-19 01:44:39,906 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2019-07-19 01:44:39,906 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,907 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2019-07-19 01:44:39,907 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2019-07-19 01:44:39,907 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,907 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2019-07-19 01:44:39,908 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2019-07-19 01:44:39,908 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,908 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2019-07-19 01:44:39,908 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2019-07-19 01:44:39,908 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,909 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2019-07-19 01:44:39,909 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2019-07-19 01:44:39,909 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,909 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2019-07-19 01:44:39,910 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2019-07-19 01:44:39,910 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,910 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2019-07-19 01:44:39,910 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2019-07-19 01:44:39,910 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2019-07-19 01:44:39,911 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2019-07-19 01:44:39,911 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2019-07-19 01:44:39,911 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2019-07-19 01:44:39,911 [TRACE] [doorpi.action.handler] [LRN0WE] finished fire_event for event_name OnShutdown 2019-07-19 01:44:39,912 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2019-07-19 01:44:39,913 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi Traceback (most recent call last): File "/usr/local/bin/doorpi_cli", line 10, in sys.exit(entry_point()) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 169, in entry_point raise SystemExit(main_as_application(sys.argv)) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 155, in main_as_application finally: doorpi.DoorPi().destroy() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 210, in destroy time.sleep(waiting_between_checks) KeyboardInterrupt