pi@raspberrypi:~ $ sudo doorpi_cli --trace 2016-08-16 20:24:02,707 [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 2016-08-16 20:24:02,707 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-08-16 20:24:02,708 [DEBUG] [doorpi.doorpi] run 2016-08-16 20:24:02,708 [DEBUG] [doorpi.doorpi] prepare 2016-08-16 20:24:02,708 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-08-16 20:24:02,711 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-08-16 20:24:02,715 [DEBUG] [doorpi.conf.config_object] __init__ 2016-08-16 20:24:02,716 [TRACE] [doorpi.conf.config_object] get_from_config 2016-08-16 20:24:02,719 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-08-16 20:24:02,720 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-08-16 20:24:02,721 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-08-16 20:24:02,723 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-08-16 20:24:02,724 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-08-16 20:24:02,724 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-08-16 20:24:02,725 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-08-16 20:24:02,725 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-08-16 20:24:02,726 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-08-16 20:24:02,726 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-08-16 20:24:02,727 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-08-16 20:24:02,727 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-08-16 20:24:02,727 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-08-16 20:24:02,728 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-08-16 20:24:02,728 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-08-16 20:24:02,728 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-08-16 20:24:02,730 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-08-16 20:24:02,731 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-08-16 20:24:02,733 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-08-16 20:24:02,733 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-08-16 20:24:02,733 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-08-16 20:24:02,734 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-08-16 20:24:02,736 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2016-08-16 20:24:02,737 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-08-16 20:24:02,737 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-08-16 20:24:02,738 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-08-16 20:24:02,738 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-08-16 20:24:02,738 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-08-16 20:24:02,739 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-08-16 20:24:02,740 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-08-16 20:24:02,740 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-08-16 20:24:02,742 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-08-16 20:24:02,742 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-08-16 20:24:02,743 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-08-16 20:24:02,744 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-08-16 20:24:02,745 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-08-16 20:24:02,745 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-08-16 20:24:02,746 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-08-16 20:24:02,747 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-08-16 20:24:02,747 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrator'] 2016-08-16 20:24:02,748 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-08-16 20:24:02,748 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-08-16 20:24:02,749 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-08-16 20:24:02,749 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'Group', 'ReadPermission', 'AdminNumbers', 'keyboards', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-08-16 20:24:02,750 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-08-16 20:24:02,751 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section ReadPermission (default: []) returns ['installer'] 2016-08-16 20:24:02,751 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-08-16 20:24:02,752 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-08-16 20:24:02,753 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-08-16 20:24:02,755 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,755 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-08-16 20:24:02,756 [DEBUG] [doorpi.action.handler] [M4J072] fire for event OnWebServerStart this actions [] 2016-08-16 20:24:02,756 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,757 [TRACE] [doorpi.action.handler] [M4J072] try to fire action WebServerStartupAction with args () and kwargs {} 2016-08-16 20:24:02,757 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,758 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2016-08-16 20:24:02,758 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,759 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,759 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,760 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,760 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,761 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,761 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,762 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,762 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,763 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,763 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,764 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,764 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-08-16 20:24:02,765 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-08-16 20:24:02,765 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-08-16 20:24:02,766 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-08-16 20:24:02,766 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2016-08-16 20:24:02,767 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '0'] 2016-08-16 20:24:02,767 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['0', '2', '7'] 2016-08-16 20:24:02,768 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2016-08-16 20:24:02,768 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-08-16 20:24:02,769 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-08-16 20:24:02,770 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2016-08-16 20:24:02,797 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0'], output_pins = ['0', '2', '7'], polarity = 0) 2016-08-16 20:24:02,840 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-08-16 20:24:02,840 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2016-08-16 20:24:02,840 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,841 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,841 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,841 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,842 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,842 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-08-16 20:24:02,842 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,842 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,843 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,843 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,843 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,843 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-08-16 20:24:02,843 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,844 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,844 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,844 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,844 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,844 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-08-16 20:24:02,845 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-08-16 20:24:02,845 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,845 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,845 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,846 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.0 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,846 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-08-16 20:24:02,846 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-08-16 20:24:02,846 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,846 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,847 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,847 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.0 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,847 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-08-16 20:24:02,847 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-08-16 20:24:02,848 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,848 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,848 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2016-08-16 20:24:02,848 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.0 and registered source doorpi.keyboard.from_piface 2016-08-16 20:24:02,857 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-08-16 20:24:02,858 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['0', '2', '7'] 2016-08-16 20:24:02,858 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_OutputPins (default: ) returns Tueroeffner 2016-08-16 20:24:02,859 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_OutputPins (default: ) returns Licht 2016-08-16 20:24:02,859 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_OutputPins (default: ) returns blinking_led 2016-08-16 20:24:02,861 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-08-16 20:24:02,862 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-08-16 20:24:02,864 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-08-16 20:24:02,865 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-08-16 20:24:02,890 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-08-16 20:24:02,892 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-08-16 20:24:02,894 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-08-16 20:24:02,904 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-08-16 20:24:02,908 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-08-16 20:24:02,909 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-08-16 20:24:02,910 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-08-16 20:24:02,911 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-08-16 20:24:02,912 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-08-16 20:24:02,914 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-08-16 20:24:02,915 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-08-16 20:24:02,916 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-08-16 20:24:02,916 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,917 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-08-16 20:24:02,917 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,917 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,917 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,918 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,918 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,918 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,918 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,918 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,919 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,919 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,919 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,919 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,920 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,920 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,920 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,920 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,920 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,921 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,921 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,921 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,922 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,922 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-08-16 20:24:02,922 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-08-16 20:24:02,922 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-08-16 20:24:02,923 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-08-16 20:24:02,923 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,923 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-08-16 20:24:02,923 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,924 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,924 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,924 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,924 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,924 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,925 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,925 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,925 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,925 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,926 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,926 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,926 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,926 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,926 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,927 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,927 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,927 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,927 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,928 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,928 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,928 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,928 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,929 [WARNING] [root] section DTMF not found in configfile 2016-08-16 20:24:02,929 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-08-16 20:24:02,929 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,929 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-08-16 20:24:02,929 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,930 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,930 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,930 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,930 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,931 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,931 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,931 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,931 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,932 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,932 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,932 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,932 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,932 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,933 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,933 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,933 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,933 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,934 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-08-16 20:24:02,935 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-08-16 20:24:02,935 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x75ab0c98 2016-08-16 20:24:02,936 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-08-16 20:24:02,936 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-08-16 20:24:02,937 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x75ab0c98 2016-08-16 20:24:02,937 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74524e40, "(null)", "(null)") 2016-08-16 20:24:02,937 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-08-16 20:24:02,937 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2016-08-16 20:24:02,938 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-08-16 20:24:02,938 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-08-16 20:24:02,954 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-08-16 20:24:02,991 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-08-16 20:24:02,995 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-08-16 20:24:02,997 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-08-16 20:24:02,997 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-08-16 20:24:02,998 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2016-08-16 20:24:02,998 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-08-16 20:24:02,998 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-08-16 20:24:02,999 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-08-16 20:24:02,999 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-08-16 20:24:02,999 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-08-16 20:24:03,000 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x5b58b8] registered on core [0x5b5b88] 2016-08-16 20:24:03,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x5b58b8, 1, "Starting up") 2016-08-16 20:24:03,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-08-16 20:24:03,000 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x5b58b8] notifying [global_state_changed] 2016-08-16 20:24:03,001 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-08-16 20:24:03,001 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-08-16 20:24:03,001 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-08-16 20:24:03,001 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-08-16 20:24:03,002 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2016-08-16 20:24:03,002 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-08-16 20:24:03,003 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-08-16 20:24:03,003 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-08-16 20:24:03,003 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-08-16 20:24:03,004 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-08-16 20:24:03,004 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-08-16 20:24:03,004 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-08-16 20:24:03,004 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-08-16 20:24:03,005 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-08-16 20:24:03,005 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-08-16 20:24:03,005 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-08-16 20:24:03,005 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-08-16 20:24:03,006 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-08-16 20:24:03,006 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-08-16 20:24:03,006 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-08-16 20:24:03,006 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-08-16 20:24:03,007 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-08-16 20:24:03,007 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-08-16 20:24:03,007 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-08-16 20:24:03,007 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-08-16 20:24:03,007 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-08-16 20:24:03,008 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-08-16 20:24:03,008 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-08-16 20:24:03,008 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-08-16 20:24:03,008 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-08-16 20:24:03,009 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-08-16 20:24:03,009 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-08-16 20:24:03,009 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-08-16 20:24:03,009 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-08-16 20:24:03,009 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-08-16 20:24:03,010 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-08-16 20:24:03,010 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-08-16 20:24:03,010 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-08-16 20:24:03,010 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-08-16 20:24:03,011 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-08-16 20:24:03,011 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-08-16 20:24:03,011 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-08-16 20:24:03,012 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-08-16 20:24:03,012 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-08-16 20:24:03,012 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-08-16 20:24:03,012 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-08-16 20:24:03,013 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-08-16 20:24:03,013 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-08-16 20:24:03,013 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-08-16 20:24:03,013 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-08-16 20:24:03,013 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-08-16 20:24:03,014 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-08-16 20:24:03,014 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-08-16 20:24:03,014 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-08-16 20:24:03,014 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-08-16 20:24:03,015 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-08-16 20:24:03,015 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-08-16 20:24:03,015 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-08-16 20:24:03,015 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-08-16 20:24:03,015 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-08-16 20:24:03,016 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-08-16 20:24:03,016 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-08-16 20:24:03,016 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-08-16 20:24:03,016 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-08-16 20:24:03,017 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-08-16 20:24:03,017 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-08-16 20:24:03,017 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-08-16 20:24:03,017 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-08-16 20:24:03,018 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-08-16 20:24:03,018 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-08-16 20:24:03,018 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-08-16 20:24:03,018 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-08-16 20:24:03,018 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-08-16 20:24:03,019 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-08-16 20:24:03,019 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-08-16 20:24:03,019 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-08-16 20:24:03,019 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-08-16 20:24:03,020 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-08-16 20:24:03,020 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-08-16 20:24:03,020 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-08-16 20:24:03,020 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-08-16 20:24:03,020 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-08-16 20:24:03,021 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-08-16 20:24:03,021 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-08-16 20:24:03,021 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,022 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,022 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x5b58b8, 2, "(null)") 2016-08-16 20:24:03,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-08-16 20:24:03,023 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x5b58b8] notifying [configuring_status] 2016-08-16 20:24:03,023 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-08-16 20:24:03,023 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-08-16 20:24:03,023 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-08-16 20:24:03,023 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-08-16 20:24:03,024 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-08-16 20:24:03,024 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-08-16 20:24:03,024 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-08-16 20:24:03,024 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-08-16 20:24:03,025 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-08-16 20:24:03,025 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-08-16 20:24:03,025 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,025 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,026 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,026 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,026 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-08-16 20:24:03,026 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-08-16 20:24:03,026 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-08-16 20:24:03,027 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,027 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,027 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-08-16 20:24:03,027 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-08-16 20:24:03,028 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,028 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-08-16 20:24:03,028 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x6176e8] on [sip:0.0.0.0:5060;transport=UDP] 2016-08-16 20:24:03,028 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x6178f8] on [sip:0.0.0.0:5060;transport=TCP] 2016-08-16 20:24:03,029 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x617ad0] on [sip:0.0.0.0:-1;transport=TLS] 2016-08-16 20:24:03,029 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 40955 2016-08-16 20:24:03,029 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,030 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x5b58b8, 2, "Ready") 2016-08-16 20:24:03,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-08-16 20:24:03,032 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x5b58b8] notifying [global_state_changed] 2016-08-16 20:24:03,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x75ab0c98 2016-08-16 20:24:03,033 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-08-16 20:24:03,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x75ab0c98 [0x5b58b8], "DoorPi ") 2016-08-16 20:24:03,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-08-16 20:24:03,034 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-08-16 20:24:03,035 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-08-16 20:24:03,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x75ab0c98 [0x5b58b8], 2) 2016-08-16 20:24:03,036 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-08-16 20:24:03,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-08-16 20:24:03,037 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-08-16 20:24:03,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x75ab0c98 [0x5b58b8], 0x2e0614 [0]) 2016-08-16 20:24:03,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-08-16 20:24:03,037 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-08-16 20:24:03,038 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-08-16 20:24:03,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-08-16 20:24:03,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x75ab0cb0 2016-08-16 20:24:03,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x75ab0c98 [0x5b58b8], 0x75ab0cb0 [0x75ab0cb8]) 2016-08-16 20:24:03,039 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x6176e8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-08-16 20:24:03,039 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x6178f8] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-08-16 20:24:03,039 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x617ad0] on [sip:0.0.0.0:40955;transport=TLS] destroyed 2016-08-16 20:24:03,039 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-08-16 20:24:03,040 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x61a140] on [sip:0.0.0.0:5060;transport=UDP] 2016-08-16 20:24:03,040 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x625718] on [sip:0.0.0.0:5060;transport=TCP] 2016-08-16 20:24:03,040 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x6253e0] on [sip:0.0.0.0:-1;transport=TLS] 2016-08-16 20:24:03,041 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 38391 2016-08-16 20:24:03,041 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,041 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-08-16 20:24:03,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-08-16 20:24:03,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x75ab0cb0) 2016-08-16 20:24:03,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-08-16 20:24:03,042 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-08-16 20:24:03,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x75ab0c98 [0x5b58b8], 0x2e0614 [0]) 2016-08-16 20:24:03,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-08-16 20:24:03,043 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-08-16 20:24:03,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x75ab0c98 [0x5b58b8], "") 2016-08-16 20:24:03,044 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-08-16 20:24:03,044 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-08-16 20:24:03,044 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-08-16 20:24:03,045 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-08-16 20:24:03,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-08-16 20:24:03,045 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-08-16 20:24:03,046 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-08-16 20:24:03,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x75ab0c98 [0x5b58b8], 0) 2016-08-16 20:24:03,046 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-08-16 20:24:03,046 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-08-16 20:24:03,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-08-16 20:24:03,047 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-08-16 20:24:03,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x75ab0c98 [0x5b58b8], 120) 2016-08-16 20:24:03,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-08-16 20:24:03,048 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-08-16 20:24:03,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x75ab0c98 [0x5b58b8], 15) 2016-08-16 20:24:03,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-08-16 20:24:03,049 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2016-08-16 20:24:03,050 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-08-16 20:24:03,051 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-08-16 20:24:03,052 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-08-16 20:24:03,052 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-08-16 20:24:03,053 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-08-16 20:24:03,053 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-08-16 20:24:03,053 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-08-16 20:24:03,053 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-08-16 20:24:03,054 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-08-16 20:24:03,054 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-08-16 20:24:03,054 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-08-16 20:24:03,054 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-08-16 20:24:03,055 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-08-16 20:24:03,055 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-08-16 20:24:03,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x75ab0c98 [0x5b58b8], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-08-16 20:24:03,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-08-16 20:24:03,056 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-08-16 20:24:03,057 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2016-08-16 20:24:03,057 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-08-16 20:24:03,057 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,058 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-08-16 20:24:03,058 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,058 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,058 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,058 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,059 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,059 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-08-16 20:24:03,059 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-08-16 20:24:03,059 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-08-16 20:24:03,060 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-08-16 20:24:03,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x7452d878 2016-08-16 20:24:03,061 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-08-16 20:24:03,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7452c710 2016-08-16 20:24:03,062 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-08-16 20:24:03,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x75ab0c98 [0x5b58b8], "ALSA: default device") 2016-08-16 20:24:03,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-08-16 20:24:03,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7452c6e0 2016-08-16 20:24:03,063 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-08-16 20:24:03,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x75ab0c98 [0x5b58b8], "ALSA: default device") 2016-08-16 20:24:03,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-08-16 20:24:03,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x7452d760 2016-08-16 20:24:03,064 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2016-08-16 20:24:03,065 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-08-16 20:24:03,065 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-08-16 20:24:03,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x7452d760 2016-08-16 20:24:03,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75ab0c98 [0x5b58b8], "ALSA: default device") 2016-08-16 20:24:03,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-08-16 20:24:03,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75ab0c98 [0x5b58b8], "ALSA: default device") 2016-08-16 20:24:03,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-08-16 20:24:03,067 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-08-16 20:24:03,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75ab0c98 [0x5b58b8], "ALSA: bcm2835 ALSA") 2016-08-16 20:24:03,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-08-16 20:24:03,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75ab0c98 [0x5b58b8], "ALSA: bcm2835 ALSA") 2016-08-16 20:24:03,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-08-16 20:24:03,068 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-08-16 20:24:03,068 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-08-16 20:24:03,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x7452c7d0 2016-08-16 20:24:03,069 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-08-16 20:24:03,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x7452c7a0 2016-08-16 20:24:03,069 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-08-16 20:24:03,070 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-08-16 20:24:03,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe058) 2016-08-16 20:24:03,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd3450 2016-08-16 20:24:03,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd3450 2016-08-16 20:24:03,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd3450 2016-08-16 20:24:03,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe0c8) 2016-08-16 20:24:03,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd3440 2016-08-16 20:24:03,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd3440 2016-08-16 20:24:03,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd3440 2016-08-16 20:24:03,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe130) 2016-08-16 20:24:03,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd3460 2016-08-16 20:24:03,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd3460 2016-08-16 20:24:03,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd3460 2016-08-16 20:24:03,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe198) 2016-08-16 20:24:03,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd3470 2016-08-16 20:24:03,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd3470 2016-08-16 20:24:03,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd3470 2016-08-16 20:24:03,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe1f0) 2016-08-16 20:24:03,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd3480 2016-08-16 20:24:03,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd3480 2016-08-16 20:24:03,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd3480 2016-08-16 20:24:03,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe248) 2016-08-16 20:24:03,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd3490 2016-08-16 20:24:03,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd3490 2016-08-16 20:24:03,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd3490 2016-08-16 20:24:03,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fe2a0) 2016-08-16 20:24:03,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd34a0 2016-08-16 20:24:03,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd34a0 2016-08-16 20:24:03,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd34a0 2016-08-16 20:24:03,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x6173f8) 2016-08-16 20:24:03,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd34b0 2016-08-16 20:24:03,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd34b0 2016-08-16 20:24:03,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd34b0 2016-08-16 20:24:03,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x617460) 2016-08-16 20:24:03,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd34c0 2016-08-16 20:24:03,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd34c0 2016-08-16 20:24:03,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd34c0 2016-08-16 20:24:03,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x6174b8) 2016-08-16 20:24:03,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd34d0 2016-08-16 20:24:03,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd34d0 2016-08-16 20:24:03,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd34d0 2016-08-16 20:24:03,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x7452d8f0 2016-08-16 20:24:03,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3450 [0x5fe058]) 2016-08-16 20:24:03,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3450 [0x5fe058]) 2016-08-16 20:24:03,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,085 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-08-16 20:24:03,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd3450 [0x5fe058], 0x2e0614 [0]) 2016-08-16 20:24:03,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3440 [0x5fe0c8]) 2016-08-16 20:24:03,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528a60 2016-08-16 20:24:03,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3440 [0x5fe0c8]) 2016-08-16 20:24:03,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528a60 2016-08-16 20:24:03,086 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-08-16 20:24:03,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd3440 [0x5fe0c8], 0x2e0614 [0]) 2016-08-16 20:24:03,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3460 [0x5fe130]) 2016-08-16 20:24:03,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3460 [0x5fe130]) 2016-08-16 20:24:03,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,088 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-08-16 20:24:03,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd3460 [0x5fe130], 0x2e0614 [0]) 2016-08-16 20:24:03,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3470 [0x5fe198]) 2016-08-16 20:24:03,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528a60 2016-08-16 20:24:03,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3470 [0x5fe198]) 2016-08-16 20:24:03,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528a60 2016-08-16 20:24:03,090 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-08-16 20:24:03,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd3470 [0x5fe198], 0x2e0620 [1]) 2016-08-16 20:24:03,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3480 [0x5fe1f0]) 2016-08-16 20:24:03,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3480 [0x5fe1f0]) 2016-08-16 20:24:03,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,091 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-08-16 20:24:03,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd3480 [0x5fe1f0], 0x2e0620 [1]) 2016-08-16 20:24:03,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3490 [0x5fe248]) 2016-08-16 20:24:03,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd3490 [0x5fe248]) 2016-08-16 20:24:03,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,093 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-08-16 20:24:03,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd3490 [0x5fe248], 0x2e0614 [0]) 2016-08-16 20:24:03,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34a0 [0x5fe2a0]) 2016-08-16 20:24:03,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34a0 [0x5fe2a0]) 2016-08-16 20:24:03,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528860 2016-08-16 20:24:03,095 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-08-16 20:24:03,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd34a0 [0x5fe2a0], 0x2e0614 [0]) 2016-08-16 20:24:03,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34b0 [0x6173f8]) 2016-08-16 20:24:03,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528a60 2016-08-16 20:24:03,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34b0 [0x6173f8]) 2016-08-16 20:24:03,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74528a60 2016-08-16 20:24:03,096 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-08-16 20:24:03,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd34b0 [0x6173f8], 0x2e0614 [0]) 2016-08-16 20:24:03,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34c0 [0x617460]) 2016-08-16 20:24:03,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34c0 [0x617460]) 2016-08-16 20:24:03,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,098 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-08-16 20:24:03,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd34c0 [0x617460], 0x2e0614 [0]) 2016-08-16 20:24:03,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34d0 [0x6174b8]) 2016-08-16 20:24:03,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34d0 [0x6174b8]) 2016-08-16 20:24:03,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,100 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-08-16 20:24:03,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd34d0 [0x6174b8], 0x2e0614 [0]) 2016-08-16 20:24:03,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7452d878 2016-08-16 20:24:03,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7452d878 2016-08-16 20:24:03,102 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2016-08-16 20:24:03,102 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-08-16 20:24:03,102 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-08-16 20:24:03,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7452d878 2016-08-16 20:24:03,103 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2016-08-16 20:24:03,103 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-08-16 20:24:03,103 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-08-16 20:24:03,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7452d878 2016-08-16 20:24:03,104 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2016-08-16 20:24:03,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x7452d878 2016-08-16 20:24:03,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x75ab0c98 [0x5b58b8], 0x2e0620 [1]) 2016-08-16 20:24:03,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-08-16 20:24:03,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x75ab0c98 [0x5b58b8], "V4L2: /dev/video0") 2016-08-16 20:24:03,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-08-16 20:24:03,105 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-08-16 20:24:03,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x75ab0c98 [0x5b58b8], "vga") 2016-08-16 20:24:03,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-08-16 20:24:03,242 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,242 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x7452da20 2016-08-16 20:24:03,242 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2016-08-16 20:24:03,243 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-08-16 20:24:03,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5fdfb8) 2016-08-16 20:24:03,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-08-16 20:24:03,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd34e0 2016-08-16 20:24:03,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-08-16 20:24:03,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd34e0 2016-08-16 20:24:03,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd34e0 2016-08-16 20:24:03,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x7452d8a0 2016-08-16 20:24:03,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34e0 [0x5fdfb8]) 2016-08-16 20:24:03,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-08-16 20:24:03,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd34e0 [0x5fdfb8]) 2016-08-16 20:24:03,246 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75ab0cb0 2016-08-16 20:24:03,246 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-08-16 20:24:03,247 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75ab0c98 [0x5b58b8], 0x76cd34e0 [0x5fdfb8], 0x2e0620 [1]) 2016-08-16 20:24:03,247 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x4748bc 2016-08-16 20:24:03,247 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2016-08-16 20:24:03,248 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 623 2016-08-16 20:24:03,248 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 623) returns ******* 2016-08-16 20:24:03,248 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2016-08-16 20:24:03,248 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-08-16 20:24:03,248 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x75ab0c98 [0x5b58b8]) 2016-08-16 20:24:03,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x624cf0) 2016-08-16 20:24:03,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-08-16 20:24:03,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76cd34f0 2016-08-16 20:24:03,249 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-08-16 20:24:03,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76cd34f0 2016-08-16 20:24:03,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76cd34f0 2016-08-16 20:24:03,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76cd34f0 2016-08-16 20:24:03,250 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-08-16 20:24:03,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-08-16 20:24:03,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x6039e0) 2016-08-16 20:24:03,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-08-16 20:24:03,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76cd3500 2016-08-16 20:24:03,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-08-16 20:24:03,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76cd3500 2016-08-16 20:24:03,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76cd3500 2016-08-16 20:24:03,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76cd3500 2016-08-16 20:24:03,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76cd34f0 [0x624cf0], 0x76cd3500 [0x6039e0]) 2016-08-16 20:24:03,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-08-16 20:24:03,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76cd34f0 [0x624cf0], "sip:192.168.178.1") 2016-08-16 20:24:03,255 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-08-16 20:24:03,255 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76cd34f0 [0x624cf0], 0x2e0620 [1]) 2016-08-16 20:24:03,255 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-08-16 20:24:03,256 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x75ab0c98 [0x5b58b8], 0x76cd34f0 [0x624cf0]) 2016-08-16 20:24:03,256 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x4748bc 2016-08-16 20:24:03,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x75ab0c98 [0x5b58b8], 0x76cd34f0 [0x624cf0]) 2016-08-16 20:24:03,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-08-16 20:24:03,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x75ab0c98 [0x5b58b8], "623", "(null)", "raspberry", "(null)", "(null)", "fritz.box") 2016-08-16 20:24:03,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x603ad8) 2016-08-16 20:24:03,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-08-16 20:24:03,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76cd3510 2016-08-16 20:24:03,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-08-16 20:24:03,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76cd3510 2016-08-16 20:24:03,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76cd3510 2016-08-16 20:24:03,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76cd3510 2016-08-16 20:24:03,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x75ab0c98 [0x5b58b8], 0x76cd3510 [0x603ad8]) 2016-08-16 20:24:03,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-08-16 20:24:03,259 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-08-16 20:24:03,260 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-08-16 20:24:03,260 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-08-16 20:24:03,260 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-08-16 20:24:03,260 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-08-16 20:24:03,261 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-08-16 20:24:03,261 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-08-16 20:24:03,262 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-08-16 20:24:03,262 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_InputPins (default: ) returns call:**623 2016-08-16 20:24:03,263 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-08-16 20:24:03,263 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**623'} was added to new evententry OnKeyPressed_onboardpins.1 2016-08-16 20:24:03,263 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_InputPins (default: ) returns call:**611 2016-08-16 20:24:03,264 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**611'} was added to new evententry OnKeyPressed_onboardpins.0 2016-08-16 20:24:03,264 [WARNING] [root] section DTMF not found in configfile 2016-08-16 20:24:03,264 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-08-16 20:24:03,264 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns blinking_led 2016-08-16 20:24:03,265 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2016-08-16 20:24:03,266 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2016-08-16 20:24:03,266 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'HIGH', 'pin': 'blinking_led'} was added to new evententry OnTimeSecondEvenNumber 2016-08-16 20:24:03,266 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'LOW', 'pin': 'blinking_led'} was added to new evententry OnTimeSecondUnevenNumber 2016-08-16 20:24:03,267 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2016-08-16 20:24:03,268 [DEBUG] [doorpi.action.handler] [Y8B7XN] fire for event OnStartup this actions [] 2016-08-16 20:24:03,268 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2016-08-16 20:24:03,268 [TRACE] [doorpi.action.handler] [Y8B7XN] try to fire action SleepAction with args (1.0,) and kwargs {} 2016-08-16 20:24:03,269 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2016-08-16 20:24:04,276 [TRACE] [doorpi.action.handler] [Y8B7XN] finished fire_event for event_name OnStartup 2016-08-16 20:24:04,277 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2016-08-16 20:24:04,278 [INFO] [doorpi.doorpi] DoorPi started successfully 2016-08-16 20:24:04,278 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2016-08-16 20:24:04,278 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2016-08-16 20:24:04,279 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2016-08-16 20:24:04,279 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2016-08-16 20:24:04,280 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-08-16 20:24:04,280 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.39 2016-08-16 20:24:04,280 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2016-08-16 20:24:04,281 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-08-16 20:24:04,282 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-08-16 20:24:04,282 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-08-16 20:24:04,282 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-08-16 20:24:04,283 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x624cf0] about to register (LinphoneCore version: 3.9.0) 2016-08-16 20:24:04,287 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2016-08-16 20:24:04,288 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8]: starting resolution of 192.168.178.1 2016-08-16 20:24:04,288 [INFO] [doorpi.sipphone.from_linphone] channel 0x627bd8: state RES_IN_PROGRESS 2016-08-16 20:24:04,288 [INFO] [doorpi.sipphone.from_linphone] transaction [0x602f78] channel state changed to [RES_IN_PROGRESS] 2016-08-16 20:24:04,288 [INFO] [doorpi.sipphone.from_linphone] channel 0x627bd8: state RES_DONE 2016-08-16 20:24:04,289 [INFO] [doorpi.sipphone.from_linphone] transaction [0x602f78] channel state changed to [RES_DONE] 2016-08-16 20:24:04,289 [INFO] [doorpi.sipphone.from_linphone] channel 0x627bd8: state CONNECTING 2016-08-16 20:24:04,289 [INFO] [doorpi.sipphone.from_linphone] transaction [0x602f78] channel state changed to [CONNECTING] 2016-08-16 20:24:04,289 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2016-08-16 20:24:04,290 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2016-08-16 20:24:04,290 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.39:5060 2016-08-16 20:24:04,290 [INFO] [doorpi.sipphone.from_linphone] channel 0x627bd8: state READY 2016-08-16 20:24:04,290 [INFO] [doorpi.sipphone.from_linphone] transaction [0x602f78] channel state changed to [READY] 2016-08-16 20:24:04,291 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x602f78], from state [INIT] to [TRYING] 2016-08-16 20:24:04,291 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8]: message sent to [UDP://192.168.178.1:5060], size: [514] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.39:5060;branch=z9hG4bK.a9SKJCY-L;rport From: "DoorPi" ;tag=w8epTAc0J To: "DoorPi" CSeq: 20 REGISTER Call-ID: hoVPYpXggo Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2016-08-16 20:24:04,292 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2016-08-16 20:24:04,292 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x603378] takes ownership of transaction [0x602f78] 2016-08-16 20:24:04,292 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x624cf0] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2016-08-16 20:24:04,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x5b58b8, 0x624cf0, 1, "Registration in progress") 2016-08-16 20:24:04,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-08-16 20:24:04,293 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x5b58b8] notifying [registration_state_changed] 2016-08-16 20:24:04,343 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x5b58b8, 0x1) 2016-08-16 20:24:04,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2016-08-16 20:24:04,344 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x5b58b8] notifying [network_reachable] 2016-08-16 20:24:04,345 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8]: received [366] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.178.39:5060;branch=z9hG4bK.a9SKJCY-L;rport=5060 From: "DoorPi" ;tag=w8epTAc0J To: "DoorPi" ;tag=EB8C112111640734 Call-ID: hoVPYpXggo CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="6B7D650D10B1E4B2" User-Agent: FRITZ!OS Content-Length: 0 2016-08-16 20:24:04,349 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8] [366] bytes parsed 2016-08-16 20:24:04,350 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8]: discovered public ip and port are [192.168.178.39:5060] 2016-08-16 20:24:04,350 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-08-16 20:24:04,350 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x602f78], from state [TRYING] to [COMPLETED] 2016-08-16 20:24:04,351 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=623, realm= 2016-08-16 20:24:04,351 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [623] realm [fritz.box] 2016-08-16 20:24:04,351 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x60b9d8], from state [INIT] to [TRYING] 2016-08-16 20:24:04,352 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8]: message sent to [UDP://192.168.178.1:5060], size: [669] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.39:5060;branch=z9hG4bK.e~iV-4V98;rport From: "DoorPi" ;tag=w8epTAc0J To: "DoorPi" CSeq: 21 REGISTER Call-ID: hoVPYpXggo Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="6B7D650D10B1E4B2", username="623", uri="sip:192.168.178.1", response="477a8f3cc1e1a90323109ebbc34c0fe0" 2016-08-16 20:24:04,353 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2016-08-16 20:24:04,353 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2016-08-16 20:24:04,404 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8]: received [768] new bytes from [UDP://192.168.178.1:5060]: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.178.39:5060;branch=z9hG4bK.e~iV-4V98;rport=5060 From: "DoorPi" ;tag=w8epTAc0J To: "DoorPi" ;tag=6504D70030CFA5BD Call-ID: hoVPYpXggo CSeq: 21 REGISTER Contact: ;expires=88 Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7490 (UI) 113.06.60 (Jun 23 2016) Supported: 100rel,replaces,timer Allow-Events: telephone-event,refer,reg Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH Accept: application/sdp, multipart/mixed Accept-Encoding: identity Content-Length: 0 2016-08-16 20:24:04,411 [INFO] [doorpi.sipphone.from_linphone] channel [0x627bd8] [768] bytes parsed 2016-08-16 20:24:04,411 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2016-08-16 20:24:04,412 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x60b9d8], from state [TRYING] to [COMPLETED] 2016-08-16 20:24:04,412 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x603378]: has no contact for request [0x60f3c0]. 2016-08-16 20:24:04,412 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2016-08-16 20:24:04,413 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.178.1] 2016-08-16 20:24:04,413 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x624cf0] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2016-08-16 20:24:04,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x5b58b8, 0x624cf0, 2, "Registration successful") 2016-08-16 20:24:04,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2016-08-16 20:24:04,414 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x5b58b8] notifying [registration_state_changed] 2016-08-16 20:24:09,354 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x602f78], from state [COMPLETED] to [TERMINATED] 2016-08-16 20:24:09,354 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x602f78] terminated 2016-08-16 20:24:09,455 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x60b9d8], from state [COMPLETED] to [TERMINATED] 2016-08-16 20:24:09,456 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x60b9d8] terminated