pi@raspberrypi:~ $ sudo doorpi_cli --trace 2016-03-25 14:13:12,898 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.0 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-03-25 14:13:12,903 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-03-25 14:13:12,905 [DEBUG] [doorpi.doorpi] run 2016-03-25 14:13:12,905 [DEBUG] [doorpi.doorpi] prepare 2016-03-25 14:13:12,906 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-03-25 14:13:12,909 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-03-25 14:13:12,933 [DEBUG] [doorpi.conf.config_object] __init__ 2016-03-25 14:13:12,934 [TRACE] [doorpi.conf.config_object] get_from_config 2016-03-25 14:13:12,947 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-03-25 14:13:12,948 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns /backup/log/conf/eventlog.db 2016-03-25 14:13:12,950 [DEBUG] [doorpi.conf.config_object] parse string "/backup/log/conf/eventlog.db" to "/backup/log/conf/eventlog.db" 2016-03-25 14:13:13,969 [ERROR] [doorpi.action.handler] error to create event_db 2016-03-25 14:13:13,972 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-03-25 14:13:13,975 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-03-25 14:13:13,977 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-03-25 14:13:13,980 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-03-25 14:13:13,982 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-03-25 14:13:13,985 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-03-25 14:13:13,987 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-03-25 14:13:13,989 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-03-25 14:13:13,991 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-03-25 14:13:13,993 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-03-25 14:13:13,996 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-03-25 14:13:13,998 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-03-25 14:13:14,000 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-03-25 14:13:14,004 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-03-25 14:13:14,008 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-03-25 14:13:14,013 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-03-25 14:13:14,016 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-03-25 14:13:14,018 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 192.168.178.241 2016-03-25 14:13:14,021 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 8081 2016-03-25 14:13:14,030 [INFO] [doorpi.status.webserver] Initiating WebService at ip 192.168.178.241 and port 8081 2016-03-25 14:13:14,033 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-03-25 14:13:14,035 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-03-25 14:13:14,038 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-03-25 14:13:14,040 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-03-25 14:13:14,042 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-03-25 14:13:14,044 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-03-25 14:13:14,048 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-03-25 14:13:14,050 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-03-25 14:13:14,053 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-03-25 14:13:14,055 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-03-25 14:13:14,058 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-03-25 14:13:14,060 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-03-25 14:13:14,064 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-03-25 14:13:14,066 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-03-25 14:13:14,069 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-03-25 14:13:14,071 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2016-03-25 14:13:14,073 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrators', 'guest'] 2016-03-25 14:13:14,075 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators', 'guests'] 2016-03-25 14:13:14,077 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['pi', 'root', 'guest'] 2016-03-25 14:13:14,078 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['pi', ' root'] 2016-03-25 14:13:14,080 [TRACE] [doorpi.conf.config_object] get_list for key guests in section Group (default: []) returns ['guests'] 2016-03-25 14:13:14,081 [TRACE] [doorpi.conf.config_object] get_sections returns ['Group', 'ReadPermission', 'DTMF', 'EVENT_OnMediaNotRequired', 'prodsystem_OutputPins', 'EVENT_OnStartup', 'DoorPiWeb', 'OnCallIncomming', 'EVENT_OnKeyPressed_prodsystem.6', 'AREA_public', 'prodsystem_keyboard', 'WritePermission', 'User', 'EVENT_OnCallStateConnect', 'prodsystem_InputPins', 'EVENT_OnMediaRequired', 'SMTP', 'EVENT_OnKeyDown_prodsystem.5', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'EVENT_OnKeyPressed_prodsystem.1', 'EVENT_OnKeyPressed_prodsystem.0', 'EVENT_OnKeyPressed_prodsystem.3', 'EVENT_OnKeyPressed_prodsystem.2', 'EVENT_OnKeyPressed_prodsystem.4', 'DoorPi'] 2016-03-25 14:13:14,083 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['dashboard', 'status', 'config'] 2016-03-25 14:13:14,085 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section ReadPermission (default: []) returns ['dashboard', 'status', 'config'] 2016-03-25 14:13:14,087 [TRACE] [doorpi.conf.config_object] get_list for key guest in section ReadPermission (default: []) returns ['dashboard'] 2016-03-25 14:13:14,089 [ERROR] [doorpi.status.webserver] group guest doesn't exist but is assigned to ReadPermission 2016-03-25 14:13:14,091 [ERROR] [doorpi.status.webserver] user root is assigned to group administrators but doesn't exist as user 2016-03-25 14:13:14,093 [ERROR] [doorpi.status.webserver] user guests is assigned to group guests but doesn't exist as user 2016-03-25 14:13:14,096 [ERROR] [doorpi.status.webserver] module dashboard doesn't exist but is assigned to group administrators in WritePermission 2016-03-25 14:13:14,098 [ERROR] [doorpi.status.webserver] module status doesn't exist but is assigned to group administrators in WritePermission 2016-03-25 14:13:14,100 [ERROR] [doorpi.status.webserver] module config doesn't exist but is assigned to group administrators in WritePermission 2016-03-25 14:13:14,102 [ERROR] [doorpi.status.webserver] module dashboard doesn't exist but is assigned to group administrators in ReadPermission 2016-03-25 14:13:14,105 [ERROR] [doorpi.status.webserver] module status doesn't exist but is assigned to group administrators in ReadPermission 2016-03-25 14:13:14,108 [ERROR] [doorpi.status.webserver] module config doesn't exist but is assigned to group administrators in ReadPermission 2016-03-25 14:13:14,110 [ERROR] [doorpi.status.webserver] module dashboard doesn't exist but is assigned to group guest in ReadPermission 2016-03-25 14:13:14,112 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-03-25 14:13:14,115 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-03-25 14:13:14,117 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-03-25 14:13:14,121 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,125 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-03-25 14:13:14,127 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,130 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,133 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,134 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,135 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,136 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,137 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,138 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler Exception in thread OnWebServerStart from doorpi.status.webserver: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:14,140 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,141 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,142 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,143 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,143 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,144 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,145 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-03-25 14:13:14,146 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['prodsystem'] 2016-03-25 14:13:14,147 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: prodsystem) 2016-03-25 14:13:14,148 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'prodsystem' to handler 2016-03-25 14:13:14,149 [TRACE] [doorpi.conf.config_object] get_string for key prodsystem in section keyboards (default: dummy) returns piface 2016-03-25 14:13:14,150 [TRACE] [doorpi.conf.config_object] get_keys for section prodsystem_InputPins returns ['1', '0', '3', '2', '5', '4', '6'] 2016-03-25 14:13:14,151 [TRACE] [doorpi.conf.config_object] get_keys for section prodsystem_OutputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2016-03-25 14:13:14,151 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section prodsystem_keyboard (default: 2000) returns 80.0 2016-03-25 14:13:14,152 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section prodsystem_keyboard (default: 0) returns 0 2016-03-25 14:13:14,153 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section prodsystem_keyboard (default: True) returns True 2016-03-25 14:13:14,155 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2016-03-25 14:13:14,223 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0', '3', '2', '5', '4', '6'], output_pins = ['1', '0', '3', '2', '5', '4', '7', '6'], polarity = 0) 2016-03-25 14:13:14,332 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,333 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2016-03-25 14:13:14,335 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,336 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,336 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,337 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.1 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,338 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.1 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,339 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,340 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,340 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,341 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,342 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.1 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,343 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.1 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,344 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,344 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,345 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,346 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,347 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.1 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,348 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.1 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,348 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,349 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,350 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,351 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,352 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.0 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,352 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.0 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,353 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,354 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,355 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,356 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,357 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.0 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,357 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.0 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,358 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,359 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,360 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,360 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,361 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.0 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,362 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.0 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,363 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,364 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,365 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,365 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_3 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,366 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.3 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,367 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.3 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,368 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,368 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,369 [TRACE] [doorpi.action.handler] register Event OnKeyUp_3 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,370 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_3 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,371 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.3 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,371 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.3 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,372 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,373 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,374 [TRACE] [doorpi.action.handler] register Event OnKeyDown_3 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,375 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_3 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,375 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.3 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,376 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.3 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,377 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,378 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,379 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,379 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,380 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.2 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,381 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.2 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,382 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,382 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,383 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,384 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,385 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.2 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,386 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.2 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,386 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,387 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,388 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,389 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,389 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.2 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,390 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.2 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,391 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,392 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,393 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_5 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,393 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_5 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,394 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.5 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,395 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.5 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,396 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,397 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,398 [TRACE] [doorpi.action.handler] register Event OnKeyUp_5 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,398 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_5 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,399 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.5 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,400 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.5 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,401 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,401 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,402 [TRACE] [doorpi.action.handler] register Event OnKeyDown_5 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,403 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_5 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,404 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.5 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,405 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.5 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,405 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,406 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,407 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_4 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,408 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_4 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,409 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.4 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,409 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.4 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,410 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,411 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,412 [TRACE] [doorpi.action.handler] register Event OnKeyUp_4 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,412 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_4 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,413 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.4 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,414 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.4 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,415 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,415 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,416 [TRACE] [doorpi.action.handler] register Event OnKeyDown_4 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,417 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_4 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,418 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.4 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,419 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.4 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,419 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2016-03-25 14:13:14,420 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,421 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_6 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,422 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_6 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,423 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_prodsystem.6 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,423 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_prodsystem.6 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,424 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2016-03-25 14:13:14,425 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,426 [TRACE] [doorpi.action.handler] register Event OnKeyUp_6 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,426 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_6 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,427 [TRACE] [doorpi.action.handler] register Event OnKeyUp_prodsystem.6 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,428 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_prodsystem.6 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,429 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2016-03-25 14:13:14,429 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2016-03-25 14:13:14,430 [TRACE] [doorpi.action.handler] register Event OnKeyDown_6 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,431 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_6 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,432 [TRACE] [doorpi.action.handler] register Event OnKeyDown_prodsystem.6 from doorpi.keyboard.from_piface 2016-03-25 14:13:14,433 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_prodsystem.6 and registered source doorpi.keyboard.from_piface 2016-03-25 14:13:14,453 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-03-25 14:13:14,456 [TRACE] [doorpi.conf.config_object] get_keys for section prodsystem_OutputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2016-03-25 14:13:14,457 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section prodsystem_OutputPins (default: ) returns Tueroeffner 2016-03-25 14:13:14,458 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section prodsystem_OutputPins (default: ) returns Verstaerker 2016-03-25 14:13:14,459 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section prodsystem_OutputPins (default: ) returns Gong-Keller 2016-03-25 14:13:14,460 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section prodsystem_OutputPins (default: ) returns Licht 2016-03-25 14:13:14,461 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section prodsystem_OutputPins (default: ) returns Gong-Erhard 2016-03-25 14:13:14,462 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section prodsystem_OutputPins (default: ) returns Gong-Ernst 2016-03-25 14:13:14,463 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section prodsystem_OutputPins (default: ) returns Blink LED 2016-03-25 14:13:14,465 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section prodsystem_OutputPins (default: ) returns Gong-Andreas 2016-03-25 14:13:14,469 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-03-25 14:13:14,473 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-03-25 14:13:14,477 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-03-25 14:13:14,480 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-03-25 14:13:14,539 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-03-25 14:13:14,543 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-03-25 14:13:14,548 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-03-25 14:13:14,573 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-03-25 14:13:14,583 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-03-25 14:13:14,584 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-03-25 14:13:14,587 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-03-25 14:13:14,593 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-03-25 14:13:14,596 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-03-25 14:13:14,600 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-03-25 14:13:14,602 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-03-25 14:13:14,603 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-03-25 14:13:14,605 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,605 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-03-25 14:13:14,606 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,607 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,608 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,609 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,610 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,611 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,611 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,612 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,613 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,614 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,615 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,616 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,616 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,617 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,618 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,619 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,619 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,620 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,621 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,622 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,622 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,623 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-03-25 14:13:14,624 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-03-25 14:13:14,625 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-03-25 14:13:14,626 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-03-25 14:13:14,627 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,628 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-03-25 14:13:14,628 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,629 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,630 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,631 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,632 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,632 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,633 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,634 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,635 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,636 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,636 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,637 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,638 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,639 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,639 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,640 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,641 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,642 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,642 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,643 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,644 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,645 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,646 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,647 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"1"', '"**001*"', '"**002*"', '"#"'] 2016-03-25 14:13:14,648 [TRACE] [doorpi.action.handler] register Event OnDTMF_"1" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,648 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"1" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,649 [TRACE] [doorpi.action.handler] register Event OnDTMF_"**001*" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,650 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"**001*" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,651 [TRACE] [doorpi.action.handler] register Event OnDTMF_"**002*" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,652 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"**002*" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,652 [TRACE] [doorpi.action.handler] register Event OnDTMF_"#" from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,653 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"#" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,654 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,655 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-03-25 14:13:14,656 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,657 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,657 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,658 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,659 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,660 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,660 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,661 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,662 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,663 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,663 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,664 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,665 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,666 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,667 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,667 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,668 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,669 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-25 14:13:14,670 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-03-25 14:13:14,673 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-03-25 14:13:14,675 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x75a02fc8 Exception in thread OnCallStart from doorpi.sipphone.linphone_lib.CallBacks: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:14,679 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-03-25 14:13:14,680 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x75a02fc8 2016-03-25 14:13:14,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74e3d4b0, "(null)", "(null)") 2016-03-25 14:13:14,682 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-03-25 14:13:14,683 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2016-03-25 14:13:14,684 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-03-25 14:13:14,685 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-03-25 14:13:14,734 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-03-25 14:13:14,791 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-03-25 14:13:14,802 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-03-25 14:13:14,806 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-03-25 14:13:14,807 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-03-25 14:13:14,809 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2016-03-25 14:13:14,810 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-03-25 14:13:14,811 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-03-25 14:13:14,812 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-03-25 14:13:14,813 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-03-25 14:13:14,814 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-03-25 14:13:14,815 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x12faff8] registered on core [0x1361dd8] 2016-03-25 14:13:14,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x12faff8, 1, "Starting up") 2016-03-25 14:13:14,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-25 14:13:14,818 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x12faff8] notifying [global_state_changed] 2016-03-25 14:13:14,819 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-03-25 14:13:14,820 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-03-25 14:13:14,821 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-03-25 14:13:14,822 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-03-25 14:13:14,822 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2016-03-25 14:13:14,823 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 14:13:14,824 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 14:13:14,825 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-25 14:13:14,826 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-25 14:13:14,827 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-03-25 14:13:14,828 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-03-25 14:13:14,829 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-03-25 14:13:14,830 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-03-25 14:13:14,831 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-03-25 14:13:14,831 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-03-25 14:13:14,832 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-03-25 14:13:14,833 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-03-25 14:13:14,834 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-03-25 14:13:14,835 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-03-25 14:13:14,836 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-03-25 14:13:14,837 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-03-25 14:13:14,838 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-25 14:13:14,839 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-25 14:13:14,840 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 14:13:14,840 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 14:13:14,841 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 14:13:14,842 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 14:13:14,843 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 14:13:14,844 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 14:13:14,845 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 14:13:14,846 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 14:13:14,847 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-25 14:13:14,848 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-25 14:13:14,848 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-03-25 14:13:14,849 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-03-25 14:13:14,850 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-03-25 14:13:14,851 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 14:13:14,852 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 14:13:14,853 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 14:13:14,853 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 14:13:14,855 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-25 14:13:14,856 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-25 14:13:14,857 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-03-25 14:13:14,858 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-03-25 14:13:14,859 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-03-25 14:13:14,859 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-03-25 14:13:14,860 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-03-25 14:13:14,861 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-03-25 14:13:14,862 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-03-25 14:13:14,863 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-03-25 14:13:14,864 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-03-25 14:13:14,865 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-03-25 14:13:14,866 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-03-25 14:13:14,866 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-03-25 14:13:14,867 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-03-25 14:13:14,868 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-03-25 14:13:14,869 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-03-25 14:13:14,870 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-03-25 14:13:14,871 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-03-25 14:13:14,872 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-03-25 14:13:14,872 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-03-25 14:13:14,873 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-03-25 14:13:14,874 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-03-25 14:13:14,875 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-03-25 14:13:14,876 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-03-25 14:13:14,877 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-25 14:13:14,878 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-03-25 14:13:14,879 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-03-25 14:13:14,879 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-03-25 14:13:14,880 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-03-25 14:13:14,881 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-03-25 14:13:14,882 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-03-25 14:13:14,883 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-03-25 14:13:14,884 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-03-25 14:13:14,885 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-03-25 14:13:14,886 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-03-25 14:13:14,886 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-25 14:13:14,887 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-25 14:13:14,888 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-03-25 14:13:14,889 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-03-25 14:13:14,890 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-03-25 14:13:14,891 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-03-25 14:13:14,892 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-25 14:13:14,893 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-25 14:13:14,894 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,895 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,896 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,897 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x12faff8, 2, "(null)") 2016-03-25 14:13:14,898 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-03-25 14:13:14,899 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x12faff8] notifying [configuring_status] 2016-03-25 14:13:14,900 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-03-25 14:13:14,901 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-03-25 14:13:14,901 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-03-25 14:13:14,902 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-03-25 14:13:14,903 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-03-25 14:13:14,904 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-25 14:13:14,905 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-25 14:13:14,906 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-03-25 14:13:14,907 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-03-25 14:13:14,908 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-03-25 14:13:14,909 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,909 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,910 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,911 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,912 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-03-25 14:13:14,913 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-25 14:13:14,914 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-25 14:13:14,915 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,916 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,916 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-03-25 14:13:14,917 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-25 14:13:14,918 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,919 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-25 14:13:14,920 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x134aab8] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-25 14:13:14,922 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x134acc8] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-25 14:13:14,923 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x134aea0] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-25 14:13:14,924 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 50610 2016-03-25 14:13:14,925 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,926 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,931 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x12faff8, 2, "Ready") 2016-03-25 14:13:14,932 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-25 14:13:14,933 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x12faff8] notifying [global_state_changed] 2016-03-25 14:13:14,934 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x75a02fc8 2016-03-25 14:13:14,935 [DEBUG] [doorpi.conf.config_object] create new key identity in section SIP-Phone with value 'DoorPi' 2016-03-25 14:13:14,936 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-03-25 14:13:14,937 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x75a02fc8 [0x12faff8], "DoorPi ") 2016-03-25 14:13:14,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-03-25 14:13:14,941 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-03-25 14:13:14,943 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 1 2016-03-25 14:13:14,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x75a02fc8 [0x12faff8], 1) 2016-03-25 14:13:14,946 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 Exception in thread OnSipPhoneCreate from doorpi.sipphone.from_linphone: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:14,950 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns True 2016-03-25 14:13:14,951 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x75a02fc8 [0x12faff8], 0x2e0620 [1]) 2016-03-25 14:13:14,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-03-25 14:13:14,954 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-25 14:13:14,956 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-25 14:13:14,956 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-03-25 14:13:14,957 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x75a02fe0 2016-03-25 14:13:14,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x75a02fc8 [0x12faff8], 0x75a02fe0 [0x75a02fe8]) 2016-03-25 14:13:14,960 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x134aab8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-03-25 14:13:14,961 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x134acc8] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-25 14:13:14,962 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x134aea0] on [sip:0.0.0.0:50610;transport=TLS] destroyed 2016-03-25 14:13:14,963 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-25 14:13:14,964 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1360920] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-25 14:13:14,965 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x135f0d8] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-25 14:13:14,966 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x134aa08] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-25 14:13:14,967 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 53155 2016-03-25 14:13:14,968 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,969 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-25 14:13:14,970 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-03-25 14:13:14,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x75a02fe0) 2016-03-25 14:13:14,972 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-03-25 14:13:14,973 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-03-25 14:13:14,974 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x75a02fc8 [0x12faff8], 0x2e0614 [0]) 2016-03-25 14:13:14,975 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-03-25 14:13:14,976 [DEBUG] [doorpi.conf.config_object] create new key stun_server in section SIP-Phone with value '' 2016-03-25 14:13:14,977 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-03-25 14:13:14,978 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x75a02fc8 [0x12faff8], "") 2016-03-25 14:13:14,980 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-25 14:13:14,981 [INFO] [doorpi.sipphone.from_linphone] 192.168.178.1 2016-03-25 14:13:14,982 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-25 14:13:14,983 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-25 14:13:14,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-03-25 14:13:14,986 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-03-25 14:13:14,986 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-03-25 14:13:14,987 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x75a02fc8 [0x12faff8], 0) 2016-03-25 14:13:14,988 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-25 14:13:14,989 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-25 14:13:14,991 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-03-25 14:13:14,992 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-03-25 14:13:14,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x75a02fc8 [0x12faff8], 120) 2016-03-25 14:13:14,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-03-25 14:13:14,996 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 20 2016-03-25 14:13:14,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x75a02fc8 [0x12faff8], 20) 2016-03-25 14:13:14,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-03-25 14:13:14,998 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/doorpi/media/ShortDialTone.wav 2016-03-25 14:13:15,001 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/doorpi/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/doorpi/media/ShortDialTone.wav" 2016-03-25 14:13:15,002 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-25 14:13:15,004 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-03-25 14:13:15,005 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-25 14:13:15,006 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-03-25 14:13:15,007 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-03-25 14:13:15,008 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-25 14:13:15,009 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-03-25 14:13:15,009 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-25 14:13:15,010 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-03-25 14:13:15,011 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-03-25 14:13:15,012 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-03-25 14:13:15,013 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-03-25 14:13:15,014 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-25 14:13:15,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x75a02fc8 [0x12faff8], "/usr/local/etc/DoorPi/doorpi/media/ShortDialTone.wav") 2016-03-25 14:13:15,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-03-25 14:13:15,019 [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 /backup/record-audio/!LastKey!/%Y-%m-%d_%H-%M-%S.wav 2016-03-25 14:13:15,021 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-25 14:13:15,022 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 14:13:15,023 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added Exception in thread OnPlayerCreated from doorpi.sipphone.linphone_lib.Player: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:15,025 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-25 14:13:15,026 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 14:13:15,027 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-25 14:13:15,028 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-25 14:13:15,028 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-25 14:13:15,029 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns True 2016-03-25 14:13:15,030 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneMakeCall 2016-03-25 14:13:15,031 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-03-25 14:13:15,032 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-03-25 14:13:15,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74e41148 2016-03-25 14:13:15,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75a02fc8 [0x12faff8]) Exception in thread OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:15,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74e37aa0 2016-03-25 14:13:15,042 [DEBUG] [doorpi.conf.config_object] create new key capture_device in section SIP-Phone with value 'ALSA: default device' 2016-03-25 14:13:15,043 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-03-25 14:13:15,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x75a02fc8 [0x12faff8], "ALSA: default device") 2016-03-25 14:13:15,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-03-25 14:13:15,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74e37b00 2016-03-25 14:13:15,048 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-03-25 14:13:15,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x75a02fc8 [0x12faff8], "ALSA: default device") 2016-03-25 14:13:15,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-03-25 14:13:15,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74e3ada0 2016-03-25 14:13:15,053 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2016-03-25 14:13:15,054 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-03-25 14:13:15,055 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 14:13:15,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74e3ada0 2016-03-25 14:13:15,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75a02fc8 [0x12faff8], "ALSA: default device") 2016-03-25 14:13:15,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-25 14:13:15,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75a02fc8 [0x12faff8], "ALSA: default device") 2016-03-25 14:13:15,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-25 14:13:15,061 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-03-25 14:13:15,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75a02fc8 [0x12faff8], "ALSA: bcm2835 ALSA") 2016-03-25 14:13:15,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-03-25 14:13:15,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75a02fc8 [0x12faff8], "ALSA: bcm2835 ALSA") 2016-03-25 14:13:15,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-25 14:13:15,066 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-03-25 14:13:15,067 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 14:13:15,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x74e37bf0 2016-03-25 14:13:15,070 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-03-25 14:13:15,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x74e37bc0 2016-03-25 14:13:15,073 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-03-25 14:13:15,074 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU', 'Speex'] 2016-03-25 14:13:15,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x12fb628) 2016-03-25 14:13:15,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd0450 2016-03-25 14:13:15,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd0450 2016-03-25 14:13:15,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd0450 2016-03-25 14:13:15,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x12fb698) 2016-03-25 14:13:15,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd0440 2016-03-25 14:13:15,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd0440 2016-03-25 14:13:15,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd0440 2016-03-25 14:13:15,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x12fb700) 2016-03-25 14:13:15,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd0460 2016-03-25 14:13:15,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd0460 2016-03-25 14:13:15,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd0460 2016-03-25 14:13:15,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a668) 2016-03-25 14:13:15,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd0470 2016-03-25 14:13:15,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd0470 2016-03-25 14:13:15,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd0470 2016-03-25 14:13:15,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a6c0) 2016-03-25 14:13:15,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd0480 2016-03-25 14:13:15,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd0480 2016-03-25 14:13:15,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd0480 2016-03-25 14:13:15,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a718) 2016-03-25 14:13:15,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd0490 2016-03-25 14:13:15,107 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd0490 2016-03-25 14:13:15,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd0490 2016-03-25 14:13:15,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a770) 2016-03-25 14:13:15,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd04a0 2016-03-25 14:13:15,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd04a0 2016-03-25 14:13:15,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd04a0 2016-03-25 14:13:15,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a7c8) 2016-03-25 14:13:15,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,118 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd04b0 2016-03-25 14:13:15,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd04b0 2016-03-25 14:13:15,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd04b0 2016-03-25 14:13:15,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a830) 2016-03-25 14:13:15,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd04c0 2016-03-25 14:13:15,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd04c0 2016-03-25 14:13:15,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd04c0 2016-03-25 14:13:15,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x134a888) 2016-03-25 14:13:15,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,129 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd04d0 2016-03-25 14:13:15,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd04d0 2016-03-25 14:13:15,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd04d0 2016-03-25 14:13:15,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x74e3ac60 2016-03-25 14:13:15,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0450 [0x12fb628]) 2016-03-25 14:13:15,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0450 [0x12fb628]) 2016-03-25 14:13:15,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,137 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-03-25 14:13:15,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd0450 [0x12fb628], 0x2e0614 [0]) 2016-03-25 14:13:15,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0440 [0x12fb698]) 2016-03-25 14:13:15,141 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0440 [0x12fb698]) 2016-03-25 14:13:15,143 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,144 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-25 14:13:15,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd0440 [0x12fb698], 0x2e0614 [0]) 2016-03-25 14:13:15,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0460 [0x12fb700]) 2016-03-25 14:13:15,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0460 [0x12fb700]) 2016-03-25 14:13:15,150 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,151 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-25 14:13:15,152 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd0460 [0x12fb700], 0x2e0614 [0]) 2016-03-25 14:13:15,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0470 [0x134a668]) 2016-03-25 14:13:15,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0470 [0x134a668]) 2016-03-25 14:13:15,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,158 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-03-25 14:13:15,159 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd0470 [0x134a668], 0x2e0620 [1]) 2016-03-25 14:13:15,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,161 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0480 [0x134a6c0]) 2016-03-25 14:13:15,162 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0480 [0x134a6c0]) 2016-03-25 14:13:15,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,165 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-03-25 14:13:15,166 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd0480 [0x134a6c0], 0x2e0620 [1]) 2016-03-25 14:13:15,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,168 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0490 [0x134a718]) 2016-03-25 14:13:15,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd0490 [0x134a718]) 2016-03-25 14:13:15,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,171 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-03-25 14:13:15,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd0490 [0x134a718], 0x2e0614 [0]) 2016-03-25 14:13:15,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04a0 [0x134a770]) 2016-03-25 14:13:15,175 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,176 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04a0 [0x134a770]) 2016-03-25 14:13:15,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,178 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-03-25 14:13:15,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd04a0 [0x134a770], 0x2e0614 [0]) 2016-03-25 14:13:15,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04b0 [0x134a7c8]) 2016-03-25 14:13:15,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33f20 2016-03-25 14:13:15,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04b0 [0x134a7c8]) 2016-03-25 14:13:15,184 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x74e33fc0 2016-03-25 14:13:15,185 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-25 14:13:15,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd04b0 [0x134a7c8], 0x2e0614 [0]) 2016-03-25 14:13:15,187 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,188 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04c0 [0x134a830]) 2016-03-25 14:13:15,189 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,190 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04c0 [0x134a830]) 2016-03-25 14:13:15,191 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,192 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-25 14:13:15,193 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd04c0 [0x134a830], 0x2e0614 [0]) 2016-03-25 14:13:15,194 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04d0 [0x134a888]) 2016-03-25 14:13:15,196 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,197 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04d0 [0x134a888]) 2016-03-25 14:13:15,198 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,198 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-25 14:13:15,199 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd04d0 [0x134a888], 0x2e0614 [0]) 2016-03-25 14:13:15,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,201 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,202 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74e41120 2016-03-25 14:13:15,203 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,204 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74e41120 2016-03-25 14:13:15,205 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2016-03-25 14:13:15,206 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-03-25 14:13:15,207 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 14:13:15,208 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74e41120 2016-03-25 14:13:15,209 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2016-03-25 14:13:15,210 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-03-25 14:13:15,211 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-25 14:13:15,212 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,213 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74e41120 2016-03-25 14:13:15,214 [DEBUG] [doorpi.conf.config_object] create new key video_device in section SIP-Phone with value 'V4L2: /dev/video0' 2016-03-25 14:13:15,215 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2016-03-25 14:13:15,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,217 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74e41120 2016-03-25 14:13:15,218 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x75a02fc8 [0x12faff8], 0x2e0620 [1]) 2016-03-25 14:13:15,219 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-03-25 14:13:15,220 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x75a02fc8 [0x12faff8], "V4L2: /dev/video0") 2016-03-25 14:13:15,221 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-03-25 14:13:15,222 [DEBUG] [doorpi.conf.config_object] create new key video_size in section SIP-Phone with value 'vga' 2016-03-25 14:13:15,222 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-03-25 14:13:15,223 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x75a02fc8 [0x12faff8], "vga") 2016-03-25 14:13:15,224 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-03-25 14:13:15,225 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,226 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x74e411d8 2016-03-25 14:13:15,227 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2016-03-25 14:13:15,228 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-03-25 14:13:15,229 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,230 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x12fb588) 2016-03-25 14:13:15,231 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-25 14:13:15,232 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cd04e0 2016-03-25 14:13:15,233 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-25 14:13:15,234 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cd04e0 2016-03-25 14:13:15,235 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cd04e0 2016-03-25 14:13:15,236 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x74e3afa8 2016-03-25 14:13:15,237 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04e0 [0x12fb588]) 2016-03-25 14:13:15,238 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,239 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,240 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-03-25 14:13:15,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cd04e0 [0x12fb588]) 2016-03-25 14:13:15,242 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75a02fe0 2016-03-25 14:13:15,242 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-03-25 14:13:15,244 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75a02fc8 [0x12faff8], 0x76cd04e0 [0x12fb588], 0x2e0620 [1]) 2016-03-25 14:13:15,245 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x11c38bc 2016-03-25 14:13:15,246 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.178.1 2016-03-25 14:13:15,247 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 620 2016-03-25 14:13:15,247 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 620) returns ******* 2016-03-25 14:13:15,248 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.178.1) returns 192.168.178.1 2016-03-25 14:13:15,249 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-03-25 14:13:15,250 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x75a02fc8 [0x12faff8]) 2016-03-25 14:13:15,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x136ca20) 2016-03-25 14:13:15,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-03-25 14:13:15,253 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76cd04f0 2016-03-25 14:13:15,254 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-03-25 14:13:15,255 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76cd04f0 2016-03-25 14:13:15,256 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76cd04f0 2016-03-25 14:13:15,257 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76cd04f0 2016-03-25 14:13:15,258 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-03-25 14:13:15,259 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-03-25 14:13:15,261 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x136b670) 2016-03-25 14:13:15,262 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-03-25 14:13:15,263 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76cd0500 2016-03-25 14:13:15,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-03-25 14:13:15,265 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76cd0500 2016-03-25 14:13:15,266 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76cd0500 2016-03-25 14:13:15,267 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76cd0500 2016-03-25 14:13:15,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76cd04f0 [0x136ca20], 0x76cd0500 [0x136b670]) 2016-03-25 14:13:15,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-03-25 14:13:15,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76cd04f0 [0x136ca20], "sip:192.168.178.1") 2016-03-25 14:13:15,271 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-03-25 14:13:15,273 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76cd04f0 [0x136ca20], 0x2e0620 [1]) 2016-03-25 14:13:15,273 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-03-25 14:13:15,275 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x75a02fc8 [0x12faff8], 0x76cd04f0 [0x136ca20]) 2016-03-25 14:13:15,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x11c38bc 2016-03-25 14:13:15,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x75a02fc8 [0x12faff8], 0x76cd04f0 [0x136ca20]) 2016-03-25 14:13:15,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-03-25 14:13:15,280 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x75a02fc8 [0x12faff8], "620", "(null)", "nea1102", "(null)", "(null)", "192.168.178.1") 2016-03-25 14:13:15,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x136af88) 2016-03-25 14:13:15,282 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-03-25 14:13:15,283 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76cd0510 2016-03-25 14:13:15,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-03-25 14:13:15,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76cd0510 2016-03-25 14:13:15,286 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76cd0510 2016-03-25 14:13:15,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76cd0510 2016-03-25 14:13:15,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x75a02fc8 [0x12faff8], 0x76cd0510 [0x136af88]) 2016-03-25 14:13:15,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-03-25 14:13:15,289 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-03-25 14:13:15,290 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnMediaNotRequired', 'EVENT_OnStartup', 'EVENT_OnKeyPressed_prodsystem.6', 'EVENT_OnCallStateConnect', 'EVENT_OnMediaRequired', 'EVENT_OnKeyDown_prodsystem.5', 'EVENT_OnKeyPressed_prodsystem.1', 'EVENT_OnKeyPressed_prodsystem.0', 'EVENT_OnKeyPressed_prodsystem.3', 'EVENT_OnKeyPressed_prodsystem.2', 'EVENT_OnKeyPressed_prodsystem.4'] 2016-03-25 14:13:15,291 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnMediaNotRequired' in configfile 2016-03-25 14:13:15,292 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnMediaNotRequired returns ['10', '30', '20', '50', '40'] 2016-03-25 14:13:15,293 [INFO] [doorpi.doorpi] registering action '10' for event 'OnMediaNotRequired' 2016-03-25 14:13:15,294 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Keller,0 2016-03-25 14:13:15,296 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2016-03-25 14:13:15,298 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2016-03-25 14:13:15,300 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Keller'} was added to new evententry OnMediaNotRequired 2016-03-25 14:13:15,301 [INFO] [doorpi.doorpi] registering action '20' for event 'OnMediaNotRequired' 2016-03-25 14:13:15,302 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Ernst,0 2016-03-25 14:13:15,303 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Ernst'} was added to event OnMediaNotRequired 2016-03-25 14:13:15,304 [INFO] [doorpi.doorpi] registering action '30' for event 'OnMediaNotRequired' 2016-03-25 14:13:15,305 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Erhard,0 2016-03-25 14:13:15,306 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Erhard'} was added to event OnMediaNotRequired 2016-03-25 14:13:15,307 [INFO] [doorpi.doorpi] registering action '40' for event 'OnMediaNotRequired' 2016-03-25 14:13:15,308 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnMediaNotRequired (default: ) returns out:Gong-Andreas,0 2016-03-25 14:13:15,309 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Gong-Andreas'} was added to event OnMediaNotRequired 2016-03-25 14:13:15,310 [INFO] [doorpi.doorpi] registering action '50' for event 'OnMediaNotRequired' 2016-03-25 14:13:15,310 [TRACE] [doorpi.conf.config_object] get_string for key 50 in section EVENT_OnMediaNotRequired (default: ) returns out:Verstaerker,0 2016-03-25 14:13:15,311 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Verstaerker'} was added to event OnMediaNotRequired 2016-03-25 14:13:15,313 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-03-25 14:13:15,314 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-03-25 14:13:15,315 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-03-25 14:13:15,315 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-03-25 14:13:15,317 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-03-25 14:13:15,319 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-03-25 14:13:15,319 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_prodsystem.6' in configfile 2016-03-25 14:13:15,320 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_prodsystem.6 returns ['10', '20'] 2016-03-25 14:13:15,321 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_prodsystem.6' 2016-03-25 14:13:15,322 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_prodsystem.6 (default: ) returns out:Licht,1,0,21600 2016-03-25 14:13:15,323 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 21600.0, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnKeyPressed_prodsystem.6 2016-03-25 14:13:15,324 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyPressed_prodsystem.6' 2016-03-25 14:13:15,325 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyPressed_prodsystem.6 (default: ) returns sleep:1 2016-03-25 14:13:15,326 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyPressed_prodsystem.6 2016-03-25 14:13:15,327 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnCallStateConnect' in configfile 2016-03-25 14:13:15,328 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnCallStateConnect returns ['10'] 2016-03-25 14:13:15,329 [INFO] [doorpi.doorpi] registering action '10' for event 'OnCallStateConnect' 2016-03-25 14:13:15,330 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnCallStateConnect (default: ) returns out:Verstaerker,1,0,200 2016-03-25 14:13:15,331 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 200.0, 'pin': 'Verstaerker', 'start_value': '1'} was added to event OnCallStateConnect 2016-03-25 14:13:15,332 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnMediaRequired' in configfile 2016-03-25 14:13:15,333 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnMediaRequired returns [] 2016-03-25 14:13:15,333 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyDown_prodsystem.5' in configfile 2016-03-25 14:13:15,334 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyDown_prodsystem.5 returns ['10'] 2016-03-25 14:13:15,335 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyDown_prodsystem.5' 2016-03-25 14:13:15,336 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyDown_prodsystem.5 (default: ) returns out:Licht,1,0,0.1 2016-03-25 14:13:15,337 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.1, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnKeyDown_prodsystem.5 2016-03-25 14:13:15,338 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_prodsystem.1' in configfile 2016-03-25 14:13:15,339 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_prodsystem.1 returns [] 2016-03-25 14:13:15,340 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_prodsystem.0' in configfile 2016-03-25 14:13:15,341 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_prodsystem.0 returns ['10', '20', '30', '50', '40', '60', '70', '90', '80'] 2016-03-25 14:13:15,342 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,343 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-25 14:13:15,345 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2016-03-25 14:13:15,347 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to new evententry OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,348 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,349 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns out:Gong-Keller,1,0,0.5 2016-03-25 14:13:15,350 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'Gong-Keller', 'start_value': '1'} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,351 [INFO] [doorpi.doorpi] registering action '30' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,352 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns call:**621 2016-03-25 14:13:15,354 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-03-25 14:13:15,356 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**621'} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,357 [INFO] [doorpi.doorpi] registering action '40' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,358 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns os_execute:sudo /usr/bin/sendEmail -f doorpi@schwarz-ketsch.de -t andreas@schwarz-ketsch.de -u Klingel -m Es steht jemand vor der Tuer -s smtp.1und1.de:587 -xu doorpi@schwarz-ketsch.de -xp schnillo1102 -o tls=yes &>/dev/null & 2016-03-25 14:13:15,360 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /usr/bin/sendEmail -f doorpi@schwarz-ketsch.de -t andreas@schwarz-ketsch.de -u Klingel -m Es steht jemand vor der Tuer -s smtp.1und1.de:587 -xu doorpi@schwarz-ketsch.de -xp schnillo1102 -o tls=yes &>/dev/null &'} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,361 [INFO] [doorpi.doorpi] registering action '50' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,362 [TRACE] [doorpi.conf.config_object] get_string for key 50 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns sleep:2 2016-03-25 14:13:15,363 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,364 [INFO] [doorpi.doorpi] registering action '60' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,365 [TRACE] [doorpi.conf.config_object] get_string for key 60 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns os_execute:sudo /usr/local/bin/mail-bild.sh 2016-03-25 14:13:15,367 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /usr/local/bin/mail-bild.sh'} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,368 [INFO] [doorpi.doorpi] registering action '70' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,369 [TRACE] [doorpi.conf.config_object] get_string for key 70 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-25 14:13:15,371 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,372 [INFO] [doorpi.doorpi] registering action '80' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,373 [TRACE] [doorpi.conf.config_object] get_string for key 80 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns sleep:2 2016-03-25 14:13:15,374 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,375 [INFO] [doorpi.doorpi] registering action '90' for event 'OnKeyPressed_prodsystem.0' 2016-03-25 14:13:15,375 [TRACE] [doorpi.conf.config_object] get_string for key 90 in section EVENT_OnKeyPressed_prodsystem.0 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-25 14:13:15,378 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,379 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_prodsystem.3' in configfile 2016-03-25 14:13:15,379 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_prodsystem.3 returns ['10', '20', '30', '50', '40', '60', '70', '80'] 2016-03-25 14:13:15,380 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,381 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-25 14:13:15,383 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to new evententry OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,384 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,385 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns out:Gong-Andreas,1,0,0.5 2016-03-25 14:13:15,386 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'Gong-Andreas', 'start_value': '1'} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,387 [INFO] [doorpi.doorpi] registering action '30' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,388 [TRACE] [doorpi.conf.config_object] get_string for key 30 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns call:**624 2016-03-25 14:13:15,389 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**624'} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,390 [INFO] [doorpi.doorpi] registering action '40' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,391 [TRACE] [doorpi.conf.config_object] get_string for key 40 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns sleep:2 2016-03-25 14:13:15,392 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,393 [INFO] [doorpi.doorpi] registering action '50' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,394 [TRACE] [doorpi.conf.config_object] get_string for key 50 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns os_execute:sudo /usr/local/bin/mail-bild.sh 2016-03-25 14:13:15,396 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'sudo /usr/local/bin/mail-bild.sh'} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,397 [INFO] [doorpi.doorpi] registering action '60' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,398 [TRACE] [doorpi.conf.config_object] get_string for key 60 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-25 14:13:15,400 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,401 [INFO] [doorpi.doorpi] registering action '70' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,402 [TRACE] [doorpi.conf.config_object] get_string for key 70 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns sleep:2 2016-03-25 14:13:15,403 [TRACE] [doorpi.action.handler] action SleepAction with args (2.0,) and kwargs {} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,404 [INFO] [doorpi.doorpi] registering action '80' for event 'OnKeyPressed_prodsystem.3' 2016-03-25 14:13:15,405 [TRACE] [doorpi.conf.config_object] get_string for key 80 in section EVENT_OnKeyPressed_prodsystem.3 (default: ) returns os_execute:php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php 2016-03-25 14:13:15,407 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': 'php /usr/local/etc/DoorPi/tools/take_snapshot_doorpi.php'} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,408 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_prodsystem.2' in configfile 2016-03-25 14:13:15,409 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_prodsystem.2 returns [] 2016-03-25 14:13:15,410 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_prodsystem.4' in configfile 2016-03-25 14:13:15,411 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_prodsystem.4 returns ['10', '20'] 2016-03-25 14:13:15,412 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_prodsystem.4' 2016-03-25 14:13:15,413 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_prodsystem.4 (default: ) returns out:Licht,1,0,300 2016-03-25 14:13:15,414 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 300.0, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnKeyPressed_prodsystem.4 2016-03-25 14:13:15,415 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyPressed_prodsystem.4' 2016-03-25 14:13:15,416 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyPressed_prodsystem.4 (default: ) returns sleep:1 2016-03-25 14:13:15,417 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyPressed_prodsystem.4 2016-03-25 14:13:15,418 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-25 14:13:15,419 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.1 2016-03-25 14:13:15,420 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-25 14:13:15,421 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to event OnKeyPressed_prodsystem.0 2016-03-25 14:13:15,422 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-25 14:13:15,423 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to event OnKeyPressed_prodsystem.3 2016-03-25 14:13:15,424 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section prodsystem_InputPins (default: ) returns sleep:0.1 2016-03-25 14:13:15,425 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_prodsystem.2 2016-03-25 14:13:15,426 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section prodsystem_InputPins (default: ) returns Aufnahme-Bewegungssensor-in 2016-03-25 14:13:15,427 [ERROR] [doorpi.action.base] error while creating SingleAction from config string: Aufnahme-Bewegungssensor-in Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/action/base.py", line 64, in from_string return importlib.import_module('doorpi.action.SingleActions.'+action_name).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) ImportError: No module named Aufnahme-Bewegungssensor-in 2016-03-25 14:13:15,430 [ERROR] [doorpi.action.handler] action_object is None 2016-03-25 14:13:15,431 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section prodsystem_InputPins (default: ) returns Licht-Bewegungssensor-in 2016-03-25 14:13:15,432 [ERROR] [doorpi.action.base] error while creating SingleAction from config string: Licht-Bewegungssensor-in Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/action/base.py", line 64, in from_string return importlib.import_module('doorpi.action.SingleActions.'+action_name).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) ImportError: No module named Licht-Bewegungssensor-in 2016-03-25 14:13:15,434 [ERROR] [doorpi.action.handler] action_object is None 2016-03-25 14:13:15,435 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section prodsystem_InputPins (default: ) returns Licht-manuell 2016-03-25 14:13:15,436 [ERROR] [doorpi.action.base] error while creating SingleAction from config string: Licht-manuell Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/action/base.py", line 64, in from_string return importlib.import_module('doorpi.action.SingleActions.'+action_name).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) ImportError: No module named Licht-manuell 2016-03-25 14:13:15,438 [ERROR] [doorpi.action.handler] action_object is None 2016-03-25 14:13:15,439 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"1"', '"**001*"', '"**002*"', '"#"'] 2016-03-25 14:13:15,440 [TRACE] [doorpi.conf.config_object] get_string for key "#" in section DTMF (default: ) returns out:Tueroeffner,1,0,2 2016-03-25 14:13:15,441 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 2.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnDTMF_"#" 2016-03-25 14:13:15,442 [TRACE] [doorpi.conf.config_object] get_string for key "**001*" in section DTMF (default: ) returns out:Verstaerker,0 2016-03-25 14:13:15,443 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Verstaerker'} was added to new evententry OnDTMF_"**001*" 2016-03-25 14:13:15,445 [TRACE] [doorpi.conf.config_object] get_string for key "**002*" in section DTMF (default: ) returns out:Verstaerker,1 2016-03-25 14:13:15,446 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'Verstaerker'} was added to new evententry OnDTMF_"**002*" 2016-03-25 14:13:15,447 [TRACE] [doorpi.conf.config_object] get_string for key "1" in section DTMF (default: ) returns out:Licht,1,0,300 2016-03-25 14:13:15,448 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 300.0, 'pin': 'Licht', 'start_value': '1'} was added to new evententry OnDTMF_"1" 2016-03-25 14:13:15,449 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns Blink LED 2016-03-25 14:13:15,450 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'HIGH', 'pin': 'Blink LED'} was added to new evententry OnTimeSecondEvenNumber 2016-03-25 14:13:15,451 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': 'False', 'value': 'LOW', 'pin': 'Blink LED'} was added to new evententry OnTimeSecondUnevenNumber 2016-03-25 14:13:15,452 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron Exception in thread BeforeStartup from doorpi.doorpi: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:15,456 [ERROR] [doorpi.main] Exception NameError: database is locked Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 152, in main_as_application try: doorpi.DoorPi(parsed_arguments).run() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 237, in run self.event_handler.fire_event_synchron('OnStartup', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-25 14:13:15,467 [DEBUG] [doorpi.doorpi] destroy doorpi 2016-03-25 14:13:15,468 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1995792384)>, ] 2016-03-25 14:13:15,469 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron Exception in thread BeforeShutdown from doorpi.doorpi: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Traceback (most recent call last): File "/usr/local/bin/doorpi_cli", line 11, in sys.exit(entry_point()) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 169, in entry_point raise SystemExit(main_as_application(sys.argv)) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 155, in main_as_application finally: doorpi.DoorPi().destroy() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 207, in destroy self.event_handler.fire_event_synchron('OnShutdown', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) sqlite3.OperationalError: database is locked