2016-04-20 03:51:13,661 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.2 / /_// (_) | (_) | | / ___/| | 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-04-20 03:51:13,672 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-04-20 03:51:13,681 [DEBUG] [doorpi.doorpi] run 2016-04-20 03:51:13,689 [DEBUG] [doorpi.doorpi] prepare 2016-04-20 03:51:13,695 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-04-20 03:51:13,726 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-04-20 03:51:13,757 [DEBUG] [doorpi.conf.config_object] __init__ 2016-04-20 03:51:13,763 [TRACE] [doorpi.conf.config_object] get_from_config 2016-04-20 03:51:13,791 [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-04-20 03:51:13,796 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-04-20 03:51:13,808 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-04-20 03:51:13,824 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-04-20 03:51:13,831 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-04-20 03:51:13,838 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-04-20 03:51:13,844 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-04-20 03:51:13,850 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-04-20 03:51:13,857 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-04-20 03:51:13,865 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-04-20 03:51:13,876 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-04-20 03:51:13,883 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-04-20 03:51:13,890 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-04-20 03:51:13,897 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-04-20 03:51:13,905 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-04-20 03:51:13,912 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-04-20 03:51:13,926 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-04-20 03:51:13,934 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-04-20 03:51:13,954 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-04-20 03:51:13,959 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-04-20 03:51:13,969 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 192.168.1.123 2016-04-20 03:51:13,978 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-04-20 03:51:13,986 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip 192.168.1.123 and port 80 ([Errno 98] Address already in use) 2016-04-20 03:51:13,997 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip 192.168.1.123 and port 80 ([Errno 98] Address already in use) 2016-04-20 03:51:14,017 [INFO] [doorpi.status.webserver] Initiating WebService at ip 192.168.1.123 and port 8080 2016-04-20 03:51:14,023 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-04-20 03:51:14,032 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-04-20 03:51:14,042 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-04-20 03:51:14,049 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-04-20 03:51:14,056 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-04-20 03:51:14,061 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-04-20 03:51:14,075 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-04-20 03:51:14,084 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-04-20 03:51:14,094 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-04-20 03:51:14,100 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-04-20 03:51:14,110 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-04-20 03:51:14,117 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-04-20 03:51:14,127 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-04-20 03:51:14,138 [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-04-20 03:51:14,147 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-04-20 03:51:14,154 [WARNING] [root] section WritePermission not found in configfile 2016-04-20 03:51:14,162 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2016-04-20 03:51:14,168 [WARNING] [root] section ReadPermission not found in configfile 2016-04-20 03:51:14,174 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-04-20 03:51:14,181 [WARNING] [root] section Group not found in configfile 2016-04-20 03:51:14,188 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2016-04-20 03:51:14,198 [WARNING] [root] section User not found in configfile 2016-04-20 03:51:14,206 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2016-04-20 03:51:14,213 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'keyboards', 'AdminNumbers', 'DTMF', 'SIP-Phone', 'AREA_installer', 'EVENT_OnStartup', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-04-20 03:51:14,222 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-04-20 03:51:14,229 [ERROR] [doorpi.status.webserver] no groups in configfile! 2016-04-20 03:51:14,241 [ERROR] [doorpi.status.webserver] no WritePermission found 2016-04-20 03:51:14,250 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-04-20 03:51:14,258 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-04-20 03:51:14,266 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-04-20 03:51:14,279 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,285 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-04-20 03:51:14,306 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,318 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,334 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,342 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,353 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,361 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,367 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,374 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,380 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,388 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,395 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,405 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,414 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,421 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,428 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-04-20 03:51:14,438 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnTimeSecondEvenNumber 2016-04-20 03:51:14,446 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-04-20 03:51:14,455 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-04-20 03:51:14,464 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-04-20 03:51:14,470 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2016-04-20 03:51:14,480 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['37'] 2016-04-20 03:51:14,487 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11', '7'] 2016-04-20 03:51:14,495 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 20.0 2016-04-20 03:51:14,504 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 1 2016-04-20 03:51:14,510 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-04-20 03:51:14,522 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-04-20 03:51:14,540 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['37'], output_pins = ['11', '7'], bouncetime = 20.0, polarity = 1) 2016-04-20 03:51:14,548 [DEBUG] [doorpi.conf.config_object] create new key mode in section onboardpins_keyboard with value 'BOARD' 2016-04-20 03:51:14,555 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2016-04-20 03:51:14,562 [DEBUG] [doorpi.conf.config_object] create new key pull_up_down in section onboardpins_keyboard with value 'PUD_OFF' 2016-04-20 03:51:14,568 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-04-20 03:51:14,579 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,588 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-04-20 03:51:14,595 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,602 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_37 from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,611 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_37 and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,618 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.37 from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,625 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.37 and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,633 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,640 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,649 [TRACE] [doorpi.action.handler] register Event OnKeyUp_37 from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,657 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_37 and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,667 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.37 from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,675 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.37 and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,682 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,687 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,695 [TRACE] [doorpi.action.handler] register Event OnKeyDown_37 from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,701 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_37 and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,710 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.37 from doorpi.keyboard.from_gpio 2016-04-20 03:51:14,716 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.37 and registered source doorpi.keyboard.from_gpio 2016-04-20 03:51:14,733 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-04-20 03:51:14,739 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['11', '7'] 2016-04-20 03:51:14,750 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_OutputPins (default: ) returns horn 2016-04-20 03:51:14,756 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_OutputPins (default: ) returns licht 2016-04-20 03:51:14,772 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-04-20 03:51:14,786 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-04-20 03:51:14,796 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-04-20 03:51:14,806 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-04-20 03:51:14,952 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-04-20 03:51:14,964 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-04-20 03:51:14,978 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-04-20 03:51:15,175 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-04-20 03:51:15,201 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-04-20 03:51:15,208 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-04-20 03:51:15,218 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-04-20 03:51:15,235 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-04-20 03:51:15,248 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-04-20 03:51:15,262 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-04-20 03:51:15,271 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-04-20 03:51:15,283 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-04-20 03:51:15,290 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,298 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-04-20 03:51:15,304 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,311 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,320 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,337 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,349 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 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-04-20 03:51:15,367 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,375 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,382 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,389 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,396 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,402 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,409 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,417 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,425 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,439 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,446 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,456 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,468 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,480 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,490 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,499 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,506 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-04-20 03:51:15,513 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-04-20 03:51:15,520 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-04-20 03:51:15,529 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-04-20 03:51:15,536 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,545 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-04-20 03:51:15,553 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,559 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,567 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,575 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,582 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,588 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,596 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,602 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,606 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,610 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,613 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,616 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,620 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,624 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,627 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,630 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,634 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,639 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,643 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,646 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,650 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,654 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,657 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,661 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"#"'] 2016-04-20 03:51:15,665 [TRACE] [doorpi.action.handler] register Event OnDTMF_"#" from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,668 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"#" and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,672 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,675 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-04-20 03:51:15,679 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,682 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,685 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,689 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,692 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,697 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,700 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,705 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,708 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,711 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,715 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,718 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,721 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,724 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,728 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,731 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,735 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,738 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-04-20 03:51:15,741 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-04-20 03:51:15,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-04-20 03:51:15,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb46cb830 2016-04-20 03:51:15,763 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-04-20 03:51:15,766 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb46cb830 2016-04-20 03:51:15,770 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb66248a0, "(null)", "(null)") 2016-04-20 03:51:15,774 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-04-20 03:51:15,778 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2016-04-20 03:51:15,781 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-04-20 03:51:15,785 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-04-20 03:51:15,852 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-04-20 03:51:15,971 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-04-20 03:51:15,998 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-04-20 03:51:16,030 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-04-20 03:51:16,043 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-04-20 03:51:16,053 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2016-04-20 03:51:16,062 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-04-20 03:51:16,072 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-04-20 03:51:16,080 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-04-20 03:51:16,087 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-04-20 03:51:16,096 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-04-20 03:51:16,103 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x119c740] registered on core [0x1172db8] 2016-04-20 03:51:16,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x119c740, 1, "Starting up") 2016-04-20 03:51:16,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-04-20 03:51:16,133 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x119c740] notifying [global_state_changed] 2016-04-20 03:51:16,140 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-04-20 03:51:16,146 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-04-20 03:51:16,154 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-04-20 03:51:16,161 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-04-20 03:51:16,168 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2016-04-20 03:51:16,177 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-20 03:51:16,184 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-20 03:51:16,190 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-04-20 03:51:16,197 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-04-20 03:51:16,209 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-04-20 03:51:16,216 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-04-20 03:51:16,223 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-04-20 03:51:16,233 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-04-20 03:51:16,241 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-04-20 03:51:16,249 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-04-20 03:51:16,255 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-04-20 03:51:16,263 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-04-20 03:51:16,270 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-04-20 03:51:16,282 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-04-20 03:51:16,294 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-04-20 03:51:16,301 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-04-20 03:51:16,308 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-04-20 03:51:16,316 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-04-20 03:51:16,330 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-20 03:51:16,339 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-20 03:51:16,345 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-20 03:51:16,349 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-20 03:51:16,353 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-20 03:51:16,356 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-20 03:51:16,361 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-20 03:51:16,365 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-20 03:51:16,370 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-04-20 03:51:16,374 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-04-20 03:51:16,379 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-04-20 03:51:16,385 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-04-20 03:51:16,389 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-04-20 03:51:16,393 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-20 03:51:16,397 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-20 03:51:16,402 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-20 03:51:16,406 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-20 03:51:16,411 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-04-20 03:51:16,414 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-04-20 03:51:16,418 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-04-20 03:51:16,423 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-04-20 03:51:16,426 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-04-20 03:51:16,430 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-04-20 03:51:16,436 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-04-20 03:51:16,440 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-04-20 03:51:16,444 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-04-20 03:51:16,448 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-04-20 03:51:16,453 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-04-20 03:51:16,457 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-04-20 03:51:16,461 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-04-20 03:51:16,465 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-04-20 03:51:16,468 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-04-20 03:51:16,473 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-04-20 03:51:16,477 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-04-20 03:51:16,481 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-04-20 03:51:16,487 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-04-20 03:51:16,491 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-04-20 03:51:16,496 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-04-20 03:51:16,500 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-04-20 03:51:16,504 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-04-20 03:51:16,508 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-04-20 03:51:16,512 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-04-20 03:51:16,516 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-04-20 03:51:16,520 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-04-20 03:51:16,524 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-04-20 03:51:16,529 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-04-20 03:51:16,532 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-04-20 03:51:16,538 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-04-20 03:51:16,542 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-04-20 03:51:16,546 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-04-20 03:51:16,550 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-04-20 03:51:16,555 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-04-20 03:51:16,559 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-04-20 03:51:16,562 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-04-20 03:51:16,566 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-04-20 03:51:16,570 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-04-20 03:51:16,574 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-04-20 03:51:16,578 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-04-20 03:51:16,582 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-04-20 03:51:16,585 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-04-20 03:51:16,594 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-04-20 03:51:16,598 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:16,602 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:16,605 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:16,609 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x119c740, 2, "(null)") 2016-04-20 03:51:16,614 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-04-20 03:51:16,619 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x119c740] notifying [configuring_status] 2016-04-20 03:51:16,622 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-04-20 03:51:16,626 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-04-20 03:51:16,630 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-04-20 03:51:16,633 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-04-20 03:51:16,637 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2016-04-20 03:51:16,641 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-04-20 03:51:16,645 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-04-20 03:51:16,649 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-04-20 03:51:16,652 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-04-20 03:51:16,656 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-04-20 03:51:16,660 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,664 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,667 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,671 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,675 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-04-20 03:51:16,678 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-04-20 03:51:16,682 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-04-20 03:51:16,685 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,689 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,694 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-04-20 03:51:16,698 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-04-20 03:51:16,702 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:16,707 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-04-20 03:51:16,711 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x119cfb0] on [sip:0.0.0.0:5060;transport=UDP] 2016-04-20 03:51:16,715 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x119d1c0] on [sip:0.0.0.0:5060;transport=TCP] 2016-04-20 03:51:16,718 [ERROR] [doorpi.sipphone.from_linphone] TCP bind() failed for 0.0.0.0 port 5060: Address already in use 2016-04-20 03:51:16,722 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x119d1c0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-04-20 03:51:16,726 [WARNING] [doorpi.sipphone.from_linphone] Could not start tcp transport on port 5060, maybe this port is already used. 2016-04-20 03:51:16,730 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x119d198] on [sip:0.0.0.0:-1;transport=TLS] 2016-04-20 03:51:16,733 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 60145 2016-04-20 03:51:16,737 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:16,739 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:16,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x119c740, 2, "Ready") 2016-04-20 03:51:16,762 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-04-20 03:51:16,770 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x119c740] notifying [global_state_changed] 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-04-20 03:51:16,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb46cb830 2016-04-20 03:51:16,811 [DEBUG] [doorpi.conf.config_object] create new key identity in section SIP-Phone with value 'DoorPi' 2016-04-20 03:51:16,822 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-04-20 03:51:16,834 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb46cb830 [0x119c740], "DoorPi ") 2016-04-20 03:51:16,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-04-20 03:51:16,856 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-04-20 03:51:16,865 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-04-20 03:51:16,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb46cb830 [0x119c740], 2) 2016-04-20 03:51:16,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-04-20 03:51:16,899 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-04-20 03:51:16,908 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb46cb830 [0x119c740], 0x2e0614 [0]) 2016-04-20 03:51:16,917 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-04-20 03:51:16,927 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-04-20 03:51:16,933 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-04-20 03:51:16,941 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-04-20 03:51:16,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb46cb848 2016-04-20 03:51:16,964 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb46cb830 [0x119c740], 0xb46cb848 [0xb46cb850]) 2016-04-20 03:51:16,977 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x119cfb0] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-04-20 03:51:16,984 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x119d198] on [sip:0.0.0.0:60145;transport=TLS] destroyed 2016-04-20 03:51:16,991 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-04-20 03:51:17,001 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11d02e8] on [sip:0.0.0.0:5060;transport=UDP] 2016-04-20 03:51:17,008 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11cefb0] on [sip:0.0.0.0:5060;transport=TCP] 2016-04-20 03:51:17,015 [ERROR] [doorpi.sipphone.from_linphone] TCP bind() failed for 0.0.0.0 port 5060: Address already in use 2016-04-20 03:51:17,025 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x11cefb0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-04-20 03:51:17,032 [WARNING] [doorpi.sipphone.from_linphone] Could not start tcp transport on port 5060, maybe this port is already used. 2016-04-20 03:51:17,041 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11cd080] on [sip:0.0.0.0:-1;transport=TLS] 2016-04-20 03:51:17,048 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 52303 2016-04-20 03:51:17,057 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:17,066 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-04-20 03:51:17,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-04-20 03:51:17,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb46cb848) 2016-04-20 03:51:17,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-04-20 03:51:17,106 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-04-20 03:51:17,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb46cb830 [0x119c740], 0x2e0614 [0]) 2016-04-20 03:51:17,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-04-20 03:51:17,134 [DEBUG] [doorpi.conf.config_object] create new key stun_server in section SIP-Phone with value '' 2016-04-20 03:51:17,145 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-04-20 03:51:17,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb46cb830 [0x119c740], "") 2016-04-20 03:51:17,170 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-04-20 03:51:17,183 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-04-20 03:51:17,202 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-04-20 03:51:17,210 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-04-20 03:51:17,216 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-04-20 03:51:17,226 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-04-20 03:51:17,233 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-04-20 03:51:17,240 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb46cb830 [0x119c740], 0) 2016-04-20 03:51:17,249 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-04-20 03:51:17,256 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-04-20 03:51:17,268 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-04-20 03:51:17,278 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-04-20 03:51:17,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb46cb830 [0x119c740], 120) 2016-04-20 03:51:17,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-04-20 03:51:17,308 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-04-20 03:51:17,318 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb46cb830 [0x119c740], 15) 2016-04-20 03:51:17,326 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-04-20 03:51:17,338 [DEBUG] [doorpi.conf.config_object] create new key dialtone in section SIP-Phone with value '!BASEPATH!/media/ShortDialTone.wav' 2016-04-20 03:51:17,345 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2016-04-20 03:51:17,356 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-04-20 03:51:17,365 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-04-20 03:51:17,378 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-04-20 03:51:17,385 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-04-20 03:51:17,390 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-04-20 03:51:17,398 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-04-20 03:51:17,405 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-04-20 03:51:17,415 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-04-20 03:51:17,422 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-04-20 03:51:17,429 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-04-20 03:51:17,436 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-04-20 03:51:17,444 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-04-20 03:51:17,452 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-04-20 03:51:17,459 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-04-20 03:51:17,470 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb46cb830 [0x119c740], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-04-20 03:51:17,478 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-04-20 03:51:17,490 [DEBUG] [doorpi.conf.config_object] create new key records in section SIP-Phone with value '!BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav' 2016-04-20 03:51:17,498 [TRACE] [doorpi.conf.config_object] get_string for key records in section SIP-Phone (default: !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav) returns !BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav 2016-04-20 03:51:17,506 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-04-20 03:51:17,513 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-04-20 03:51:17,523 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-04-20 03:51:17,533 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-04-20 03:51:17,541 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-04-20 03:51:17,552 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-04-20 03:51:17,557 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-04-20 03:51:17,566 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-04-20 03:51:17,573 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-04-20 03:51:17,579 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-04-20 03:51:17,583 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-04-20 03:51:17,587 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-04-20 03:51:17,593 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,598 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb466aee0 2016-04-20 03:51:17,605 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,611 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4669980 2016-04-20 03:51:17,616 [DEBUG] [doorpi.conf.config_object] create new key capture_device in section SIP-Phone with value 'ALSA: default device' 2016-04-20 03:51:17,623 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-04-20 03:51:17,627 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb46cb830 [0x119c740], "ALSA: default device") 2016-04-20 03:51:17,631 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-04-20 03:51:17,637 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,641 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4669950 2016-04-20 03:51:17,646 [DEBUG] [doorpi.conf.config_object] create new key playback_device in section SIP-Phone with value 'ALSA: default device' 2016-04-20 03:51:17,650 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2016-04-20 03:51:17,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb46cb830 [0x119c740], "ALSA: default device") 2016-04-20 03:51:17,662 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-04-20 03:51:17,666 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,672 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb466aee0 2016-04-20 03:51:17,677 [INFO] [doorpi.sipphone.from_linphone] found 2 possible sounddevices: 2016-04-20 03:51:17,681 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-04-20 03:51:17,685 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-20 03:51:17,689 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,694 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb466aee0 2016-04-20 03:51:17,699 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb46cb830 [0x119c740], "ALSA: default device") 2016-04-20 03:51:17,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-04-20 03:51:17,710 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb46cb830 [0x119c740], "ALSA: default device") 2016-04-20 03:51:17,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-04-20 03:51:17,719 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-04-20 03:51:17,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb46cb830 [0x119c740], "ALSA: bcm2835 ALSA") 2016-04-20 03:51:17,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-04-20 03:51:17,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb46cb830 [0x119c740], "ALSA: bcm2835 ALSA") 2016-04-20 03:51:17,745 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-04-20 03:51:17,749 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-04-20 03:51:17,753 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-20 03:51:17,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,764 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4669aa0 2016-04-20 03:51:17,768 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2016-04-20 03:51:17,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,779 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4669a70 2016-04-20 03:51:17,783 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2016-04-20 03:51:17,788 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-04-20 03:51:17,792 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb46cb830 [0x119c740]) 2016-04-20 03:51:17,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119ca20) 2016-04-20 03:51:17,802 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:17,807 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d470 2016-04-20 03:51:17,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:17,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d470 2016-04-20 03:51:17,821 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d470 2016-04-20 03:51:17,829 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119ca90) 2016-04-20 03:51:17,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:17,840 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d480 2016-04-20 03:51:17,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:17,849 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d480 2016-04-20 03:51:17,854 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d480 2016-04-20 03:51:17,858 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119caf8) 2016-04-20 03:51:17,863 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:17,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d490 2016-04-20 03:51:17,872 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:17,880 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d490 2016-04-20 03:51:17,884 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d490 2016-04-20 03:51:17,889 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119cb60) 2016-04-20 03:51:17,893 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:17,898 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d4a0 2016-04-20 03:51:17,903 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 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-04-20 03:51:17,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d4a0 2016-04-20 03:51:17,927 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d4a0 2016-04-20 03:51:17,933 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119cbb8) 2016-04-20 03:51:17,938 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:17,943 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d3b0 2016-04-20 03:51:17,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:17,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d3b0 2016-04-20 03:51:17,959 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d3b0 2016-04-20 03:51:17,963 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119cc10) 2016-04-20 03:51:17,969 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:17,974 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d3e0 2016-04-20 03:51:17,978 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:17,985 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d3e0 2016-04-20 03:51:17,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d3e0 2016-04-20 03:51:17,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119cc68) 2016-04-20 03:51:17,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:18,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d4d0 2016-04-20 03:51:18,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:18,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d4d0 2016-04-20 03:51:18,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d4d0 2016-04-20 03:51:18,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119ccc0) 2016-04-20 03:51:18,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:18,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d4c0 2016-04-20 03:51:18,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:18,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d4c0 2016-04-20 03:51:18,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d4c0 2016-04-20 03:51:18,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119cd28) 2016-04-20 03:51:18,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:18,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d4b0 2016-04-20 03:51:18,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:18,129 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d4b0 2016-04-20 03:51:18,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d4b0 2016-04-20 03:51:18,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x119cd80) 2016-04-20 03:51:18,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:18,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d4e0 2016-04-20 03:51:18,176 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:18,185 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d4e0 2016-04-20 03:51:18,196 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d4e0 2016-04-20 03:51:18,207 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb46720f8 2016-04-20 03:51:18,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d470 [0x119ca20]) 2016-04-20 03:51:18,226 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64e1940 2016-04-20 03:51:18,236 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d470 [0x119ca20]) 2016-04-20 03:51:18,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,258 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-04-20 03:51:18,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d470 [0x119ca20], 0x2e0614 [0]) 2016-04-20 03:51:18,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d480 [0x119ca90]) 2016-04-20 03:51:18,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f54e0 2016-04-20 03:51:18,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d480 [0x119ca90]) 2016-04-20 03:51:18,315 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f54e0 2016-04-20 03:51:18,323 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-04-20 03:51:18,331 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d480 [0x119ca90], 0x2e0614 [0]) 2016-04-20 03:51:18,337 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,341 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d490 [0x119caf8]) 2016-04-20 03:51:18,346 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,353 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d490 [0x119caf8]) 2016-04-20 03:51:18,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,362 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-04-20 03:51:18,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d490 [0x119caf8], 0x2e0614 [0]) 2016-04-20 03:51:18,374 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,379 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4a0 [0x119cb60]) 2016-04-20 03:51:18,383 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f54e0 2016-04-20 03:51:18,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4a0 [0x119cb60]) 2016-04-20 03:51:18,393 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f54e0 2016-04-20 03:51:18,397 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-04-20 03:51:18,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d4a0 [0x119cb60], 0x2e0620 [1]) 2016-04-20 03:51:18,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d3b0 [0x119cbb8]) 2016-04-20 03:51:18,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,423 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d3b0 [0x119cbb8]) 2016-04-20 03:51:18,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,432 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-04-20 03:51:18,436 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d3b0 [0x119cbb8], 0x2e0620 [1]) 2016-04-20 03:51:18,442 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,447 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d3e0 [0x119cc10]) 2016-04-20 03:51:18,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,459 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d3e0 [0x119cc10]) 2016-04-20 03:51:18,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,468 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-04-20 03:51:18,472 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d3e0 [0x119cc10], 0x2e0614 [0]) 2016-04-20 03:51:18,477 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,482 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4d0 [0x119cc68]) 2016-04-20 03:51:18,487 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4d0 [0x119cc68]) 2016-04-20 03:51:18,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f5360 2016-04-20 03:51:18,501 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-04-20 03:51:18,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d4d0 [0x119cc68], 0x2e0614 [0]) 2016-04-20 03:51:18,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4c0 [0x119ccc0]) 2016-04-20 03:51:18,520 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f54e0 2016-04-20 03:51:18,525 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4c0 [0x119ccc0]) 2016-04-20 03:51:18,529 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64f54e0 2016-04-20 03:51:18,532 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-04-20 03:51:18,537 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d4c0 [0x119ccc0], 0x2e0614 [0]) 2016-04-20 03:51:18,541 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,548 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4b0 [0x119cd28]) 2016-04-20 03:51:18,553 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,559 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4b0 [0x119cd28]) 2016-04-20 03:51:18,564 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,569 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-04-20 03:51:18,573 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d4b0 [0x119cd28], 0x2e0614 [0]) 2016-04-20 03:51:18,580 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,585 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4e0 [0x119cd80]) 2016-04-20 03:51:18,589 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,593 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4e0 [0x119cd80]) 2016-04-20 03:51:18,597 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,601 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-04-20 03:51:18,604 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d4e0 [0x119cd80], 0x2e0614 [0]) 2016-04-20 03:51:18,611 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,615 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,620 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb466a9e0 2016-04-20 03:51:18,624 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,628 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb466a9e0 2016-04-20 03:51:18,632 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2016-04-20 03:51:18,635 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-04-20 03:51:18,639 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-20 03:51:18,642 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,646 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb466a9e0 2016-04-20 03:51:18,650 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2016-04-20 03:51:18,653 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-04-20 03:51:18,660 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-04-20 03:51:18,663 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb466a9e0 2016-04-20 03:51:18,672 [DEBUG] [doorpi.conf.config_object] create new key video_device in section SIP-Phone with value 'V4L2: /dev/video0' 2016-04-20 03:51:18,675 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2016-04-20 03:51:18,679 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,683 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb466a9e0 2016-04-20 03:51:18,688 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb46cb830 [0x119c740], 0x2e0620 [1]) 2016-04-20 03:51:18,692 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-04-20 03:51:18,698 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb46cb830 [0x119c740], "V4L2: /dev/video0") 2016-04-20 03:51:18,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-04-20 03:51:18,707 [DEBUG] [doorpi.conf.config_object] create new key video_size in section SIP-Phone with value 'vga' 2016-04-20 03:51:18,711 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-04-20 03:51:18,715 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb46cb830 [0x119c740], "vga") 2016-04-20 03:51:18,719 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-04-20 03:51:18,724 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,728 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb4672188 2016-04-20 03:51:18,732 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2016-04-20 03:51:18,745 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-04-20 03:51:18,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,758 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1172c18) 2016-04-20 03:51:18,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-04-20 03:51:18,786 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6c7d4f0 2016-04-20 03:51:18,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-04-20 03:51:18,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6c7d4f0 2016-04-20 03:51:18,816 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6c7d4f0 2016-04-20 03:51:18,825 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb466aa08 2016-04-20 03:51:18,835 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4f0 [0x1172c18]) 2016-04-20 03:51:18,844 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,863 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-04-20 03:51:18,879 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6c7d4f0 [0x1172c18]) 2016-04-20 03:51:18,888 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb46cb878 2016-04-20 03:51:18,898 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-04-20 03:51:18,905 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb46cb830 [0x119c740], 0xb6c7d4f0 [0x1172c18], 0x2e0620 [1]) 2016-04-20 03:51:18,913 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x10388bc 2016-04-20 03:51:18,917 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2016-04-20 03:51:18,921 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 620 2016-04-20 03:51:18,926 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 620) returns ******* 2016-04-20 03:51:18,932 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns fritz.box 2016-04-20 03:51:18,936 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-04-20 03:51:18,941 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb46cb830 [0x119c740]) 2016-04-20 03:51:18,947 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x11d0000) 2016-04-20 03:51:18,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-04-20 03:51:18,960 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6c7d500 2016-04-20 03:51:18,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-04-20 03:51:18,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6c7d500 2016-04-20 03:51:18,977 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6c7d500 2016-04-20 03:51:18,983 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6c7d500 2016-04-20 03:51:18,988 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2016-04-20 03:51:18,993 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2016-04-20 03:51:19,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x11c3a98) 2016-04-20 03:51:19,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2016-04-20 03:51:19,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0xb6c7d510 2016-04-20 03:51:19,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2016-04-20 03:51:19,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0xb6c7d510 2016-04-20 03:51:19,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0xb6c7d510 2016-04-20 03:51:19,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0xb6c7d510 2016-04-20 03:51:19,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6c7d500 [0x11d0000], 0xb6c7d510 [0x11c3a98]) 2016-04-20 03:51:19,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-04-20 03:51:19,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6c7d500 [0x11d0000], "sip:192.168.1.1") 2016-04-20 03:51:19,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-04-20 03:51:19,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6c7d500 [0x11d0000], 0x2e0620 [1]) 2016-04-20 03:51:19,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-04-20 03:51:19,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb46cb830 [0x119c740], 0xb6c7d500 [0x11d0000]) 2016-04-20 03:51:19,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x10388bc 2016-04-20 03:51:19,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb46cb830 [0x119c740], 0xb6c7d500 [0x11d0000]) 2016-04-20 03:51:19,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-04-20 03:51:19,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb46cb830 [0x119c740], "620", "(null)", "doorpi", "(null)", "(null)", "fritz.box") 2016-04-20 03:51:19,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x11daac0) 2016-04-20 03:51:19,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-04-20 03:51:19,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6c7d520 2016-04-20 03:51:19,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-04-20 03:51:19,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6c7d520 2016-04-20 03:51:19,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6c7d520 2016-04-20 03:51:19,129 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6c7d520 2016-04-20 03:51:19,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb46cb830 [0x119c740], 0xb6c7d520 [0x11daac0]) 2016-04-20 03:51:19,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-04-20 03:51:19,142 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-04-20 03:51:19,146 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-04-20 03:51:19,149 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-04-20 03:51:19,153 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-04-20 03:51:19,156 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-04-20 03:51:19,160 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-04-20 03:51:19,166 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-04-20 03:51:19,170 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-04-20 03:51:19,175 [TRACE] [doorpi.conf.config_object] get_string for key 37 in section onboardpins_InputPins (default: ) returns call:**610 2016-04-20 03:51:19,184 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-04-20 03:51:19,188 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**610'} was added to new evententry OnKeyPressed_onboardpins.37 2016-04-20 03:51:19,192 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"#"'] 2016-04-20 03:51:19,196 [TRACE] [doorpi.conf.config_object] get_string for key "#" in section DTMF (default: ) returns out:licht,1,0,5 2016-04-20 03:51:19,207 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2016-04-20 03:51:19,212 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2016-04-20 03:51:19,217 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 5.0, 'pin': 'licht', 'start_value': '1'} was added to new evententry OnDTMF_"#" 2016-04-20 03:51:19,221 [DEBUG] [doorpi.conf.config_object] create new key is_alive_led in section DoorPi with value '' 2016-04-20 03:51:19,224 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-04-20 03:51:19,227 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 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 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 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-04-20 03:51:21,975 [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 235, 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-04-20 03:51:22,028 [DEBUG] [doorpi.doorpi] destroy doorpi 2016-04-20 03:51:22,035 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started -1225773056)>] 2016-04-20 03:51:22,042 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 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 205, 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 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 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