2022-03-22 14:14:47,029 [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 2022-03-22 14:14:47,029 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2022-03-22 14:14:47,029 [DEBUG] [doorpi.doorpi] run 2022-03-22 14:14:47,029 [DEBUG] [doorpi.doorpi] prepare 2022-03-22 14:14:47,029 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2022-03-22 14:14:47,030 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2022-03-22 14:14:47,033 [DEBUG] [doorpi.conf.config_object] __init__ 2022-03-22 14:14:47,033 [TRACE] [doorpi.conf.config_object] get_from_config 2022-03-22 14:14:47,035 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2022-03-22 14:14:47,035 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2022-03-22 14:14:47,036 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2022-03-22 14:14:47,037 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2022-03-22 14:14:47,037 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2022-03-22 14:14:47,037 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2022-03-22 14:14:47,037 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2022-03-22 14:14:47,038 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2022-03-22 14:14:47,039 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2022-03-22 14:14:47,040 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2022-03-22 14:14:47,040 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2022-03-22 14:14:47,040 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2022-03-22 14:14:47,041 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2022-03-22 14:14:47,041 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2022-03-22 14:14:47,042 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2022-03-22 14:14:47,042 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2022-03-22 14:14:47,042 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2022-03-22 14:14:47,042 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2022-03-22 14:14:47,042 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2022-03-22 14:14:47,043 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2022-03-22 14:14:47,043 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2022-03-22 14:14:47,043 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2022-03-22 14:14:47,043 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2022-03-22 14:14:47,043 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2022-03-22 14:14:47,044 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2022-03-22 14:14:47,044 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2022-03-22 14:14:47,044 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2022-03-22 14:14:47,044 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2022-03-22 14:14:47,045 [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 2022-03-22 14:14:47,045 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2022-03-22 14:14:47,045 [WARNING] [root] section WritePermission not found in configfile 2022-03-22 14:14:47,045 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2022-03-22 14:14:47,045 [WARNING] [root] section ReadPermission not found in configfile 2022-03-22 14:14:47,045 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2022-03-22 14:14:47,045 [WARNING] [root] section Group not found in configfile 2022-03-22 14:14:47,046 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2022-03-22 14:14:47,046 [WARNING] [root] section User not found in configfile 2022-03-22 14:14:47,046 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2022-03-22 14:14:47,046 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'AREA_public', 'onboardpins_keyboard', 'keyboards', 'AdminNumbers', 'virtual_keyboard', 'DTMF', 'SIP-Phone', 'virtual_InputPins', 'virtual_OutputPins', 'EVENT_OnKeyPressed_onboardpins.0', 'EVENT_OnStartup', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2022-03-22 14:14:47,046 [ERROR] [doorpi.status.webserver] no ReadPermission found 2022-03-22 14:14:47,046 [ERROR] [doorpi.status.webserver] no groups in configfile! 2022-03-22 14:14:47,046 [ERROR] [doorpi.status.webserver] no WritePermission found 2022-03-22 14:14:47,047 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2022-03-22 14:14:47,047 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2022-03-22 14:14:47,047 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2022-03-22 14:14:47,047 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,048 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2022-03-22 14:14:47,048 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,048 [DEBUG] [doorpi.action.handler] [D51DJJ] fire for event OnWebServerStart this actions [] 2022-03-22 14:14:47,048 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,048 [TRACE] [doorpi.action.handler] [D51DJJ] try to fire action WebServerStartupAction with args () and kwargs {} 2022-03-22 14:14:47,048 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,049 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2022-03-22 14:14:47,049 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,049 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,049 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,049 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,049 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2022-03-22 14:14:47,050 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['virtual', 'onboardpins'] 2022-03-22 14:14:47,051 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: virtual, onboardpins) 2022-03-22 14:14:47,051 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'virtual' to handler 2022-03-22 14:14:47,051 [TRACE] [doorpi.conf.config_object] get_string for key virtual in section keyboards (default: dummy) returns filesystem 2022-03-22 14:14:47,051 [TRACE] [doorpi.conf.config_object] get_keys for section virtual_InputPins returns [] 2022-03-22 14:14:47,051 [TRACE] [doorpi.conf.config_object] get_keys for section virtual_OutputPins returns [] 2022-03-22 14:14:47,051 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section virtual_keyboard (default: 2000) returns 2000.0 2022-03-22 14:14:47,051 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section virtual_keyboard (default: 0) returns 0 2022-03-22 14:14:47,051 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section virtual_keyboard (default: True) returns True 2022-03-22 14:14:47,052 [DEBUG] [doorpi.keyboard.from_filesystem] doorpi.keyboard.from_filesystem loaded 2022-03-22 14:14:47,085 [DEBUG] [doorpi.keyboard.from_filesystem] FileSystem.__init__(input_pins = [], output_pins = [], polarity = 0) 2022-03-22 14:14:47,086 [TRACE] [doorpi.conf.config_object] get_boolean for key reset_input in section virtual_keyboard (default: True) returns True 2022-03-22 14:14:47,086 [TRACE] [doorpi.conf.config_object] get_string for key base_path_input in section virtual_keyboard (default: ) returns /usr/local/etc/DoorPi/filesystem/inputs/ 2022-03-22 14:14:47,086 [DEBUG] [doorpi.conf.config_object] parse string "/usr/local/etc/DoorPi/filesystem/inputs/" to "/usr/local/etc/DoorPi/filesystem/inputs/" 2022-03-22 14:14:47,087 [TRACE] [doorpi.conf.config_object] get_string for key base_path_output in section virtual_keyboard (default: ) returns /usr/local/etc/DoorPi/filesystem/outputs/ 2022-03-22 14:14:47,087 [DEBUG] [doorpi.conf.config_object] parse string "/usr/local/etc/DoorPi/filesystem/outputs/" to "/usr/local/etc/DoorPi/filesystem/outputs/" 2022-03-22 14:14:47,089 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2022-03-22 14:14:47,089 [TRACE] [doorpi.conf.config_object] get_keys for section virtual_OutputPins returns [] 2022-03-22 14:14:47,089 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2022-03-22 14:14:47,089 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2022-03-22 14:14:47,089 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2022-03-22 14:14:47,090 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['1', '0', '2', '7'] 2022-03-22 14:14:47,090 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 4000.0 2022-03-22 14:14:47,090 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2022-03-22 14:14:47,090 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2022-03-22 14:14:47,091 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2022-03-22 14:14:47,099 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0', '3', '2', '5', '4', '7', '6'], output_pins = ['1', '0', '2', '7'], polarity = 0) 2022-03-22 14:14:47,127 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,128 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2022-03-22 14:14:47,128 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,128 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,128 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,128 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,129 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,130 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.0 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,131 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.0 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,132 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.0 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_3 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,133 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.3 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.3 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] register Event OnKeyUp_3 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_3 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.3 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,134 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.3 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] register Event OnKeyDown_3 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_3 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.3 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.3 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,135 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.2 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.2 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,136 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.2 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.2 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.2 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,137 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.2 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_5 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_5 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.5 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.5 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,138 [TRACE] [doorpi.action.handler] register Event OnKeyUp_5 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_5 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.5 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.5 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] register Event OnKeyDown_5 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_5 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.5 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,139 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.5 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_4 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_4 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.4 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.4 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,140 [TRACE] [doorpi.action.handler] register Event OnKeyUp_4 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_4 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.4 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.4 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] register Event OnKeyDown_4 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_4 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.4 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,141 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.4 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.7 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,142 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.7 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,143 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,144 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.7 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,144 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_6 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_6 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.6 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.6 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,145 [TRACE] [doorpi.action.handler] register Event OnKeyUp_6 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_6 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.6 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.6 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] register Event OnKeyDown_6 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_6 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.6 from doorpi.keyboard.from_piface 2022-03-22 14:14:47,146 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.6 and registered source doorpi.keyboard.from_piface 2022-03-22 14:14:47,158 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2022-03-22 14:14:47,159 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['1', '0', '2', '7'] 2022-03-22 14:14:47,159 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_OutputPins (default: ) returns Tueroeffner 2022-03-22 14:14:47,159 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_OutputPins (default: ) returns Verstaerker 2022-03-22 14:14:47,159 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_OutputPins (default: ) returns 2022-03-22 14:14:47,159 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_OutputPins (default: ) returns DoorPi-Alive-LED 2022-03-22 14:14:47,161 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2022-03-22 14:14:47,162 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2022-03-22 14:14:47,163 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2022-03-22 14:14:47,164 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2022-03-22 14:14:47,185 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2022-03-22 14:14:47,186 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2022-03-22 14:14:47,188 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2022-03-22 14:14:47,195 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2022-03-22 14:14:47,197 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2022-03-22 14:14:47,198 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2022-03-22 14:14:47,198 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2022-03-22 14:14:47,199 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2022-03-22 14:14:47,200 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2022-03-22 14:14:47,201 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2022-03-22 14:14:47,206 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2022-03-22 14:14:47,206 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2022-03-22 14:14:47,206 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,206 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2022-03-22 14:14:47,206 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,207 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,208 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,209 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,209 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,209 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,209 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2022-03-22 14:14:47,209 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2022-03-22 14:14:47,209 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2022-03-22 14:14:47,209 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,209 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,210 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,211 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"0#"'] 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] register Event OnDTMF_"0#" from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,212 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"0#" and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,213 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,214 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,214 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,214 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,214 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,214 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,214 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,215 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,215 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,215 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,215 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,215 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,215 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2022-03-22 14:14:47,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2022-03-22 14:14:47,216 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2022-03-22 14:14:47,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb22fa848 2022-03-22 14:14:47,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2022-03-22 14:14:47,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb22fa848 2022-03-22 14:14:47,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb4cad390, "(null)", "(null)") 2022-03-22 14:14:47,217 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2022-03-22 14:14:47,217 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2022-03-22 14:14:47,217 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2022-03-22 14:14:47,218 [INFO] [doorpi.sipphone.from_linphone] srtp init 2022-03-22 14:14:47,225 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2022-03-22 14:14:47,238 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2022-03-22 14:14:47,240 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1711 - open '/dev/snd/pcmC0D0c' failed (-2) 2022-03-22 14:14:47,241 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 Headphones' added 2022-03-22 14:14:47,241 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2022-03-22 14:14:47,242 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2022-03-22 14:14:47,242 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2022-03-22 14:14:47,242 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2022-03-22 14:14:47,242 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2022-03-22 14:14:47,242 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2022-03-22 14:14:47,243 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x233ec70] registered on core [0x2395798] 2022-03-22 14:14:47,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x233ec70, 1, "Starting up") 2022-03-22 14:14:47,243 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2022-03-22 14:14:47,243 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x233ec70] notifying [global_state_changed] 2022-03-22 14:14:47,243 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2022-03-22 14:14:47,243 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2022-03-22 14:14:47,243 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2022-03-22 14:14:47,243 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2022-03-22 14:14:47,244 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2022-03-22 14:14:47,245 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-22 14:14:47,246 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2022-03-22 14:14:47,247 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2022-03-22 14:14:47,248 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2022-03-22 14:14:47,249 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2022-03-22 14:14:47,250 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2022-03-22 14:14:47,251 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2022-03-22 14:14:47,252 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2022-03-22 14:14:47,253 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2022-03-22 14:14:47,254 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2022-03-22 14:14:47,254 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2022-03-22 14:14:47,254 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,254 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,254 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x233ec70, 2, "(null)") 2022-03-22 14:14:47,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2022-03-22 14:14:47,255 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x233ec70] notifying [configuring_status] 2022-03-22 14:14:47,255 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2022-03-22 14:14:47,255 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2022-03-22 14:14:47,255 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2022-03-22 14:14:47,255 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2022-03-22 14:14:47,255 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2022-03-22 14:14:47,255 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,256 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2022-03-22 14:14:47,257 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,258 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2022-03-22 14:14:47,258 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21b3c58] on [sip:0.0.0.0:5060;transport=UDP] 2022-03-22 14:14:47,258 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x22dee10] on [sip:0.0.0.0:5060;transport=TCP] 2022-03-22 14:14:47,258 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x23b5a90] on [sip:0.0.0.0:-1;transport=TLS] 2022-03-22 14:14:47,258 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 44825 2022-03-22 14:14:47,259 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,259 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,260 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x233ec70, 2, "Ready") 2022-03-22 14:14:47,260 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2022-03-22 14:14:47,261 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x233ec70] notifying [global_state_changed] 2022-03-22 14:14:47,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb22fa848 2022-03-22 14:14:47,261 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2022-03-22 14:14:47,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb22fa848 [0x233ec70], "DoorPi ") 2022-03-22 14:14:47,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2022-03-22 14:14:47,262 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2022-03-22 14:14:47,262 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2022-03-22 14:14:47,262 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb22fa848 [0x233ec70], 2) 2022-03-22 14:14:47,262 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2022-03-22 14:14:47,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2022-03-22 14:14:47,263 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2022-03-22 14:14:47,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb22fa848 [0x233ec70], 0x2a311c [0]) 2022-03-22 14:14:47,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2022-03-22 14:14:47,263 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2022-03-22 14:14:47,263 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2022-03-22 14:14:47,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2022-03-22 14:14:47,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb22fa860 2022-03-22 14:14:47,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb22fa848 [0x233ec70], 0xb22fa860 [0xb22fa868]) 2022-03-22 14:14:47,264 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x21b3c58] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2022-03-22 14:14:47,264 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x22dee10] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2022-03-22 14:14:47,265 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x23b5a90] on [sip:0.0.0.0:44825;transport=TLS] destroyed 2022-03-22 14:14:47,265 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2022-03-22 14:14:47,265 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21b3c58] on [sip:0.0.0.0:5060;transport=UDP] 2022-03-22 14:14:47,265 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x2395880] on [sip:0.0.0.0:5060;transport=TCP] 2022-03-22 14:14:47,265 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x23bc230] on [sip:0.0.0.0:-1;transport=TLS] 2022-03-22 14:14:47,265 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 38179 2022-03-22 14:14:47,266 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,266 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2022-03-22 14:14:47,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2022-03-22 14:14:47,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb22fa860) 2022-03-22 14:14:47,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2022-03-22 14:14:47,266 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2022-03-22 14:14:47,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb22fa848 [0x233ec70], 0x2a311c [0]) 2022-03-22 14:14:47,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2022-03-22 14:14:47,267 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2022-03-22 14:14:47,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb22fa848 [0x233ec70], "") 2022-03-22 14:14:47,267 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2022-03-22 14:14:47,267 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2022-03-22 14:14:47,267 [INFO] [doorpi.sipphone.from_linphone] fd00::9a9b:cbff:fe76:3cb9 2022-03-22 14:14:47,268 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2022-03-22 14:14:47,268 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2022-03-22 14:14:47,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2022-03-22 14:14:47,268 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2022-03-22 14:14:47,269 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2022-03-22 14:14:47,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb22fa848 [0x233ec70], 0) 2022-03-22 14:14:47,269 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2022-03-22 14:14:47,269 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2022-03-22 14:14:47,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2022-03-22 14:14:47,270 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2022-03-22 14:14:47,270 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb22fa848 [0x233ec70], 120) 2022-03-22 14:14:47,270 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2022-03-22 14:14:47,270 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2022-03-22 14:14:47,270 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb22fa848 [0x233ec70], 15) 2022-03-22 14:14:47,270 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2022-03-22 14:14:47,270 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2022-03-22 14:14:47,271 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2022-03-22 14:14:47,271 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2022-03-22 14:14:47,272 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2022-03-22 14:14:47,272 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2022-03-22 14:14:47,272 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2022-03-22 14:14:47,272 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2022-03-22 14:14:47,272 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2022-03-22 14:14:47,272 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2022-03-22 14:14:47,272 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2022-03-22 14:14:47,273 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2022-03-22 14:14:47,273 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2022-03-22 14:14:47,273 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2022-03-22 14:14:47,273 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2022-03-22 14:14:47,273 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2022-03-22 14:14:47,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb22fa848 [0x233ec70], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2022-03-22 14:14:47,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2022-03-22 14:14:47,274 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2022-03-22 14:14:47,274 [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 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,275 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,275 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,276 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2022-03-22 14:14:47,276 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2022-03-22 14:14:47,276 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2022-03-22 14:14:47,276 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2022-03-22 14:14:47,276 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb4c8f648 2022-03-22 14:14:47,277 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2022-03-22 14:14:47,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb2319f80 2022-03-22 14:14:47,277 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2022-03-22 14:14:47,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb22fa848 [0x233ec70], "ALSA: default device") 2022-03-22 14:14:47,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2022-03-22 14:14:47,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb2319f50 2022-03-22 14:14:47,278 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2022-03-22 14:14:47,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb22fa848 [0x233ec70], "ALSA: default device") 2022-03-22 14:14:47,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2022-03-22 14:14:47,278 [TRACE] [doorpi.conf.config_object] get_integer for key mic_gain_db in section SIP-Phone (default: 0) returns 0.0 2022-03-22 14:14:47,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_mic_gain_db(0xb22fa848 [0x233ec70], 0.000000) 2022-03-22 14:14:47,279 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2022-03-22 14:14:47,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_mic_gain_db -> 0 2022-03-22 14:14:47,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb6045378 2022-03-22 14:14:47,279 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb6045378 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb22fa848 [0x233ec70], "ALSA: default device") 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a3128 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb22fa848 [0x233ec70], "ALSA: default device") 2022-03-22 14:14:47,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb22fa848 [0x233ec70], "ALSA: bcm2835 Headphones") 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2a311c 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb22fa848 [0x233ec70], "ALSA: bcm2835 Headphones") 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2a3128 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 Headphones 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-22 14:14:47,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb23240e0 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb23240b0 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_mic_gain_db(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_mic_gain_db -> 0x22df898 2022-03-22 14:14:47,282 [DEBUG] [doorpi.sipphone.from_linphone] mic_gain_db: 0.0 2022-03-22 14:14:47,283 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2022-03-22 14:14:47,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x2394fd8) 2022-03-22 14:14:47,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4c50 2022-03-22 14:14:47,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4c50 2022-03-22 14:14:47,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4c50 2022-03-22 14:14:47,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x2395048) 2022-03-22 14:14:47,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4c40 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4c40 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4c40 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23b5438) 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4c60 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4c60 2022-03-22 14:14:47,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4c60 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23b54a0) 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4c70 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4c70 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4c70 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23b54f8) 2022-03-22 14:14:47,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4c80 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4c80 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4c80 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23b5550) 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4c90 2022-03-22 14:14:47,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4c90 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4c90 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23b55a8) 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4ca0 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4ca0 2022-03-22 14:14:47,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4ca0 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23953a0) 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4cb0 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4cb0 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4cb0 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x2395408) 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4cc0 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4cc0 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4cc0 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x2395460) 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4cd0 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4cd0 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4cd0 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb60453a0 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c50 [0x2394fd8]) 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c50 [0x2394fd8]) 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2022-03-22 14:14:47,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4c50 [0x2394fd8], 0x2a311c [0]) 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c40 [0x2395048]) 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c2e0 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c40 [0x2395048]) 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c2e0 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2022-03-22 14:14:47,292 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4c40 [0x2395048], 0x2a311c [0]) 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c60 [0x23b5438]) 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c60 [0x23b5438]) 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4c60 [0x23b5438], 0x2a311c [0]) 2022-03-22 14:14:47,293 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c70 [0x23b54a0]) 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c2e0 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c70 [0x23b54a0]) 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c2e0 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4c70 [0x23b54a0], 0x2a3128 [1]) 2022-03-22 14:14:47,294 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c80 [0x23b54f8]) 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c80 [0x23b54f8]) 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4c80 [0x23b54f8], 0x2a3128 [1]) 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,295 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c90 [0x23b5550]) 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4c90 [0x23b5550]) 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4c90 [0x23b5550], 0x2a311c [0]) 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4ca0 [0x23b55a8]) 2022-03-22 14:14:47,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4ca0 [0x23b55a8]) 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c520 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4ca0 [0x23b55a8], 0x2a311c [0]) 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4cb0 [0x23953a0]) 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c2e0 2022-03-22 14:14:47,297 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4cb0 [0x23953a0]) 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4c4c2e0 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4cb0 [0x23953a0], 0x2a311c [0]) 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4cc0 [0x2395408]) 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4cc0 [0x2395408]) 2022-03-22 14:14:47,298 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4cc0 [0x2395408], 0x2a311c [0]) 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4cd0 [0x2395460]) 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4cd0 [0x2395460]) 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,299 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4cd0 [0x2395460], 0x2a311c [0]) 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4c8f648 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4c8f648 2022-03-22 14:14:47,300 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2022-03-22 14:14:47,300 [DEBUG] [doorpi.sipphone.from_linphone] | name 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4c8f648 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4c8f648 2022-03-22 14:14:47,301 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4c8f648 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb22fa848 [0x233ec70], 0x2a3128 [1]) 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb22fa848 [0x233ec70], "StaticImage: Static picture") 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2022-03-22 14:14:47,302 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2022-03-22 14:14:47,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb22fa848 [0x233ec70], "vga") 2022-03-22 14:14:47,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2022-03-22 14:14:47,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb2324230 2022-03-22 14:14:47,303 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2022-03-22 14:14:47,303 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2022-03-22 14:14:47,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x23954b8) 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6ca4ce0 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6ca4ce0 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6ca4ce0 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb6045300 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4ce0 [0x23954b8]) 2022-03-22 14:14:47,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2a3128 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6ca4ce0 [0x23954b8]) 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb22fa860 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb22fa848 [0x233ec70], 0xb6ca4ce0 [0x23954b8], 0x2a3128 [1]) 2022-03-22 14:14:47,305 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x20c476c 2022-03-22 14:14:47,305 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2022-03-22 14:14:47,306 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns doorpiadmin 2022-03-22 14:14:47,306 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: doorpiadmin) returns ******* 2022-03-22 14:14:47,306 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns fritz.box 2022-03-22 14:14:47,306 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2022-03-22 14:14:47,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb22fa848 [0x233ec70]) 2022-03-22 14:14:47,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x22b2010) 2022-03-22 14:14:47,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2022-03-22 14:14:47,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6ca4cf0 2022-03-22 14:14:47,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2022-03-22 14:14:47,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6ca4cf0 2022-03-22 14:14:47,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6ca4cf0 2022-03-22 14:14:47,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6ca4cf0 2022-03-22 14:14:47,307 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2022-03-22 14:14:47,307 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x22dee10) 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6ca4d00 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6ca4d00 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6ca4d00 2022-03-22 14:14:47,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6ca4d00 2022-03-22 14:14:47,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6ca4cf0 [0x22b2010], 0xb6ca4d00 [0x22dee10]) 2022-03-22 14:14:47,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2022-03-22 14:14:47,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6ca4cf0 [0x22b2010], "sip:192.168.178.1") 2022-03-22 14:14:47,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2022-03-22 14:14:47,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6ca4cf0 [0x22b2010], 0x2a3128 [1]) 2022-03-22 14:14:47,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2022-03-22 14:14:47,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb22fa848 [0x233ec70], 0xb6ca4cf0 [0x22b2010]) 2022-03-22 14:14:47,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x20c476c 2022-03-22 14:14:47,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb22fa848 [0x233ec70], 0xb6ca4cf0 [0x22b2010]) 2022-03-22 14:14:47,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2022-03-22 14:14:47,310 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb22fa848 [0x233ec70], "doorpiadmin", "(null)", "Nb14102011", "(null)", "(null)", "fritz.box") 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x2176cd0) 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6ca4d10 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6ca4d10 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6ca4d10 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6ca4d10 2022-03-22 14:14:47,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb22fa848 [0x233ec70], 0xb6ca4d10 [0x2176cd0]) 2022-03-22 14:14:47,312 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2022-03-22 14:14:47,312 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2022-03-22 14:14:47,312 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnKeyPressed_onboardpins.0', 'EVENT_OnStartup'] 2022-03-22 14:14:47,312 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.0' in configfile 2022-03-22 14:14:47,312 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.0 returns ['10'] 2022-03-22 14:14:47,312 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.0' 2022-03-22 14:14:47,312 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.0 (default: ) returns file_call_value:/usr/local/etc/DoorPi/tools/call.txt 2022-03-22 14:14:47,316 [DEBUG] [doorpi.action.SingleActions.file_call_value] doorpi.action.SingleActions.file_call_value loaded 2022-03-22 14:14:47,317 [TRACE] [doorpi.action.handler] action CallPhoneNumberFromFileAction with args () and kwargs {'filename': '/usr/local/etc/DoorPi/tools/call.txt'} was added to new evententry OnKeyPressed_onboardpins.0 2022-03-22 14:14:47,317 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2022-03-22 14:14:47,317 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2022-03-22 14:14:47,317 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2022-03-22 14:14:47,317 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2022-03-22 14:14:47,318 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2022-03-22 14:14:47,318 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2022-03-22 14:14:47,318 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,318 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.1 2022-03-22 14:14:47,318 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,318 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to event OnKeyPressed_onboardpins.0 2022-03-22 14:14:47,319 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,319 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.3 2022-03-22 14:14:47,319 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,319 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.2 2022-03-22 14:14:47,319 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,319 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.5 2022-03-22 14:14:47,319 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,320 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.4 2022-03-22 14:14:47,320 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,320 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.7 2022-03-22 14:14:47,320 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section onboardpins_InputPins (default: ) returns sleep:0 2022-03-22 14:14:47,320 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.6 2022-03-22 14:14:47,320 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"0#"'] 2022-03-22 14:14:47,320 [TRACE] [doorpi.conf.config_object] get_string for key "0#" in section DTMF (default: ) returns out:Tueroeffner,1,0,3 2022-03-22 14:14:47,321 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2022-03-22 14:14:47,321 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2022-03-22 14:14:47,321 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnDTMF_"0#" 2022-03-22 14:14:47,321 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns DoorPi-Alive-LED 2022-03-22 14:14:47,322 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'HIGH', 'pin': 'DoorPi-Alive-LED'} was added to new evententry OnTimeSecondEvenNumber 2022-03-22 14:14:47,322 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'LOW', 'pin': 'DoorPi-Alive-LED'} was added to new evententry OnTimeSecondUnevenNumber 2022-03-22 14:14:47,322 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2022-03-22 14:14:47,323 [DEBUG] [doorpi.action.handler] [6RT5NT] fire for event OnStartup this actions [] 2022-03-22 14:14:47,323 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2022-03-22 14:14:47,323 [TRACE] [doorpi.action.handler] [6RT5NT] try to fire action SleepAction with args (1.0,) and kwargs {} 2022-03-22 14:14:47,323 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2022-03-22 14:14:48,325 [TRACE] [doorpi.action.handler] [6RT5NT] finished fire_event for event_name OnStartup 2022-03-22 14:14:48,326 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2022-03-22 14:14:48,327 [INFO] [doorpi.doorpi] DoorPi started successfully 2022-03-22 14:14:48,327 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2022-03-22 14:14:48,327 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2022-03-22 14:14:48,328 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2022-03-22 14:14:48,329 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2022-03-22 14:14:48,329 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2022-03-22 14:14:48,330 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.178.39 2022-03-22 14:14:48,330 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2022-03-22 14:14:48,331 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2022-03-22 14:14:48,331 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2022-03-22 14:14:48,331 [INFO] [doorpi.sipphone.from_linphone] fd00::9a9b:cbff:fe76:3cb9 2022-03-22 14:14:48,332 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2022-03-22 14:14:48,332 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2022-03-22 14:14:48,333 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x22b2010] about to register (LinphoneCore version: 3.9.0) 2022-03-22 14:14:48,339 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2022-03-22 14:14:48,339 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060]: starting resolution of 192.168.178.1 2022-03-22 14:14:48,339 [INFO] [doorpi.sipphone.from_linphone] channel 0x23c9060: state RES_IN_PROGRESS 2022-03-22 14:14:48,340 [INFO] [doorpi.sipphone.from_linphone] transaction [0x23bba30] channel state changed to [RES_IN_PROGRESS] 2022-03-22 14:14:48,340 [INFO] [doorpi.sipphone.from_linphone] channel 0x23c9060: state RES_DONE 2022-03-22 14:14:48,340 [INFO] [doorpi.sipphone.from_linphone] transaction [0x23bba30] channel state changed to [RES_DONE] 2022-03-22 14:14:48,341 [INFO] [doorpi.sipphone.from_linphone] channel 0x23c9060: state CONNECTING 2022-03-22 14:14:48,341 [INFO] [doorpi.sipphone.from_linphone] transaction [0x23bba30] channel state changed to [CONNECTING] 2022-03-22 14:14:48,341 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.178.1:5060] 2022-03-22 14:14:48,342 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2022-03-22 14:14:48,342 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.178.39:5060 2022-03-22 14:14:48,343 [INFO] [doorpi.sipphone.from_linphone] channel 0x23c9060: state READY 2022-03-22 14:14:48,343 [INFO] [doorpi.sipphone.from_linphone] transaction [0x23bba30] channel state changed to [READY] 2022-03-22 14:14:48,343 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23bba30], from state [INIT] to [TRYING] 2022-03-22 14:14:48,344 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060]: message sent to [UDP://192.168.178.1:5060], size: [538] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.39:5060;branch=z9hG4bK.mOhKsRGRw;rport From: "DoorPi" ;tag=6s-5QSv1L To: "DoorPi" CSeq: 20 REGISTER Call-ID: fAtBLJFL5R 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) 2022-03-22 14:14:48,344 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2022-03-22 14:14:48,345 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x23b6648] takes ownership of transaction [0x23bba30] 2022-03-22 14:14:48,345 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x22b2010] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2022-03-22 14:14:48,345 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x233ec70, 0x22b2010, 1, "Registration in progress") 2022-03-22 14:14:48,346 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-22 14:14:48,346 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x233ec70] notifying [registration_state_changed] 2022-03-22 14:14:48,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x233ec70, 0x1) 2022-03-22 14:14:48,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2022-03-22 14:14:48,397 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x233ec70] notifying [network_reachable] 2022-03-22 14:14:48,398 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060]: received [382] 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.mOhKsRGRw;rport=5060 From: "DoorPi" ;tag=6s-5QSv1L To: "DoorPi" ;tag=BACB9BC47583DD0B Call-ID: fAtBLJFL5R CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="7DD8114E4FA10FE2" User-Agent: FRITZ!OS Content-Length: 0 2022-03-22 14:14:48,405 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060] [382] bytes parsed 2022-03-22 14:14:48,405 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060]: discovered public ip and port are [192.168.178.39:5060] 2022-03-22 14:14:48,406 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2022-03-22 14:14:48,406 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23bba30], from state [TRYING] to [COMPLETED] 2022-03-22 14:14:48,406 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpiadmin, realm= 2022-03-22 14:14:48,407 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [doorpiadmin] realm [fritz.box] 2022-03-22 14:14:48,407 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23abce8], from state [INIT] to [TRYING] 2022-03-22 14:14:48,408 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060]: message sent to [UDP://192.168.178.1:5060], size: [701] bytes REGISTER sip:192.168.178.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.178.39:5060;branch=z9hG4bK.C0-G~vsLY;rport From: "DoorPi" ;tag=6s-5QSv1L To: "DoorPi" CSeq: 21 REGISTER Call-ID: fAtBLJFL5R 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="7DD8114E4FA10FE2", username="doorpiadmin", uri="sip:192.168.178.1", response="e9985687de790b9c0a51dfd049b285a8" 2022-03-22 14:14:48,408 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.178.1 2022-03-22 14:14:48,408 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2022-03-22 14:14:48,459 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060]: received [694] 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.C0-G~vsLY;rport=5060 From: "DoorPi" ;tag=6s-5QSv1L To: "DoorPi" ;tag=78FE12984B0F9D4D Call-ID: fAtBLJFL5R CSeq: 21 REGISTER Contact: ;+sip.instance="";expires=300 User-Agent: AVM FRITZ!Box 7590 154.07.29 (Oct 26 2021) 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 2022-03-22 14:14:48,464 [INFO] [doorpi.sipphone.from_linphone] channel [0x23c9060] [694] bytes parsed 2022-03-22 14:14:48,465 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2022-03-22 14:14:48,465 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23abce8], from state [TRYING] to [COMPLETED] 2022-03-22 14:14:48,465 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x23b6648]: has no contact for request [0x23af080]. 2022-03-22 14:14:48,466 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 270000 ms 2022-03-22 14:14:48,466 [INFO] [doorpi.sipphone.from_linphone] Register refresher [200] reason [OK] for proxy [sip:192.168.178.1] 2022-03-22 14:14:48,466 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x22b2010] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] 2022-03-22 14:14:48,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x233ec70, 0x22b2010, 2, "Registration successful") 2022-03-22 14:14:48,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2022-03-22 14:14:48,467 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x233ec70] notifying [registration_state_changed] 2022-03-22 14:14:53,457 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23bba30], from state [COMPLETED] to [TERMINATED] 2022-03-22 14:14:53,458 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x23bba30] terminated 2022-03-22 14:14:53,508 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x23abce8], from state [COMPLETED] to [TERMINATED] 2022-03-22 14:14:53,509 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x23abce8] terminated