2018-12-30 18:37:44,353 [INFO] [doorpi.main] ___ ___ _ / \___ ___ _ __ / _ (_) VoIP Door-Intercomstation with Raspberry Pi / /\ / _ \ / _ \| '__/ /_)/ | version: 2.5.1 / /_// (_) | (_) | | / ___/| | license: CC BY-NC 4.0 /___,' \___/ \___/|_| \/ |_| URL: Authors: Thomas Meissner Supporter: Phillip Munz Hermann Dötsch Dennis Häußler Hubert Nusser Michael Hauer Andreas Schwarz Max Rößler missing someone? -> sorry -> mail me 2018-12-30 18:37:44,354 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2018-12-30 18:37:44,356 [DEBUG] [doorpi.doorpi] run 2018-12-30 18:37:44,356 [DEBUG] [doorpi.doorpi] prepare 2018-12-30 18:37:44,357 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2018-12-30 18:37:44,361 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2018-12-30 18:37:44,370 [DEBUG] [doorpi.conf.config_object] __init__ 2018-12-30 18:37:44,371 [TRACE] [doorpi.conf.config_object] get_from_config 2018-12-30 18:37:44,378 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2018-12-30 18:37:44,379 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2018-12-30 18:37:44,380 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2018-12-30 18:37:44,385 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2018-12-30 18:37:44,385 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2018-12-30 18:37:44,386 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2018-12-30 18:37:44,386 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2018-12-30 18:37:44,387 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2018-12-30 18:37:44,387 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2018-12-30 18:37:44,388 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2018-12-30 18:37:44,388 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2018-12-30 18:37:44,389 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2018-12-30 18:37:44,389 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2018-12-30 18:37:44,390 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2018-12-30 18:37:44,390 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2018-12-30 18:37:44,390 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2018-12-30 18:37:44,392 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2018-12-30 18:37:44,394 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2018-12-30 18:37:44,397 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2018-12-30 18:37:44,397 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2018-12-30 18:37:44,398 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2018-12-30 18:37:44,399 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2018-12-30 18:37:44,400 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2018-12-30 18:37:44,401 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2018-12-30 18:37:44,404 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 8080 2018-12-30 18:37:44,405 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2018-12-30 18:37:44,405 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2018-12-30 18:37:44,406 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2018-12-30 18:37:44,406 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2018-12-30 18:37:44,407 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2018-12-30 18:37:44,407 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2018-12-30 18:37:44,409 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2018-12-30 18:37:44,409 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2018-12-30 18:37:44,411 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2018-12-30 18:37:44,411 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2018-12-30 18:37:44,413 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2018-12-30 18:37:44,413 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2018-12-30 18:37:44,415 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2018-12-30 18:37:44,415 [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 2018-12-30 18:37:44,417 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2018-12-30 18:37:44,417 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2018-12-30 18:37:44,418 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrators'] 2018-12-30 18:37:44,418 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators'] 2018-12-30 18:37:44,419 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2018-12-30 18:37:44,419 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['door'] 2018-12-30 18:37:44,420 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'Group', 'ReadPermission', 'AdminNumbers', 'keyboards', 'DTMF', 'SIP-Phone', 'WritePermission', 'AREA_installer', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2018-12-30 18:37:44,421 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['installer'] 2018-12-30 18:37:44,421 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section ReadPermission (default: []) returns ['installer'] 2018-12-30 18:37:44,422 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2018-12-30 18:37:44,423 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2018-12-30 18:37:44,423 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2018-12-30 18:37:44,425 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,426 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2018-12-30 18:37:44,427 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,427 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,428 [DEBUG] [doorpi.action.handler] [LIOPMH] fire for event OnWebServerStart this actions [] 2018-12-30 18:37:44,428 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,429 [TRACE] [doorpi.action.handler] [LIOPMH] try to fire action WebServerStartupAction with args () and kwargs {} 2018-12-30 18:37:44,429 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,430 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2018-12-30 18:37:44,431 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,432 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,432 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,433 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,433 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,433 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,434 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,434 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,435 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,435 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,436 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:44,436 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnTimeSecondEvenNumber 2018-12-30 18:37:44,437 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2018-12-30 18:37:44,437 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2018-12-30 18:37:44,438 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2018-12-30 18:37:44,438 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2018-12-30 18:37:44,439 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '2'] 2018-12-30 18:37:44,439 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2018-12-30 18:37:44,440 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2018-12-30 18:37:44,441 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2018-12-30 18:37:44,441 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns False 2018-12-30 18:37:44,443 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2018-12-30 18:37:44,493 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '2'], output_pins = [], polarity = 0) 2018-12-30 18:37:44,615 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2018-12-30 18:37:44,616 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2018-12-30 18:37:44,616 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,617 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,617 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,618 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,618 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,619 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2018-12-30 18:37:44,619 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,620 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,620 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,621 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,621 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,622 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2018-12-30 18:37:44,622 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,623 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,623 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,624 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,624 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,625 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2018-12-30 18:37:44,625 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2018-12-30 18:37:44,626 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,626 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,626 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.2 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,627 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.2 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,627 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2018-12-30 18:37:44,628 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2018-12-30 18:37:44,628 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,629 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,629 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.2 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,630 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.2 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,630 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2018-12-30 18:37:44,631 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2018-12-30 18:37:44,631 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,631 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,632 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.2 from doorpi.keyboard.from_piface 2018-12-30 18:37:44,632 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.2 and registered source doorpi.keyboard.from_piface 2018-12-30 18:37:44,639 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2018-12-30 18:37:44,641 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns [] 2018-12-30 18:37:44,645 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2018-12-30 18:37:44,648 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2018-12-30 18:37:44,652 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2018-12-30 18:37:44,654 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2018-12-30 18:37:44,706 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2018-12-30 18:37:44,710 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2018-12-30 18:37:44,714 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2018-12-30 18:37:44,734 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2018-12-30 18:37:44,742 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2018-12-30 18:37:44,743 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2018-12-30 18:37:44,745 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2018-12-30 18:37:44,748 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2018-12-30 18:37:44,750 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2018-12-30 18:37:44,753 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2018-12-30 18:37:44,754 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2018-12-30 18:37:44,756 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2018-12-30 18:37:44,757 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,758 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2018-12-30 18:37:44,758 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,759 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,759 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,760 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,760 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,761 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,761 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,762 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,762 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,763 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,763 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,764 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,764 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,764 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,765 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,765 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,766 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,766 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,767 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,767 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,767 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,768 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,768 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2018-12-30 18:37:44,769 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2018-12-30 18:37:44,770 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2018-12-30 18:37:44,770 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,771 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2018-12-30 18:37:44,771 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,772 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,772 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,773 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,773 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,774 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,774 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,775 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,775 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,775 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,776 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,776 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,777 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,777 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,778 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,778 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,779 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,779 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,780 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,780 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,781 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,781 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,782 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,782 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2018-12-30 18:37:44,783 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,783 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2018-12-30 18:37:44,784 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,784 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,785 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,785 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,785 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,786 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,786 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,787 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,787 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,788 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,789 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,789 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,790 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,790 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,791 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,791 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,792 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,792 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,793 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2018-12-30 18:37:44,795 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2018-12-30 18:37:44,796 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x759902a8 2018-12-30 18:37:44,796 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:44,797 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2018-12-30 18:37:44,798 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x759902a8 2018-12-30 18:37:44,799 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x743e56f0, "(null)", "(null)") 2018-12-30 18:37:44,800 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.11.0 (git: 380f221) initialized. 2018-12-30 18:37:44,800 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2018-12-30 18:37:44,801 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done 2018-12-30 18:37:44,801 [INFO] [doorpi.sipphone.from_linphone] srtp init 2018-12-30 18:37:44,813 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2018-12-30 18:37:44,838 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added ALSA lib conf.c:4974:(snd_config_expand) Unknown parameters 0 ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL default:0 2018-12-30 18:37:44,839 [WARNING] [doorpi.sipphone.from_linphone] Could not attach mixer to card: Invalid argument 2018-12-30 18:37:44,843 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added ALSA lib conf.c:4974:(snd_config_expand) Unknown parameters 1 ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL default:1 2018-12-30 18:37:44,844 [WARNING] [doorpi.sipphone.from_linphone] Could not attach mixer to card: Invalid argument 2018-12-30 18:37:44,847 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: Dummy' added ALSA lib conf.c:4974:(snd_config_expand) Unknown parameters 2 ALSA lib control.c:1373:(snd_ctl_open_noupdate) Invalid CTL default:2 2018-12-30 18:37:44,848 [WARNING] [doorpi.sipphone.from_linphone] Could not attach mixer to card: Invalid argument 2018-12-30 18:37:44,869 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB Audio Device' added 2018-12-30 18:37:44,871 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2018-12-30 18:37:44,872 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2018-12-30 18:37:44,873 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2018-12-30 18:37:44,873 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2018-12-30 18:37:44,874 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2018-12-30 18:37:44,875 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2018-12-30 18:37:44,875 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.8.0 2018-12-30 18:37:44,876 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x4f2090] registered on core [0x5009d0] 2018-12-30 18:37:44,877 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x4f2090] notifying [global_state_changed] 2018-12-30 18:37:44,877 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x4f2090, 1, "Starting up") 2018-12-30 18:37:44,878 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2018-12-30 18:37:44,878 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.24.0 initialized. 2018-12-30 18:37:44,879 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2018-12-30 18:37:44,879 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2018-12-30 18:37:44,880 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2018-12-30 18:37:44,880 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1; stereo=0; sprop-stereo=0] number=-1, enabled=1) added to default capabilities. 2018-12-30 18:37:44,881 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-12-30 18:37:44,881 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-12-30 18:37:44,882 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2018-12-30 18:37:44,882 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2018-12-30 18:37:44,883 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2018-12-30 18:37:44,883 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2018-12-30 18:37:44,884 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2018-12-30 18:37:44,884 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2018-12-30 18:37:44,885 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2018-12-30 18:37:44,886 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2018-12-30 18:37:44,886 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2018-12-30 18:37:44,887 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2018-12-30 18:37:44,887 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2018-12-30 18:37:44,888 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2018-12-30 18:37:44,888 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2018-12-30 18:37:44,889 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2018-12-30 18:37:44,889 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-12-30 18:37:44,890 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-12-30 18:37:44,891 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-12-30 18:37:44,891 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-12-30 18:37:44,892 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-12-30 18:37:44,892 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-12-30 18:37:44,893 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-12-30 18:37:44,893 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-12-30 18:37:44,894 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-12-30 18:37:44,894 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-12-30 18:37:44,894 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2018-12-30 18:37:44,895 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2018-12-30 18:37:44,895 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2018-12-30 18:37:44,896 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-12-30 18:37:44,896 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-12-30 18:37:44,897 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-12-30 18:37:44,897 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-12-30 18:37:44,898 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-12-30 18:37:44,898 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-12-30 18:37:44,899 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2018-12-30 18:37:44,899 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2018-12-30 18:37:44,900 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2018-12-30 18:37:44,901 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2018-12-30 18:37:44,901 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2018-12-30 18:37:44,902 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2018-12-30 18:37:44,902 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2018-12-30 18:37:44,903 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2018-12-30 18:37:44,903 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2018-12-30 18:37:44,903 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2018-12-30 18:37:44,904 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2018-12-30 18:37:44,904 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2018-12-30 18:37:44,905 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2018-12-30 18:37:44,905 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2018-12-30 18:37:44,906 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2018-12-30 18:37:44,906 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2018-12-30 18:37:44,907 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2018-12-30 18:37:44,907 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2018-12-30 18:37:44,908 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2018-12-30 18:37:44,908 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2018-12-30 18:37:44,909 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2018-12-30 18:37:44,909 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2018-12-30 18:37:44,910 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2018-12-30 18:37:44,911 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2018-12-30 18:37:44,911 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2018-12-30 18:37:44,912 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2018-12-30 18:37:44,912 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2018-12-30 18:37:44,913 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2018-12-30 18:37:44,913 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2018-12-30 18:37:44,914 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2018-12-30 18:37:44,915 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2018-12-30 18:37:44,915 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2018-12-30 18:37:44,916 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2018-12-30 18:37:44,916 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2018-12-30 18:37:44,916 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2018-12-30 18:37:44,917 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2018-12-30 18:37:44,917 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2018-12-30 18:37:44,918 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2018-12-30 18:37:44,919 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2018-12-30 18:37:44,919 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,920 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,921 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,921 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x4f2090] notifying [configuring_status] 2018-12-30 18:37:44,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x4f2090, 2, "(null)") 2018-12-30 18:37:44,922 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2018-12-30 18:37:44,923 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2018-12-30 18:37:44,923 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2018-12-30 18:37:44,924 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2018-12-30 18:37:44,924 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2018-12-30 18:37:44,925 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2018-12-30 18:37:44,925 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2018-12-30 18:37:44,926 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2018-12-30 18:37:44,926 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1; stereo=0; sprop-stereo=0 automatically added to codec list. 2018-12-30 18:37:44,927 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2018-12-30 18:37:44,927 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2018-12-30 18:37:44,928 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2018-12-30 18:37:44,928 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2018-12-30 18:37:44,929 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2018-12-30 18:37:44,930 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2018-12-30 18:37:44,930 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2018-12-30 18:37:44,931 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2018-12-30 18:37:44,931 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2018-12-30 18:37:44,932 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2018-12-30 18:37:44,932 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2018-12-30 18:37:44,933 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,933 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2018-12-30 18:37:44,934 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x500fd8] on [sip:0.0.0.0:5060;transport=UDP] 2018-12-30 18:37:44,935 [ERROR] [doorpi.sipphone.from_linphone] udp bind() failed for 0.0.0.0 port 5060: Address already in use 2018-12-30 18:37:44,935 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x500fd8] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2018-12-30 18:37:44,936 [WARNING] [doorpi.sipphone.from_linphone] Could not start udp transport on port 5060, maybe this port is already used. 2018-12-30 18:37:44,936 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x4f2090] notifying [display_warning] 2018-12-30 18:37:44,937 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x500fd8] on [sip:0.0.0.0:5060;transport=TCP] 2018-12-30 18:37:44,938 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x5170f0] on [sip:0.0.0.0:-1;transport=TLS] 2018-12-30 18:37:44,938 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 43593 2018-12-30 18:37:44,939 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,940 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,943 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x4f2090] notifying [display_status] 2018-12-30 18:37:44,944 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x4f2090] notifying [global_state_changed] 2018-12-30 18:37:44,944 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x4f2090, 2, "Ready") 2018-12-30 18:37:44,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2018-12-30 18:37:44,945 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x759902a8 2018-12-30 18:37:44,946 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2018-12-30 18:37:44,946 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x759902a8 [0x4f2090], "DoorPi ") 2018-12-30 18:37:44,948 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2018-12-30 18:37:44,949 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2018-12-30 18:37:44,951 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2018-12-30 18:37:44,952 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x759902a8 [0x4f2090], 2) 2018-12-30 18:37:44,952 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2018-12-30 18:37:44,953 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2018-12-30 18:37:44,954 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2018-12-30 18:37:44,955 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x759902a8 [0x4f2090], 0x2d7690 [0]) 2018-12-30 18:37:44,955 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2018-12-30 18:37:44,956 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2018-12-30 18:37:44,957 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2018-12-30 18:37:44,957 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2018-12-30 18:37:44,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x759902c0 2018-12-30 18:37:44,958 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x759902a8 [0x4f2090], 0x759902c0 [0x759902c8]) 2018-12-30 18:37:44,959 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x500fd8] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2018-12-30 18:37:44,960 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x5170f0] on [sip:0.0.0.0:43593;transport=TLS] destroyed 2018-12-30 18:37:44,961 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2018-12-30 18:37:44,961 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x51ff28] on [sip:0.0.0.0:5060;transport=UDP] 2018-12-30 18:37:44,962 [ERROR] [doorpi.sipphone.from_linphone] udp bind() failed for 0.0.0.0 port 5060: Address already in use 2018-12-30 18:37:44,962 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x51ff28] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2018-12-30 18:37:44,963 [WARNING] [doorpi.sipphone.from_linphone] Could not start udp transport on port 5060, maybe this port is already used. 2018-12-30 18:37:44,964 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x4f2090] notifying [display_warning] 2018-12-30 18:37:44,964 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x5200a8] on [sip:0.0.0.0:5060;transport=TCP] 2018-12-30 18:37:44,965 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x51ffa8] on [sip:0.0.0.0:-1;transport=TLS] 2018-12-30 18:37:44,966 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 33493 2018-12-30 18:37:44,966 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,967 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-12-30 18:37:44,967 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2018-12-30 18:37:44,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x759902c0) 2018-12-30 18:37:44,968 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2018-12-30 18:37:44,969 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2018-12-30 18:37:44,970 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x759902a8 [0x4f2090], 0x2d7690 [0]) 2018-12-30 18:37:44,970 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2018-12-30 18:37:44,971 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2018-12-30 18:37:44,971 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x759902a8 [0x4f2090], "") 2018-12-30 18:37:44,972 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2018-12-30 18:37:44,973 [INFO] [doorpi.sipphone.from_linphone] 127.0.0.1 2018-12-30 18:37:44,975 [ERROR] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() error: Unknown error 1993380720 (1993380720) 2018-12-30 18:37:44,975 [WARNING] [doorpi.sipphone.from_linphone] Stun server resolution failed. 2018-12-30 18:37:44,976 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2018-12-30 18:37:44,977 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2018-12-30 18:37:44,977 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2018-12-30 18:37:44,978 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x759902a8 [0x4f2090], 0) 2018-12-30 18:37:44,978 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2018-12-30 18:37:44,979 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2018-12-30 18:37:44,981 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2018-12-30 18:37:44,982 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2018-12-30 18:37:44,982 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x759902a8 [0x4f2090], 120) 2018-12-30 18:37:44,983 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2018-12-30 18:37:44,983 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2018-12-30 18:37:44,984 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x759902a8 [0x4f2090], 15) 2018-12-30 18:37:44,984 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2018-12-30 18:37:44,985 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2018-12-30 18:37:44,987 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2018-12-30 18:37:44,988 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2018-12-30 18:37:44,990 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2018-12-30 18:37:44,991 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:44,991 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2018-12-30 18:37:44,992 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:44,992 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:44,993 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:44,993 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:44,994 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2018-12-30 18:37:44,994 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2018-12-30 18:37:44,995 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2018-12-30 18:37:44,995 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2018-12-30 18:37:44,996 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2018-12-30 18:37:44,998 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x759902a8 [0x4f2090], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2018-12-30 18:37:44,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2018-12-30 18:37:44,999 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:45,000 [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 2018-12-30 18:37:45,001 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2018-12-30 18:37:45,002 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,002 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2018-12-30 18:37:45,003 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,003 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,004 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,004 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,005 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,005 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2018-12-30 18:37:45,006 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2018-12-30 18:37:45,006 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2018-12-30 18:37:45,007 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2018-12-30 18:37:45,008 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,009 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x743e65a8 2018-12-30 18:37:45,010 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,010 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,011 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x743e3ec0 2018-12-30 18:37:45,012 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: Dummy) returns ALSA: USB Audio Device 2018-12-30 18:37:45,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x759902a8 [0x4f2090], "ALSA: USB Audio Device") 2018-12-30 18:37:45,013 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2018-12-30 18:37:45,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,014 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x743e8500 2018-12-30 18:37:45,015 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB Audio Device 2018-12-30 18:37:45,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x759902a8 [0x4f2090], "ALSA: USB Audio Device") 2018-12-30 18:37:45,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2018-12-30 18:37:45,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x743e6468 2018-12-30 18:37:45,018 [INFO] [doorpi.sipphone.from_linphone] found 4 possible sounddevices: 2018-12-30 18:37:45,019 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2018-12-30 18:37:45,019 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-12-30 18:37:45,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,020 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x743e6468 2018-12-30 18:37:45,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x759902a8 [0x4f2090], "ALSA: default device") 2018-12-30 18:37:45,021 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d7690 2018-12-30 18:37:45,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x759902a8 [0x4f2090], "ALSA: default device") 2018-12-30 18:37:45,022 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2018-12-30 18:37:45,023 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: default device 2018-12-30 18:37:45,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x759902a8 [0x4f2090], "ALSA: bcm2835 ALSA") 2018-12-30 18:37:45,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d7690 2018-12-30 18:37:45,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x759902a8 [0x4f2090], "ALSA: bcm2835 ALSA") 2018-12-30 18:37:45,025 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2018-12-30 18:37:45,025 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2018-12-30 18:37:45,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x759902a8 [0x4f2090], "ALSA: Dummy") 2018-12-30 18:37:45,026 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d769c 2018-12-30 18:37:45,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x759902a8 [0x4f2090], "ALSA: Dummy") 2018-12-30 18:37:45,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2018-12-30 18:37:45,028 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: Dummy 2018-12-30 18:37:45,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x759902a8 [0x4f2090], "ALSA: USB Audio Device") 2018-12-30 18:37:45,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d769c 2018-12-30 18:37:45,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x759902a8 [0x4f2090], "ALSA: USB Audio Device") 2018-12-30 18:37:45,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d769c 2018-12-30 18:37:45,031 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB Audio Device 2018-12-30 18:37:45,031 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-12-30 18:37:45,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,032 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x743e8680 2018-12-30 18:37:45,033 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB Audio Device 2018-12-30 18:37:45,033 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,034 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x743e8650 2018-12-30 18:37:45,034 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: USB Audio Device 2018-12-30 18:37:45,035 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2018-12-30 18:37:45,035 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x517768) 2018-12-30 18:37:45,036 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c96440 2018-12-30 18:37:45,037 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c96440 2018-12-30 18:37:45,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c96440 2018-12-30 18:37:45,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5177f0) 2018-12-30 18:37:45,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c96450 2018-12-30 18:37:45,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,041 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c96450 2018-12-30 18:37:45,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c96450 2018-12-30 18:37:45,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5004f8) 2018-12-30 18:37:45,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c96460 2018-12-30 18:37:45,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c96460 2018-12-30 18:37:45,044 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c96460 2018-12-30 18:37:45,045 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x500560) 2018-12-30 18:37:45,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c96470 2018-12-30 18:37:45,046 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c96470 2018-12-30 18:37:45,047 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c96470 2018-12-30 18:37:45,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5005b8) 2018-12-30 18:37:45,048 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c96480 2018-12-30 18:37:45,049 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c96480 2018-12-30 18:37:45,050 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c96480 2018-12-30 18:37:45,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5174b0) 2018-12-30 18:37:45,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c96490 2018-12-30 18:37:45,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c96490 2018-12-30 18:37:45,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c96490 2018-12-30 18:37:45,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5174f8) 2018-12-30 18:37:45,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c964a0 2018-12-30 18:37:45,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c964a0 2018-12-30 18:37:45,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c964a0 2018-12-30 18:37:45,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x517550) 2018-12-30 18:37:45,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c964b0 2018-12-30 18:37:45,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c964b0 2018-12-30 18:37:45,059 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c964b0 2018-12-30 18:37:45,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5175b8) 2018-12-30 18:37:45,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c964c0 2018-12-30 18:37:45,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c964c0 2018-12-30 18:37:45,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c964c0 2018-12-30 18:37:45,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x517610) 2018-12-30 18:37:45,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c964d0 2018-12-30 18:37:45,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c964d0 2018-12-30 18:37:45,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c964d0 2018-12-30 18:37:45,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x743e6620 2018-12-30 18:37:45,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96440 [0x517768]) 2018-12-30 18:37:45,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96440 [0x517768]) 2018-12-30 18:37:45,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,068 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2018-12-30 18:37:45,068 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c96440 [0x517768], 0x2d7690 [0]) 2018-12-30 18:37:45,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96450 [0x5177f0]) 2018-12-30 18:37:45,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3c60 2018-12-30 18:37:45,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96450 [0x5177f0]) 2018-12-30 18:37:45,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3c60 2018-12-30 18:37:45,072 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2018-12-30 18:37:45,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c96450 [0x5177f0], 0x2d7690 [0]) 2018-12-30 18:37:45,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96460 [0x5004f8]) 2018-12-30 18:37:45,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96460 [0x5004f8]) 2018-12-30 18:37:45,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,075 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2018-12-30 18:37:45,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c96460 [0x5004f8], 0x2d7690 [0]) 2018-12-30 18:37:45,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96470 [0x500560]) 2018-12-30 18:37:45,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3c60 2018-12-30 18:37:45,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96470 [0x500560]) 2018-12-30 18:37:45,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3c60 2018-12-30 18:37:45,079 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2018-12-30 18:37:45,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c96470 [0x500560], 0x2d769c [1]) 2018-12-30 18:37:45,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96480 [0x5005b8]) 2018-12-30 18:37:45,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96480 [0x5005b8]) 2018-12-30 18:37:45,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,083 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2018-12-30 18:37:45,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c96480 [0x5005b8], 0x2d769c [1]) 2018-12-30 18:37:45,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96490 [0x5174b0]) 2018-12-30 18:37:45,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c96490 [0x5174b0]) 2018-12-30 18:37:45,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,087 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2018-12-30 18:37:45,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c96490 [0x5174b0], 0x2d7690 [0]) 2018-12-30 18:37:45,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964a0 [0x5174f8]) 2018-12-30 18:37:45,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964a0 [0x5174f8]) 2018-12-30 18:37:45,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3e40 2018-12-30 18:37:45,091 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2018-12-30 18:37:45,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c964a0 [0x5174f8], 0x2d7690 [0]) 2018-12-30 18:37:45,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964b0 [0x517550]) 2018-12-30 18:37:45,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3c60 2018-12-30 18:37:45,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964b0 [0x517550]) 2018-12-30 18:37:45,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743e3c60 2018-12-30 18:37:45,095 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2018-12-30 18:37:45,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c964b0 [0x517550], 0x2d7690 [0]) 2018-12-30 18:37:45,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964c0 [0x5175b8]) 2018-12-30 18:37:45,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964c0 [0x5175b8]) 2018-12-30 18:37:45,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,099 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2018-12-30 18:37:45,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c964c0 [0x5175b8], 0x2d7690 [0]) 2018-12-30 18:37:45,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964d0 [0x517610]) 2018-12-30 18:37:45,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964d0 [0x517610]) 2018-12-30 18:37:45,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,103 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2018-12-30 18:37:45,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c964d0 [0x517610], 0x2d7690 [0]) 2018-12-30 18:37:45,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x743e65a8 2018-12-30 18:37:45,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x743e65a8 2018-12-30 18:37:45,107 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2018-12-30 18:37:45,107 [DEBUG] [doorpi.sipphone.from_linphone] | name 2018-12-30 18:37:45,108 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-12-30 18:37:45,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x743e65a8 2018-12-30 18:37:45,109 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2018-12-30 18:37:45,110 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2018-12-30 18:37:45,110 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-12-30 18:37:45,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x743e65a8 2018-12-30 18:37:45,112 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2018-12-30 18:37:45,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x743e65a8 2018-12-30 18:37:45,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x759902a8 [0x4f2090], 0x2d769c [1]) 2018-12-30 18:37:45,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2018-12-30 18:37:45,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x759902a8 [0x4f2090], "V4L2: /dev/video0") 2018-12-30 18:37:45,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2018-12-30 18:37:45,116 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2018-12-30 18:37:45,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x759902a8 [0x4f2090], "vga") 2018-12-30 18:37:45,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2018-12-30 18:37:45,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,118 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x743e6750 2018-12-30 18:37:45,118 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2018-12-30 18:37:45,119 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2018-12-30 18:37:45,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x5176d8) 2018-12-30 18:37:45,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-12-30 18:37:45,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76c964e0 2018-12-30 18:37:45,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-12-30 18:37:45,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76c964e0 2018-12-30 18:37:45,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76c964e0 2018-12-30 18:37:45,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x743e65d0 2018-12-30 18:37:45,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964e0 [0x5176d8]) 2018-12-30 18:37:45,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2d769c 2018-12-30 18:37:45,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76c964e0 [0x5176d8]) 2018-12-30 18:37:45,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x759902c0 2018-12-30 18:37:45,127 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2018-12-30 18:37:45,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x759902a8 [0x4f2090], 0x76c964e0 [0x5176d8], 0x2d769c [1]) 2018-12-30 18:37:45,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x3708cc 2018-12-30 18:37:45,129 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.0.150 2018-12-30 18:37:45,129 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 305 2018-12-30 18:37:45,130 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 305) returns ******* 2018-12-30 18:37:45,130 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.0.150) returns 192.168.0.150 2018-12-30 18:37:45,131 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2018-12-30 18:37:45,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x5172e8) 2018-12-30 18:37:45,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2018-12-30 18:37:45,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76c964f0 2018-12-30 18:37:45,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2018-12-30 18:37:45,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76c964f0 2018-12-30 18:37:45,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76c964f0 2018-12-30 18:37:45,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76c964f0 2018-12-30 18:37:45,135 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2018-12-30 18:37:45,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2018-12-30 18:37:45,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x51b0e0) 2018-12-30 18:37:45,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2018-12-30 18:37:45,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76c96500 2018-12-30 18:37:45,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2018-12-30 18:37:45,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76c96500 2018-12-30 18:37:45,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76c96500 2018-12-30 18:37:45,140 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76c96500 2018-12-30 18:37:45,141 [ERROR] [doorpi.main] Exception NameError: 'linphone.ProxyConfig' object has no attribute 'identity_address' 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 232, in run if not self.__prepared: self.prepare(self.__parsed_arguments) File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 149, in prepare self.sipphone.start() File "/usr/local/lib/python2.7/dist-packages/doorpi/sipphone/from_linphone.py", line 270, in start conf.get(SIPPHONE_SECTION, "identity", 'DoorPi'), username, server) AttributeError: 'linphone.ProxyConfig' object has no attribute 'identity_address' 2018-12-30 18:37:45,145 [DEBUG] [doorpi.doorpi] destroy doorpi 2018-12-30 18:37:45,146 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1995561152)>, , ] 2018-12-30 18:37:45,146 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2018-12-30 18:37:45,149 [DEBUG] [doorpi.action.handler] [WUBPNI] fire for event OnShutdown this actions [, , , ] 2018-12-30 18:37:45,149 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2018-12-30 18:37:45,150 [TRACE] [doorpi.action.handler] [WUBPNI] try to fire action TimeTickDestroyAction with args () and kwargs {} 2018-12-30 18:37:45,151 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2018-12-30 18:37:45,152 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2018-12-30 18:37:45,152 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,153 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2018-12-30 18:37:45,153 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2018-12-30 18:37:45,154 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,154 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2018-12-30 18:37:45,155 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2018-12-30 18:37:45,155 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,156 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2018-12-30 18:37:45,156 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2018-12-30 18:37:45,157 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,157 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2018-12-30 18:37:45,157 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2018-12-30 18:37:45,158 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,158 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2018-12-30 18:37:45,159 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2018-12-30 18:37:45,159 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,160 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2018-12-30 18:37:45,160 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2018-12-30 18:37:45,161 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,161 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2018-12-30 18:37:45,162 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2018-12-30 18:37:45,162 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,163 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2018-12-30 18:37:45,164 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2018-12-30 18:37:45,164 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,164 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2018-12-30 18:37:45,165 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2018-12-30 18:37:45,165 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,166 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2018-12-30 18:37:45,166 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2018-12-30 18:37:45,167 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,167 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2018-12-30 18:37:45,168 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2018-12-30 18:37:45,168 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,168 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2018-12-30 18:37:45,169 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2018-12-30 18:37:45,169 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,170 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2018-12-30 18:37:45,170 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2018-12-30 18:37:45,171 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,171 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2018-12-30 18:37:45,172 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2018-12-30 18:37:45,172 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,173 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2018-12-30 18:37:45,173 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2018-12-30 18:37:45,174 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,174 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2018-12-30 18:37:45,175 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2018-12-30 18:37:45,175 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,175 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2018-12-30 18:37:45,176 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2018-12-30 18:37:45,176 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,177 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2018-12-30 18:37:45,177 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2018-12-30 18:37:45,178 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,178 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2018-12-30 18:37:45,179 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2018-12-30 18:37:45,179 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,180 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2018-12-30 18:37:45,180 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2018-12-30 18:37:45,181 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,181 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2018-12-30 18:37:45,181 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2018-12-30 18:37:45,182 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,182 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2018-12-30 18:37:45,183 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2018-12-30 18:37:45,183 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,184 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2018-12-30 18:37:45,184 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2018-12-30 18:37:45,185 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,185 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2018-12-30 18:37:45,185 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2018-12-30 18:37:45,186 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,186 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2018-12-30 18:37:45,187 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2018-12-30 18:37:45,187 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,188 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2018-12-30 18:37:45,188 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2018-12-30 18:37:45,189 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,189 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2018-12-30 18:37:45,190 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2018-12-30 18:37:45,190 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,190 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2018-12-30 18:37:45,191 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2018-12-30 18:37:45,191 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,192 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2018-12-30 18:37:45,192 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2018-12-30 18:37:45,193 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,193 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2018-12-30 18:37:45,194 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2018-12-30 18:37:45,194 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,195 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2018-12-30 18:37:45,195 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2018-12-30 18:37:45,196 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,196 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2018-12-30 18:37:45,197 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2018-12-30 18:37:45,197 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,198 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2018-12-30 18:37:45,198 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2018-12-30 18:37:45,199 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,199 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2018-12-30 18:37:45,200 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2018-12-30 18:37:45,200 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,201 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2018-12-30 18:37:45,201 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2018-12-30 18:37:45,201 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,202 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2018-12-30 18:37:45,202 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2018-12-30 18:37:45,203 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,203 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2018-12-30 18:37:45,204 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2018-12-30 18:37:45,204 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,205 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2018-12-30 18:37:45,205 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2018-12-30 18:37:45,206 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,206 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2018-12-30 18:37:45,207 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2018-12-30 18:37:45,207 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,208 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2018-12-30 18:37:45,208 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2018-12-30 18:37:45,209 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,209 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2018-12-30 18:37:45,210 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2018-12-30 18:37:45,210 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,211 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2018-12-30 18:37:45,211 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2018-12-30 18:37:45,212 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,212 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2018-12-30 18:37:45,212 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2018-12-30 18:37:45,213 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,213 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2018-12-30 18:37:45,214 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2018-12-30 18:37:45,214 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,215 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2018-12-30 18:37:45,215 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2018-12-30 18:37:45,216 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,216 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2018-12-30 18:37:45,216 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2018-12-30 18:37:45,217 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,217 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2018-12-30 18:37:45,218 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2018-12-30 18:37:45,218 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,219 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2018-12-30 18:37:45,219 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2018-12-30 18:37:45,220 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,220 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2018-12-30 18:37:45,221 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2018-12-30 18:37:45,221 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,222 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2018-12-30 18:37:45,222 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2018-12-30 18:37:45,222 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,223 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2018-12-30 18:37:45,224 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2018-12-30 18:37:45,224 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,225 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2018-12-30 18:37:45,225 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2018-12-30 18:37:45,225 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,226 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2018-12-30 18:37:45,226 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2018-12-30 18:37:45,227 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,227 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2018-12-30 18:37:45,228 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2018-12-30 18:37:45,228 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,228 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2018-12-30 18:37:45,229 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2018-12-30 18:37:45,229 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,230 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2018-12-30 18:37:45,230 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2018-12-30 18:37:45,231 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,231 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2018-12-30 18:37:45,232 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2018-12-30 18:37:45,232 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,233 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2018-12-30 18:37:45,233 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2018-12-30 18:37:45,234 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,234 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2018-12-30 18:37:45,235 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2018-12-30 18:37:45,235 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,236 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2018-12-30 18:37:45,236 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2018-12-30 18:37:45,237 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,237 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2018-12-30 18:37:45,237 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2018-12-30 18:37:45,238 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,238 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2018-12-30 18:37:45,239 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2018-12-30 18:37:45,239 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,240 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2018-12-30 18:37:45,240 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2018-12-30 18:37:45,241 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,241 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2018-12-30 18:37:45,242 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2018-12-30 18:37:45,242 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,243 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2018-12-30 18:37:45,243 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2018-12-30 18:37:45,244 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,244 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2018-12-30 18:37:45,245 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2018-12-30 18:37:45,245 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,246 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2018-12-30 18:37:45,246 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2018-12-30 18:37:45,247 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,247 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2018-12-30 18:37:45,248 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2018-12-30 18:37:45,248 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,249 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2018-12-30 18:37:45,249 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2018-12-30 18:37:45,250 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,250 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2018-12-30 18:37:45,251 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2018-12-30 18:37:45,251 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,251 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2018-12-30 18:37:45,252 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2018-12-30 18:37:45,252 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,253 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2018-12-30 18:37:45,253 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2018-12-30 18:37:45,254 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,254 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2018-12-30 18:37:45,255 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2018-12-30 18:37:45,255 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,256 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2018-12-30 18:37:45,256 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2018-12-30 18:37:45,256 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,257 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2018-12-30 18:37:45,257 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2018-12-30 18:37:45,258 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,258 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2018-12-30 18:37:45,259 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2018-12-30 18:37:45,259 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,260 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2018-12-30 18:37:45,260 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2018-12-30 18:37:45,261 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,261 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2018-12-30 18:37:45,262 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2018-12-30 18:37:45,262 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,262 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2018-12-30 18:37:45,263 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2018-12-30 18:37:45,263 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,264 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2018-12-30 18:37:45,264 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2018-12-30 18:37:45,265 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,265 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2018-12-30 18:37:45,266 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2018-12-30 18:37:45,267 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,268 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2018-12-30 18:37:45,269 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2018-12-30 18:37:45,269 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,270 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2018-12-30 18:37:45,271 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2018-12-30 18:37:45,271 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,272 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2018-12-30 18:37:45,272 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2018-12-30 18:37:45,273 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,273 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2018-12-30 18:37:45,273 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2018-12-30 18:37:45,274 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,274 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2018-12-30 18:37:45,275 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2018-12-30 18:37:45,275 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,276 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2018-12-30 18:37:45,278 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2018-12-30 18:37:45,278 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,279 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2018-12-30 18:37:45,280 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2018-12-30 18:37:45,281 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,281 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2018-12-30 18:37:45,282 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2018-12-30 18:37:45,283 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,284 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2018-12-30 18:37:45,285 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2018-12-30 18:37:45,285 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,286 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2018-12-30 18:37:45,287 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2018-12-30 18:37:45,288 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,289 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2018-12-30 18:37:45,290 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2018-12-30 18:37:45,290 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,292 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2018-12-30 18:37:45,293 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2018-12-30 18:37:45,294 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,294 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2018-12-30 18:37:45,371 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2018-12-30 18:37:45,372 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,373 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2018-12-30 18:37:45,376 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2018-12-30 18:37:45,378 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,379 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2018-12-30 18:37:45,380 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2018-12-30 18:37:45,380 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,381 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2018-12-30 18:37:45,382 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2018-12-30 18:37:45,383 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,383 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2018-12-30 18:37:45,384 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2018-12-30 18:37:45,386 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,387 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2018-12-30 18:37:45,388 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2018-12-30 18:37:45,389 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,389 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2018-12-30 18:37:45,390 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2018-12-30 18:37:45,391 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,392 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2018-12-30 18:37:45,393 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2018-12-30 18:37:45,394 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,394 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2018-12-30 18:37:45,395 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2018-12-30 18:37:45,396 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,397 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2018-12-30 18:37:45,397 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2018-12-30 18:37:45,398 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,400 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2018-12-30 18:37:45,401 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2018-12-30 18:37:45,402 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2018-12-30 18:37:45,402 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2018-12-30 18:37:45,403 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2018-12-30 18:37:45,404 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2018-12-30 18:37:45,406 [TRACE] [doorpi.action.handler] [WUBPNI] try to fire action WebServerShutdownAction with args () and kwargs {} 2018-12-30 18:37:45,407 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2018-12-30 18:37:45,407 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2018-12-30 18:37:45,410 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2018-12-30 18:37:45,411 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,412 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2018-12-30 18:37:45,412 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2018-12-30 18:37:45,414 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2018-12-30 18:37:45,415 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,416 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2018-12-30 18:37:45,416 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2018-12-30 18:37:45,417 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,418 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2018-12-30 18:37:45,418 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2018-12-30 18:37:45,419 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,420 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2018-12-30 18:37:45,421 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2018-12-30 18:37:45,421 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,422 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2018-12-30 18:37:45,423 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2018-12-30 18:37:45,423 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,424 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2018-12-30 18:37:45,425 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2018-12-30 18:37:45,425 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,426 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2018-12-30 18:37:45,427 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2018-12-30 18:37:45,427 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2018-12-30 18:37:45,428 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2018-12-30 18:37:45,430 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2018-12-30 18:37:45,431 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2018-12-30 18:37:45,443 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2018-12-30 18:37:45,445 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2018-12-30 18:37:45,445 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2018-12-30 18:37:45,446 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2018-12-30 18:37:45,447 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2018-12-30 18:37:45,447 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2018-12-30 18:37:45,448 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2018-12-30 18:37:45,449 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2018-12-30 18:37:45,452 [TRACE] [doorpi.action.handler] [WUBPNI] try to fire action KeyboardDestroyAction with args () and kwargs {} 2018-12-30 18:37:45,453 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2018-12-30 18:37:45,454 [TRACE] [doorpi.action.handler] [LIOPMH] finished fire_event for event_name OnWebServerStart 2018-12-30 18:37:45,455 [DEBUG] [doorpi.keyboard.from_piface] destroy 2018-12-30 18:37:45,468 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_piface and force_unregister is True 2018-12-30 18:37:45,470 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,471 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_2 - remove event too 2018-12-30 18:37:45,472 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_2 2018-12-30 18:37:45,473 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_piface 2018-12-30 18:37:45,474 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2018-12-30 18:37:45,475 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed 2018-12-30 18:37:45,476 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_piface 2018-12-30 18:37:45,476 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2018-12-30 18:37:45,477 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp 2018-12-30 18:37:45,478 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_2 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,479 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_2 - remove event too 2018-12-30 18:37:45,481 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_2 2018-12-30 18:37:45,481 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_1 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,482 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_1 - remove event too 2018-12-30 18:37:45,483 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_1 2018-12-30 18:37:45,484 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,485 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.1 - remove event too 2018-12-30 18:37:45,486 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_onboardpins.1 2018-12-30 18:37:45,487 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.2 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,488 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.2 - remove event too 2018-12-30 18:37:45,489 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_onboardpins.2 2018-12-30 18:37:45,490 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_piface 2018-12-30 18:37:45,491 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2018-12-30 18:37:45,492 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown 2018-12-30 18:37:45,493 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,494 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.1 - remove event too 2018-12-30 18:37:45,495 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_onboardpins.1 2018-12-30 18:37:45,496 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.2 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,497 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.2 - remove event too 2018-12-30 18:37:45,498 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyUp_onboardpins.2 2018-12-30 18:37:45,498 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_2 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,499 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_2 - remove event too 2018-12-30 18:37:45,501 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_2 2018-12-30 18:37:45,501 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_1 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,502 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_1 - remove event too 2018-12-30 18:37:45,503 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_1 2018-12-30 18:37:45,504 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,505 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_1 - remove event too 2018-12-30 18:37:45,506 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyPressed_1 2018-12-30 18:37:45,507 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,508 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.1 - remove event too 2018-12-30 18:37:45,509 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_onboardpins.1 2018-12-30 18:37:45,510 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.2 from doorpi.keyboard.from_piface 2018-12-30 18:37:45,511 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.2 - remove event too 2018-12-30 18:37:45,512 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed for event OnKeyDown_onboardpins.2 2018-12-30 18:37:45,512 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was removed 2018-12-30 18:37:45,514 [TRACE] [doorpi.action.handler] [WUBPNI] try to fire action > with args () and kwargs {} 2018-12-30 18:37:45,515 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-12-30 18:37:45,516 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2018-12-30 18:37:45,516 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x759902a8 [0x4f2090]) 2018-12-30 18:37:45,517 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x3708cc 2018-12-30 18:37:45,518 [DEBUG] [doorpi.action.handler] [KETYNO] fire for event OnSipPhoneDestroy this actions [, , ] 2018-12-30 18:37:45,519 [TRACE] [doorpi.action.handler] [KETYNO] try to fire action > with args () and kwargs {} 2018-12-30 18:37:45,520 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-12-30 18:37:45,521 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2018-12-30 18:37:45,521 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2018-12-30 18:37:45,522 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,522 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2018-12-30 18:37:45,523 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2018-12-30 18:37:45,523 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,524 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2018-12-30 18:37:45,525 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2018-12-30 18:37:45,525 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,526 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2018-12-30 18:37:45,526 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2018-12-30 18:37:45,527 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,527 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2018-12-30 18:37:45,528 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2018-12-30 18:37:45,529 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,529 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2018-12-30 18:37:45,530 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2018-12-30 18:37:45,531 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,531 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2018-12-30 18:37:45,532 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2018-12-30 18:37:45,532 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,533 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2018-12-30 18:37:45,533 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2018-12-30 18:37:45,534 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,535 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2018-12-30 18:37:45,535 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2018-12-30 18:37:45,536 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,536 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2018-12-30 18:37:45,537 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2018-12-30 18:37:45,537 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,538 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2018-12-30 18:37:45,539 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2018-12-30 18:37:45,540 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,540 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2018-12-30 18:37:45,541 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2018-12-30 18:37:45,542 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,542 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2018-12-30 18:37:45,543 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2018-12-30 18:37:45,543 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,544 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2018-12-30 18:37:45,544 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2018-12-30 18:37:45,545 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,546 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2018-12-30 18:37:45,546 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2018-12-30 18:37:45,547 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,547 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2018-12-30 18:37:45,548 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2018-12-30 18:37:45,548 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,549 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2018-12-30 18:37:45,549 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2018-12-30 18:37:45,550 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,551 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2018-12-30 18:37:45,551 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2018-12-30 18:37:45,552 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,552 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2018-12-30 18:37:45,553 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2018-12-30 18:37:45,553 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,554 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2018-12-30 18:37:45,554 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2018-12-30 18:37:45,555 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,556 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2018-12-30 18:37:45,556 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2018-12-30 18:37:45,557 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-12-30 18:37:45,557 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2018-12-30 18:37:45,558 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2018-12-30 18:37:45,558 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2018-12-30 18:37:45,560 [TRACE] [doorpi.action.handler] [KETYNO] try to fire action > with args () and kwargs {} 2018-12-30 18:37:45,560 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-12-30 18:37:45,561 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2018-12-30 18:37:45,563 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2018-12-30 18:37:45,564 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:45,565 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2018-12-30 18:37:45,565 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:45,566 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:45,567 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2018-12-30 18:37:45,568 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2018-12-30 18:37:45,568 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2018-12-30 18:37:45,569 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2018-12-30 18:37:45,570 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2018-12-30 18:37:45,570 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2018-12-30 18:37:45,571 [TRACE] [doorpi.action.handler] [KETYNO] try to fire action > with args () and kwargs {} 2018-12-30 18:37:45,572 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-12-30 18:37:45,573 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2018-12-30 18:37:45,574 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,574 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2018-12-30 18:37:45,575 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2018-12-30 18:37:45,576 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,576 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2018-12-30 18:37:45,577 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2018-12-30 18:37:45,577 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2018-12-30 18:37:45,578 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2018-12-30 18:37:45,578 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2018-12-30 18:37:45,579 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2018-12-30 18:37:45,580 [TRACE] [doorpi.action.handler] [KETYNO] finished fire_event for event_name OnSipPhoneDestroy 2018-12-30 18:37:45,581 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2018-12-30 18:37:45,582 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,583 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2018-12-30 18:37:45,583 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2018-12-30 18:37:45,584 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,584 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2018-12-30 18:37:45,585 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2018-12-30 18:37:45,585 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,586 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2018-12-30 18:37:45,586 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2018-12-30 18:37:45,587 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,587 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2018-12-30 18:37:45,588 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2018-12-30 18:37:45,588 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,589 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2018-12-30 18:37:45,590 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2018-12-30 18:37:45,590 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,591 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2018-12-30 18:37:45,592 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2018-12-30 18:37:45,592 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,593 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2018-12-30 18:37:45,593 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2018-12-30 18:37:45,594 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,594 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2018-12-30 18:37:45,595 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2018-12-30 18:37:45,595 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,596 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2018-12-30 18:37:45,596 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2018-12-30 18:37:45,597 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,598 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2018-12-30 18:37:45,598 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2018-12-30 18:37:45,599 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,599 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2018-12-30 18:37:45,600 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2018-12-30 18:37:45,601 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2018-12-30 18:37:45,601 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2018-12-30 18:37:45,602 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2018-12-30 18:37:45,603 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2018-12-30 18:37:45,604 [TRACE] [doorpi.action.handler] [WUBPNI] finished fire_event for event_name OnShutdown 2018-12-30 18:37:45,604 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2018-12-30 18:37:45,606 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2018-12-30 18:37:46,107 [DEBUG] [doorpi.doorpi] wait 5 seconds for threads 1 and 1 event 2018-12-30 18:37:46,108 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:46,109 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:46,610 [DEBUG] [doorpi.doorpi] wait 4.5 seconds for threads 1 and 1 event 2018-12-30 18:37:46,611 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:46,612 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:47,114 [DEBUG] [doorpi.doorpi] wait 4.0 seconds for threads 1 and 1 event 2018-12-30 18:37:47,115 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:47,115 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:47,617 [DEBUG] [doorpi.doorpi] wait 3.5 seconds for threads 1 and 1 event 2018-12-30 18:37:47,618 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:47,619 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:48,121 [DEBUG] [doorpi.doorpi] wait 3.0 seconds for threads 1 and 1 event 2018-12-30 18:37:48,122 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:48,123 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:48,624 [DEBUG] [doorpi.doorpi] wait 2.5 seconds for threads 1 and 1 event 2018-12-30 18:37:48,625 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:48,626 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:49,127 [DEBUG] [doorpi.doorpi] wait 2.0 seconds for threads 1 and 1 event 2018-12-30 18:37:49,128 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:49,129 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:49,630 [DEBUG] [doorpi.doorpi] wait 1.5 seconds for threads 1 and 1 event 2018-12-30 18:37:49,631 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:49,632 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:50,134 [DEBUG] [doorpi.doorpi] wait 1.0 seconds for threads 1 and 1 event 2018-12-30 18:37:50,135 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:50,135 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:50,637 [DEBUG] [doorpi.doorpi] wait 0.5 seconds for threads 1 and 1 event 2018-12-30 18:37:50,638 [TRACE] [doorpi.doorpi] still existing threads: [] 2018-12-30 18:37:50,649 [TRACE] [doorpi.doorpi] still existing event sources: ['doorpi.doorpi'] 2018-12-30 18:37:51,151 [WARNING] [doorpi.doorpi] waiting for threads to time out - there are still threads: [] 2018-12-30 18:37:51,152 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ========