2016-03-19 00:24:56,297 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.0.0 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2016-03-19 00:24:56,305 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2016-03-19 00:24:56,312 [DEBUG] [doorpi.doorpi] run 2016-03-19 00:24:56,317 [DEBUG] [doorpi.doorpi] prepare 2016-03-19 00:24:56,322 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2016-03-19 00:24:56,335 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2016-03-19 00:24:56,369 [DEBUG] [doorpi.conf.config_object] __init__ 2016-03-19 00:24:56,374 [TRACE] [doorpi.conf.config_object] get_from_config 2016-03-19 00:24:56,397 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2016-03-19 00:24:56,402 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2016-03-19 00:24:56,408 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2016-03-19 00:24:56,420 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2016-03-19 00:24:56,424 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2016-03-19 00:24:56,429 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2016-03-19 00:24:56,435 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2016-03-19 00:24:56,440 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2016-03-19 00:24:56,446 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2016-03-19 00:24:56,451 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2016-03-19 00:24:56,456 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2016-03-19 00:24:56,461 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2016-03-19 00:24:56,466 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2016-03-19 00:24:56,472 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2016-03-19 00:24:56,477 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2016-03-19 00:24:56,482 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2016-03-19 00:24:56,491 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2016-03-19 00:24:56,499 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2016-03-19 00:24:56,517 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2016-03-19 00:24:56,522 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2016-03-19 00:24:56,528 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2016-03-19 00:24:56,533 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2016-03-19 00:24:56,541 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2016-03-19 00:24:56,549 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2016-03-19 00:24:56,560 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 8080 2016-03-19 00:24:56,565 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2016-03-19 00:24:56,570 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2016-03-19 00:24:56,575 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2016-03-19 00:24:56,580 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2016-03-19 00:24:56,585 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2016-03-19 00:24:56,591 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2016-03-19 00:24:56,603 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2016-03-19 00:24:56,608 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2016-03-19 00:24:56,615 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2016-03-19 00:24:56,620 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2016-03-19 00:24:56,627 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2016-03-19 00:24:56,633 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2016-03-19 00:24:56,639 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2016-03-19 00:24:56,644 [TRACE] [doorpi.conf.config_object] get_string for key online_fallback in section DoorPiWeb (default: http://motom001.github.io/DoorPiWeb) returns http://motom001.github.io/DoorPiWeb 2016-03-19 00:24:56,651 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2016-03-19 00:24:56,658 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrator'] 2016-03-19 00:24:56,662 [WARNING] [root] section ReadPermission not found in configfile 2016-03-19 00:24:56,668 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2016-03-19 00:24:56,673 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrator'] 2016-03-19 00:24:56,678 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2016-03-19 00:24:56,684 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section Group (default: []) returns ['door'] 2016-03-19 00:24:56,689 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_keyboard', 'Group', 'keyboards', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2016-03-19 00:24:56,696 [TRACE] [doorpi.conf.config_object] get_list for key administrator in section WritePermission (default: []) returns ['installer'] 2016-03-19 00:24:56,702 [ERROR] [doorpi.status.webserver] no ReadPermission found 2016-03-19 00:24:56,709 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2016-03-19 00:24:56,714 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2016-03-19 00:24:56,719 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2016-03-19 00:24:56,727 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,732 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2016-03-19 00:24:56,739 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,745 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,751 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,756 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,763 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,769 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,774 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,780 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,785 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,790 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,795 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,800 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,806 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,812 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,817 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2016-03-19 00:24:56,823 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnTimeSecondEvenNumber 2016-03-19 00:24:56,829 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2016-03-19 00:24:56,834 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2016-03-19 00:24:56,839 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2016-03-19 00:24:56,845 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2016-03-19 00:24:56,850 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['11'] 2016-03-19 00:24:56,856 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-03-19 00:24:56,862 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2016-03-19 00:24:56,868 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2016-03-19 00:24:56,873 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2016-03-19 00:24:56,879 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2016-03-19 00:24:56,887 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2016-03-19 00:24:56,900 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['11'], output_pins = [], bouncetime = 200.0, polarity = 0) 2016-03-19 00:24:56,905 [DEBUG] [doorpi.conf.config_object] create new key mode in section onboardpins_keyboard with value 'BOARD' 2016-03-19 00:24:56,911 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BOARD 2016-03-19 00:24:56,917 [DEBUG] [doorpi.conf.config_object] create new key pull_up_down in section onboardpins_keyboard with value 'PUD_OFF' 2016-03-19 00:24:56,922 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2016-03-19 00:24:56,930 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2016-03-19 00:24:56,937 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2016-03-19 00:24:56,943 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:56,948 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_11 from doorpi.keyboard.from_gpio 2016-03-19 00:24:56,953 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_11 and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:56,958 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-19 00:24:56,965 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:56,970 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2016-03-19 00:24:56,975 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:56,980 [TRACE] [doorpi.action.handler] register Event OnKeyUp_11 from doorpi.keyboard.from_gpio 2016-03-19 00:24:56,985 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_11 and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:56,991 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-19 00:24:56,995 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:57,000 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2016-03-19 00:24:57,006 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:57,011 [TRACE] [doorpi.action.handler] register Event OnKeyDown_11 from doorpi.keyboard.from_gpio 2016-03-19 00:24:57,018 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_11 and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:57,023 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.11 from doorpi.keyboard.from_gpio 2016-03-19 00:24:57,028 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.11 and registered source doorpi.keyboard.from_gpio 2016-03-19 00:24:57,032 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2016-03-19 00:24:57,038 [WARNING] [root] section onboardpins_OutputPins not found in configfile 2016-03-19 00:24:57,044 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2016-03-19 00:24:57,053 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2016-03-19 00:24:57,062 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2016-03-19 00:24:57,072 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2016-03-19 00:24:57,081 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2016-03-19 00:24:57,217 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2016-03-19 00:24:57,228 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2016-03-19 00:24:57,239 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2016-03-19 00:24:57,417 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2016-03-19 00:24:57,441 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2016-03-19 00:24:57,446 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2016-03-19 00:24:57,455 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2016-03-19 00:24:57,465 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2016-03-19 00:24:57,474 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2016-03-19 00:24:57,487 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2016-03-19 00:24:57,495 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2016-03-19 00:24:57,500 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2016-03-19 00:24:57,506 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,510 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2016-03-19 00:24:57,516 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,521 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,526 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,533 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,537 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,541 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,547 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,552 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,557 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,562 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,567 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,572 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,577 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,584 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,590 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,595 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,600 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,605 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,610 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,616 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,621 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,626 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2016-03-19 00:24:57,631 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2016-03-19 00:24:57,638 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2016-03-19 00:24:57,643 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2016-03-19 00:24:57,648 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,653 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2016-03-19 00:24:57,659 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,664 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,669 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,674 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,679 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,685 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,691 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,696 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,701 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,706 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,712 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,716 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,722 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,728 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,733 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,744 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,749 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,754 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,761 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,773 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,781 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks Exception in thread OnWebServerStart from doorpi.status.webserver: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-19 00:24:57,787 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,799 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,803 [WARNING] [root] section DTMF not found in configfile 2016-03-19 00:24:57,808 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-19 00:24:57,814 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,818 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2016-03-19 00:24:57,824 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,829 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,834 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,840 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,846 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,851 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,856 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,861 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,866 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,871 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,877 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,882 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,887 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,894 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,899 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,904 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,909 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,914 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2016-03-19 00:24:57,920 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2016-03-19 00:24:57,928 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2016-03-19 00:24:57,934 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0xb4706878 2016-03-19 00:24:57,946 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2016-03-19 00:24:57,953 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0xb4706878 2016-03-19 00:24:57,959 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0xb65e59c0, "(null)", "(null)") 2016-03-19 00:24:57,967 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2016-03-19 00:24:57,972 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 1 2016-03-19 00:24:57,978 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2016-03-19 00:24:57,983 [INFO] [doorpi.sipphone.from_linphone] srtp init 2016-03-19 00:24:58,050 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2016-03-19 00:24:58,299 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2016-03-19 00:24:58,349 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2016-03-19 00:24:58,360 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2016-03-19 00:24:58,409 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB PnP Sound Device' added 2016-03-19 00:24:58,414 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2016-03-19 00:24:58,420 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2016-03-19 00:24:58,427 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2016-03-19 00:24:58,432 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2016-03-19 00:24:58,437 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2016-03-19 00:24:58,443 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2016-03-19 00:24:58,449 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x182e1c0] registered on core [0x182dd98] 2016-03-19 00:24:58,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x182e1c0, 1, "Starting up") 2016-03-19 00:24:58,460 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-19 00:24:58,466 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x182e1c0] notifying [global_state_changed] 2016-03-19 00:24:58,472 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2016-03-19 00:24:58,479 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2016-03-19 00:24:58,485 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2016-03-19 00:24:58,490 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2016-03-19 00:24:58,495 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=0) added to default capabilities. 2016-03-19 00:24:58,501 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 00:24:58,506 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 00:24:58,512 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-19 00:24:58,517 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2016-03-19 00:24:58,522 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2016-03-19 00:24:58,528 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2016-03-19 00:24:58,532 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2016-03-19 00:24:58,536 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2016-03-19 00:24:58,539 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2016-03-19 00:24:58,543 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2016-03-19 00:24:58,546 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2016-03-19 00:24:58,550 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2016-03-19 00:24:58,553 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2016-03-19 00:24:58,557 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2016-03-19 00:24:58,560 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2016-03-19 00:24:58,563 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2016-03-19 00:24:58,567 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-19 00:24:58,570 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-19 00:24:58,574 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 00:24:58,579 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 00:24:58,582 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 00:24:58,586 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 00:24:58,589 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 00:24:58,593 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 00:24:58,596 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 00:24:58,599 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 00:24:58,603 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2016-03-19 00:24:58,606 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2016-03-19 00:24:58,610 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2016-03-19 00:24:58,613 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2016-03-19 00:24:58,620 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2016-03-19 00:24:58,626 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 00:24:58,634 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 00:24:58,639 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 00:24:58,644 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 00:24:58,650 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2016-03-19 00:24:58,655 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2016-03-19 00:24:58,660 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2016-03-19 00:24:58,666 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2016-03-19 00:24:58,671 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2016-03-19 00:24:58,676 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2016-03-19 00:24:58,684 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2016-03-19 00:24:58,689 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2016-03-19 00:24:58,695 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2016-03-19 00:24:58,700 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2016-03-19 00:24:58,705 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2016-03-19 00:24:58,710 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2016-03-19 00:24:58,716 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2016-03-19 00:24:58,721 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2016-03-19 00:24:58,726 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2016-03-19 00:24:58,732 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2016-03-19 00:24:58,738 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2016-03-19 00:24:58,743 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2016-03-19 00:24:58,749 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2016-03-19 00:24:58,754 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2016-03-19 00:24:58,760 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2016-03-19 00:24:58,765 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2016-03-19 00:24:58,771 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2016-03-19 00:24:58,776 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2016-03-19 00:24:58,784 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2016-03-19 00:24:58,788 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-19 00:24:58,794 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2016-03-19 00:24:58,799 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2016-03-19 00:24:58,804 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2016-03-19 00:24:58,810 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2016-03-19 00:24:58,815 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2016-03-19 00:24:58,820 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2016-03-19 00:24:58,826 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2016-03-19 00:24:58,831 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2016-03-19 00:24:58,838 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2016-03-19 00:24:58,843 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2016-03-19 00:24:58,848 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2016-03-19 00:24:58,855 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2016-03-19 00:24:58,860 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2016-03-19 00:24:58,865 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2016-03-19 00:24:58,871 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2016-03-19 00:24:58,876 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2016-03-19 00:24:58,882 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2016-03-19 00:24:58,893 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-19 00:24:58,898 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:58,904 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:58,909 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:58,914 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x182e1c0, 2, "(null)") 2016-03-19 00:24:58,921 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2016-03-19 00:24:58,927 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x182e1c0] notifying [configuring_status] 2016-03-19 00:24:58,932 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2016-03-19 00:24:58,937 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2016-03-19 00:24:58,943 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2016-03-19 00:24:58,948 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2016-03-19 00:24:58,952 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 Exception in thread OnCallStart from doorpi.sipphone.linphone_lib.CallBacks: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-19 00:24:58,965 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-19 00:24:58,969 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-19 00:24:58,973 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2016-03-19 00:24:58,977 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2016-03-19 00:24:58,980 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2016-03-19 00:24:58,984 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2016-03-19 00:24:58,988 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2016-03-19 00:24:58,993 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2016-03-19 00:24:58,997 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2016-03-19 00:24:59,001 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2016-03-19 00:24:59,005 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-19 00:24:59,009 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2016-03-19 00:24:59,013 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2016-03-19 00:24:59,016 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2016-03-19 00:24:59,020 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2016-03-19 00:24:59,028 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-19 00:24:59,034 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:59,040 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-19 00:24:59,047 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1835770] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-19 00:24:59,053 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18358b8] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-19 00:24:59,058 [ERROR] [doorpi.sipphone.from_linphone] TCP bind() failed for 0.0.0.0 port 5060: Address already in use 2016-03-19 00:24:59,064 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18358b8] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-19 00:24:59,069 [WARNING] [doorpi.sipphone.from_linphone] Could not start tcp transport on port 5060, maybe this port is already used. 2016-03-19 00:24:59,075 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1835710] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-19 00:24:59,080 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 32813 2016-03-19 00:24:59,086 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:59,091 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:59,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x182e1c0, 2, "Ready") 2016-03-19 00:24:59,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2016-03-19 00:24:59,118 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x182e1c0] notifying [global_state_changed] 2016-03-19 00:24:59,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0xb4706878 2016-03-19 00:24:59,128 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns 621@192.168.1.1 2016-03-19 00:24:59,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0xb4706878 [0x182e1c0], "621@192.168.1.1 ") 2016-03-19 00:24:59,140 [ERROR] [doorpi.sipphone.from_linphone] header_address parser error for [621@192.168.1.1 ] 2016-03-19 00:24:59,146 [ERROR] [doorpi.sipphone.from_linphone] Cannot create LinphoneAddress, bad uri [621@192.168.1.1 ] 2016-03-19 00:24:59,150 [ERROR] [doorpi.sipphone.from_linphone] Bad contact url: 621@192.168.1.1 2016-03-19 00:24:59,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2016-03-19 00:24:59,160 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2016-03-19 00:24:59,166 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2016-03-19 00:24:59,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0xb4706878 [0x182e1c0], 2) 2016-03-19 00:24:59,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2016-03-19 00:24:59,182 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2016-03-19 00:24:59,186 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0xb4706878 [0x182e1c0], 0x2e0614 [0]) 2016-03-19 00:24:59,191 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2016-03-19 00:24:59,197 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-19 00:24:59,201 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2016-03-19 00:24:59,205 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2016-03-19 00:24:59,210 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0xb4706890 2016-03-19 00:24:59,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0xb4706878 [0x182e1c0], 0xb4706890 [0xb4706898]) 2016-03-19 00:24:59,220 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1835770] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2016-03-19 00:24:59,224 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x1835710] on [sip:0.0.0.0:32813;transport=TLS] destroyed 2016-03-19 00:24:59,231 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2016-03-19 00:24:59,236 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1843ba0] on [sip:0.0.0.0:5060;transport=UDP] 2016-03-19 00:24:59,242 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x18438a0] on [sip:0.0.0.0:5060;transport=TCP] 2016-03-19 00:24:59,248 [ERROR] [doorpi.sipphone.from_linphone] TCP bind() failed for 0.0.0.0 port 5060: Address already in use 2016-03-19 00:24:59,256 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x18438a0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2016-03-19 00:24:59,261 [WARNING] [doorpi.sipphone.from_linphone] Could not start tcp transport on port 5060, maybe this port is already used. 2016-03-19 00:24:59,267 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x1843af0] on [sip:0.0.0.0:-1;transport=TLS] 2016-03-19 00:24:59,273 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 46679 2016-03-19 00:24:59,278 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:59,283 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2016-03-19 00:24:59,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2016-03-19 00:24:59,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0xb4706890) 2016-03-19 00:24:59,304 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2016-03-19 00:24:59,309 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2016-03-19 00:24:59,316 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0xb4706878 [0x182e1c0], 0x2e0614 [0]) 2016-03-19 00:24:59,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2016-03-19 00:24:59,328 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2016-03-19 00:24:59,334 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0xb4706878 [0x182e1c0], "") 2016-03-19 00:24:59,342 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2016-03-19 00:24:59,347 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2016-03-19 00:24:59,356 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2016-03-19 00:24:59,361 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2016-03-19 00:24:59,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2016-03-19 00:24:59,373 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2016-03-19 00:24:59,379 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2016-03-19 00:24:59,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0xb4706878 [0x182e1c0], 0) 2016-03-19 00:24:59,389 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2016-03-19 00:24:59,395 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2016-03-19 00:24:59,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2016-03-19 00:24:59,413 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2016-03-19 00:24:59,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0xb4706878 [0x182e1c0], 120) 2016-03-19 00:24:59,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2016-03-19 00:24:59,431 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2016-03-19 00:24:59,436 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0xb4706878 [0x182e1c0], 15) 2016-03-19 00:24:59,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2016-03-19 00:24:59,447 [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-03-19 00:24:59,459 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2016-03-19 00:24:59,464 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-19 00:24:59,475 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2016-03-19 00:24:59,481 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2016-03-19 00:24:59,485 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2016-03-19 00:24:59,489 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2016-03-19 00:24:59,493 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2016-03-19 00:24:59,496 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2016-03-19 00:24:59,500 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2016-03-19 00:24:59,504 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2016-03-19 00:24:59,509 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2016-03-19 00:24:59,514 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2016-03-19 00:24:59,518 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2016-03-19 00:24:59,521 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2016-03-19 00:24:59,527 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0xb4706878 [0x182e1c0], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2016-03-19 00:24:59,533 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2016-03-19 00:24:59,543 [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-03-19 00:24:59,550 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2016-03-19 00:24:59,555 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 00:24:59,561 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2016-03-19 00:24:59,567 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-19 00:24:59,572 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 00:24:59,577 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-19 00:24:59,582 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2016-03-19 00:24:59,587 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2016-03-19 00:24:59,593 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2016-03-19 00:24:59,598 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2016-03-19 00:24:59,604 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2016-03-19 00:24:59,610 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2016-03-19 00:24:59,619 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,626 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb46720a8 2016-03-19 00:24:59,634 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,641 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4669ad0 2016-03-19 00:24:59,645 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-03-19 00:24:59,650 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0xb4706878 [0x182e1c0], "ALSA: USB PnP Sound Device") 2016-03-19 00:24:59,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2016-03-19 00:24:59,662 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4669aa0 2016-03-19 00:24:59,672 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB PnP Sound Device 2016-03-19 00:24:59,676 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0xb4706878 [0x182e1c0], "ALSA: USB PnP Sound Device") 2016-03-19 00:24:59,680 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2016-03-19 00:24:59,685 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,690 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb46720a8 2016-03-19 00:24:59,694 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2016-03-19 00:24:59,698 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2016-03-19 00:24:59,701 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 00:24:59,705 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0xb46720a8 2016-03-19 00:24:59,714 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4706878 [0x182e1c0], "ALSA: default device") 2016-03-19 00:24:59,722 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-19 00:24:59,726 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4706878 [0x182e1c0], "ALSA: default device") 2016-03-19 00:24:59,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-19 00:24:59,733 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2016-03-19 00:24:59,740 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4706878 [0x182e1c0], "ALSA: bcm2835 ALSA") 2016-03-19 00:24:59,748 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2016-03-19 00:24:59,754 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4706878 [0x182e1c0], "ALSA: bcm2835 ALSA") 2016-03-19 00:24:59,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-19 00:24:59,766 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2016-03-19 00:24:59,772 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0xb4706878 [0x182e1c0], "ALSA: USB PnP Sound Device") 2016-03-19 00:24:59,778 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2016-03-19 00:24:59,784 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0xb4706878 [0x182e1c0], "ALSA: USB PnP Sound Device") 2016-03-19 00:24:59,789 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2016-03-19 00:24:59,793 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB PnP Sound Device 2016-03-19 00:24:59,799 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 00:24:59,804 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,810 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0xb4669bc0 2016-03-19 00:24:59,818 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB PnP Sound Device 2016-03-19 00:24:59,824 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,829 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0xb4669bf0 2016-03-19 00:24:59,834 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: USB PnP Sound Device 2016-03-19 00:24:59,840 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2016-03-19 00:24:59,846 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0xb4706878 [0x182e1c0]) 2016-03-19 00:24:59,852 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x182df78) 2016-03-19 00:24:59,857 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:24:59,862 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb5470 2016-03-19 00:24:59,868 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:24:59,873 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb5470 2016-03-19 00:24:59,878 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb5470 2016-03-19 00:24:59,882 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x182dfe8) 2016-03-19 00:24:59,886 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:24:59,890 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb5480 2016-03-19 00:24:59,895 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:24:59,899 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb5480 2016-03-19 00:24:59,904 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb5480 2016-03-19 00:24:59,908 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x182e050) 2016-03-19 00:24:59,912 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:24:59,918 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb5490 2016-03-19 00:24:59,923 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:24:59,927 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb5490 2016-03-19 00:24:59,931 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb5490 2016-03-19 00:24:59,936 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x170c370) 2016-03-19 00:24:59,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:24:59,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb54a0 2016-03-19 00:24:59,951 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:24:59,959 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb54a0 2016-03-19 00:24:59,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb54a0 2016-03-19 00:24:59,972 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x170c3b8) 2016-03-19 00:24:59,977 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:24:59,982 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb54b0 2016-03-19 00:24:59,989 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:24:59,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb54b0 2016-03-19 00:25:00,000 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb54b0 2016-03-19 00:25:00,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x170c410) 2016-03-19 00:25:00,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:25:00,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb54c0 2016-03-19 00:25:00,031 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:25:00,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb54c0 2016-03-19 00:25:00,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb54c0 2016-03-19 00:25:00,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x170c468) 2016-03-19 00:25:00,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:25:00,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb54d0 2016-03-19 00:25:00,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:25:00,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb54d0 2016-03-19 00:25:00,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb54d0 2016-03-19 00:25:00,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x170c4c0) 2016-03-19 00:25:00,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:25:00,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb54e0 2016-03-19 00:25:00,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:25:00,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb54e0 2016-03-19 00:25:00,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb54e0 2016-03-19 00:25:00,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x182e4c0) 2016-03-19 00:25:00,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:25:00,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb54f0 2016-03-19 00:25:00,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:25:00,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb54f0 2016-03-19 00:25:00,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb54f0 2016-03-19 00:25:00,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x182e518) 2016-03-19 00:25:00,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:25:00,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb5500 2016-03-19 00:25:00,184 [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-03-19 00:25:00,190 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb5500 2016-03-19 00:25:00,209 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb5500 2016-03-19 00:25:00,215 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0xb46721c0 2016-03-19 00:25:00,222 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5470 [0x182df78]) 2016-03-19 00:25:00,228 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,235 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5470 [0x182df78]) 2016-03-19 00:25:00,241 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,247 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2016-03-19 00:25:00,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb5470 [0x182df78], 0x2e0614 [0]) 2016-03-19 00:25:00,258 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,264 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5480 [0x182dfe8]) 2016-03-19 00:25:00,269 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,274 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5480 [0x182dfe8]) 2016-03-19 00:25:00,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,286 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-19 00:25:00,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb5480 [0x182dfe8], 0x2e0614 [0]) 2016-03-19 00:25:00,296 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5490 [0x182e050]) 2016-03-19 00:25:00,306 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,311 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5490 [0x182e050]) 2016-03-19 00:25:00,315 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,319 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-19 00:25:00,323 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb5490 [0x182e050], 0x2e0614 [0]) 2016-03-19 00:25:00,328 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,335 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54a0 [0x170c370]) 2016-03-19 00:25:00,339 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54a0 [0x170c370]) 2016-03-19 00:25:00,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,355 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2016-03-19 00:25:00,362 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb54a0 [0x170c370], 0x2e0620 [1]) 2016-03-19 00:25:00,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54b0 [0x170c3b8]) 2016-03-19 00:25:00,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54b0 [0x170c3b8]) 2016-03-19 00:25:00,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,402 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2016-03-19 00:25:00,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb54b0 [0x170c3b8], 0x2e0620 [1]) 2016-03-19 00:25:00,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54c0 [0x170c410]) 2016-03-19 00:25:00,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54c0 [0x170c410]) 2016-03-19 00:25:00,441 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,445 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2016-03-19 00:25:00,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb54c0 [0x170c410], 0x2e0614 [0]) 2016-03-19 00:25:00,458 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,465 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54d0 [0x170c468]) 2016-03-19 00:25:00,470 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54d0 [0x170c468]) 2016-03-19 00:25:00,479 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,484 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2016-03-19 00:25:00,491 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb54d0 [0x170c468], 0x2e0614 [0]) 2016-03-19 00:25:00,496 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54e0 [0x170c4c0]) 2016-03-19 00:25:00,506 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b72c0 2016-03-19 00:25:00,511 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54e0 [0x170c4c0]) 2016-03-19 00:25:00,515 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb64b71c0 2016-03-19 00:25:00,520 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2016-03-19 00:25:00,524 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb54e0 [0x170c4c0], 0x2e0614 [0]) 2016-03-19 00:25:00,529 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,534 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54f0 [0x182e4c0]) 2016-03-19 00:25:00,539 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,544 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb54f0 [0x182e4c0]) 2016-03-19 00:25:00,548 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,553 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-19 00:25:00,559 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb54f0 [0x182e4c0], 0x2e0614 [0]) 2016-03-19 00:25:00,567 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,575 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5500 [0x182e518]) 2016-03-19 00:25:00,581 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,587 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb5500 [0x182e518]) 2016-03-19 00:25:00,597 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,603 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2016-03-19 00:25:00,608 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb5500 [0x182e518], 0x2e0614 [0]) 2016-03-19 00:25:00,615 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,621 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,628 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4664b70 2016-03-19 00:25:00,633 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,639 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4664b70 2016-03-19 00:25:00,647 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2016-03-19 00:25:00,651 [DEBUG] [doorpi.sipphone.from_linphone] | name 2016-03-19 00:25:00,656 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 00:25:00,661 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,667 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4664b70 2016-03-19 00:25:00,672 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2016-03-19 00:25:00,677 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2016-03-19 00:25:00,681 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,687 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4664b70 2016-03-19 00:25:00,691 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2016-03-19 00:25:00,698 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,703 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0xb4664b70 2016-03-19 00:25:00,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0xb4706878 [0x182e1c0], 0x2e0620 [1]) 2016-03-19 00:25:00,713 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2016-03-19 00:25:00,717 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0xb4706878 [0x182e1c0], "StaticImage: Static picture") 2016-03-19 00:25:00,722 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2016-03-19 00:25:00,726 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2016-03-19 00:25:00,730 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0xb4706878 [0x182e1c0], "vga") 2016-03-19 00:25:00,734 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2016-03-19 00:25:00,739 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,743 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0xb4669da0 2016-03-19 00:25:00,746 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2016-03-19 00:25:00,751 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2016-03-19 00:25:00,755 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,759 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x17dbb78) 2016-03-19 00:25:00,768 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2016-03-19 00:25:00,775 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0xb6cb53b0 2016-03-19 00:25:00,781 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2016-03-19 00:25:00,785 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0xb6cb53b0 2016-03-19 00:25:00,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0xb6cb53b0 2016-03-19 00:25:00,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0xb4664b98 2016-03-19 00:25:00,805 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb53b0 [0x17dbb78]) 2016-03-19 00:25:00,812 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,817 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,823 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2016-03-19 00:25:00,828 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0xb6cb53b0 [0x17dbb78]) 2016-03-19 00:25:00,836 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0xb4706890 2016-03-19 00:25:00,841 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2016-03-19 00:25:00,847 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0xb4706878 [0x182e1c0], 0xb6cb53b0 [0x17dbb78], 0x2e0620 [1]) 2016-03-19 00:25:00,855 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x169d8bc 2016-03-19 00:25:00,860 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.1 2016-03-19 00:25:00,866 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 621 2016-03-19 00:25:00,871 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 621) returns ******* 2016-03-19 00:25:00,876 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.1) returns 2016-03-19 00:25:00,880 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2016-03-19 00:25:00,885 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0xb4706878 [0x182e1c0]) 2016-03-19 00:25:00,891 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x1840998) 2016-03-19 00:25:00,896 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2016-03-19 00:25:00,903 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0xb6cb53e0 2016-03-19 00:25:00,908 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2016-03-19 00:25:00,913 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0xb6cb53e0 2016-03-19 00:25:00,917 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0xb6cb53e0 2016-03-19 00:25:00,921 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0xb6cb53e0 2016-03-19 00:25:00,925 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns 621@192.168.1.1 2016-03-19 00:25:00,929 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("621@192.168.1.1 ") 2016-03-19 00:25:00,933 [ERROR] [doorpi.sipphone.from_linphone] header_address parser error for [621@192.168.1.1 ] 2016-03-19 00:25:00,937 [ERROR] [doorpi.sipphone.from_linphone] Cannot create LinphoneAddress, bad uri [621@192.168.1.1 ] 2016-03-19 00:25:00,940 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr((nil)) 2016-03-19 00:25:00,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> None 2016-03-19 00:25:00,948 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x2d7824 2016-03-19 00:25:00,954 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0xb6cb53e0 [0x1840998], 0x2d7824 [(nil)]) 2016-03-19 00:25:00,958 [WARNING] [doorpi.sipphone.from_linphone] Invalid sip identity: NULL 2016-03-19 00:25:00,961 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2016-03-19 00:25:00,965 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0xb6cb53e0 [0x1840998], "sip:192.168.1.1") 2016-03-19 00:25:00,972 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2016-03-19 00:25:00,980 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0xb6cb53e0 [0x1840998], 0x2e0620 [1]) 2016-03-19 00:25:00,988 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2016-03-19 00:25:00,994 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0xb4706878 [0x182e1c0], 0xb6cb53e0 [0x1840998]) 2016-03-19 00:25:00,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x169d8c8 2016-03-19 00:25:01,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0xb4706878 [0x182e1c0], 0xb6cb53e0 [0x1840998]) 2016-03-19 00:25:01,011 [WARNING] [doorpi.sipphone.from_linphone] Bad proxy address: it is not in the list ! 2016-03-19 00:25:01,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2016-03-19 00:25:01,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0xb4706878 [0x182e1c0], "621", "(null)", "bandit1100", "(null)", "(null)", "") 2016-03-19 00:25:01,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x186cf18) 2016-03-19 00:25:01,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2016-03-19 00:25:01,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0xb6cb5530 2016-03-19 00:25:01,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2016-03-19 00:25:01,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0xb6cb5530 2016-03-19 00:25:01,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0xb6cb5530 2016-03-19 00:25:01,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0xb6cb5530 2016-03-19 00:25:01,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0xb4706878 [0x182e1c0], 0xb6cb5530 [0x186cf18]) 2016-03-19 00:25:01,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2016-03-19 00:25:01,083 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2016-03-19 00:25:01,088 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2016-03-19 00:25:01,093 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2016-03-19 00:25:01,097 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2016-03-19 00:25:01,102 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2016-03-19 00:25:01,108 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2016-03-19 00:25:01,114 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2016-03-19 00:25:01,120 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2016-03-19 00:25:01,124 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section onboardpins_InputPins (default: ) returns call:11 2016-03-19 00:25:01,130 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2016-03-19 00:25:01,134 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '11'} was added to new evententry OnKeyPressed_onboardpins.11 2016-03-19 00:25:01,139 [WARNING] [root] section DTMF not found in configfile 2016-03-19 00:25:01,143 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2016-03-19 00:25:01,146 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2016-03-19 00:25:01,150 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 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 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 BeforeStartup from doorpi.doorpi: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-19 00:25:04,243 [ERROR] [doorpi.main] Exception NameError: database is locked Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 152, in main_as_application try: doorpi.DoorPi(parsed_arguments).run() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 237, in run self.event_handler.fire_event_synchron('OnStartup', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2016-03-19 00:25:04,277 [DEBUG] [doorpi.doorpi] destroy doorpi 2016-03-19 00:25:04,283 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started -1225547776)>] 2016-03-19 00:25:04,288 [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 207, in destroy self.event_handler.fire_event_synchron('OnShutdown', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) sqlite3.OperationalError: database is locked 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