sudo doorpi_cli --trace 2018-01-27 13:48:06,580 [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-01-27 13:48:06,581 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2018-01-27 13:48:06,582 [DEBUG] [doorpi.doorpi] run 2018-01-27 13:48:06,583 [DEBUG] [doorpi.doorpi] prepare 2018-01-27 13:48:06,583 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2018-01-27 13:48:06,588 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2018-01-27 13:48:06,601 [DEBUG] [doorpi.conf.config_object] __init__ 2018-01-27 13:48:06,602 [TRACE] [doorpi.conf.config_object] get_from_config 2018-01-27 13:48:06,611 [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-01-27 13:48:06,611 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2018-01-27 13:48:06,613 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2018-01-27 13:48:06,620 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2018-01-27 13:48:06,621 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2018-01-27 13:48:06,622 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2018-01-27 13:48:06,622 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2018-01-27 13:48:06,623 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2018-01-27 13:48:06,623 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2018-01-27 13:48:06,624 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2018-01-27 13:48:06,624 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2018-01-27 13:48:06,625 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2018-01-27 13:48:06,625 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2018-01-27 13:48:06,626 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2018-01-27 13:48:06,626 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2018-01-27 13:48:06,627 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2018-01-27 13:48:06,630 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2018-01-27 13:48:06,632 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2018-01-27 13:48:06,635 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2018-01-27 13:48:06,636 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2018-01-27 13:48:06,637 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2018-01-27 13:48:06,637 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2018-01-27 13:48:06,640 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 80 2018-01-27 13:48:06,641 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2018-01-27 13:48:06,641 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2018-01-27 13:48:06,642 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2018-01-27 13:48:06,643 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2018-01-27 13:48:06,643 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2018-01-27 13:48:06,644 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2018-01-27 13:48:06,645 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2018-01-27 13:48:06,646 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2018-01-27 13:48:06,648 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2018-01-27 13:48:06,648 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2018-01-27 13:48:06,650 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2018-01-27 13:48:06,650 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2018-01-27 13:48:06,652 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2018-01-27 13:48:06,653 [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-01-27 13:48:06,654 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2018-01-27 13:48:06,655 [WARNING] [root] section WritePermission not found in configfile 2018-01-27 13:48:06,655 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2018-01-27 13:48:06,656 [WARNING] [root] section ReadPermission not found in configfile 2018-01-27 13:48:06,656 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2018-01-27 13:48:06,657 [WARNING] [root] section Group not found in configfile 2018-01-27 13:48:06,658 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2018-01-27 13:48:06,658 [WARNING] [root] section User not found in configfile 2018-01-27 13:48:06,659 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2018-01-27 13:48:06,659 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'EVENT_OnKeyUp_onboardpins.13', 'EVENT_OnKeyUp_onboardpins.6', 'EVENT_OnKeyUp_onboardpins.5', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'EVENT_OnStartup', 'EVENT_OnKeyUp_onboardpins.19', 'DoorPiWeb', 'EVENT_OnKeyUp_onboardpins.26', 'onboardpins_InputPins', 'DoorPi'] 2018-01-27 13:48:06,660 [ERROR] [doorpi.status.webserver] no ReadPermission found 2018-01-27 13:48:06,661 [ERROR] [doorpi.status.webserver] no groups in configfile! 2018-01-27 13:48:06,661 [ERROR] [doorpi.status.webserver] no WritePermission found 2018-01-27 13:48:06,662 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2018-01-27 13:48:06,663 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2018-01-27 13:48:06,663 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2018-01-27 13:48:06,665 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,666 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2018-01-27 13:48:06,667 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,667 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,668 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,668 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,669 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,670 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,671 [DEBUG] [doorpi.action.handler] [78Z12F] fire for event OnWebServerStart this actions [] 2018-01-27 13:48:06,671 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,672 [TRACE] [doorpi.action.handler] [78Z12F] try to fire action WebServerStartupAction with args () and kwargs {} 2018-01-27 13:48:06,673 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,674 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2018-01-27 13:48:06,675 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,677 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,677 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,678 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,678 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,679 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,679 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2018-01-27 13:48:06,680 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2018-01-27 13:48:06,681 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2018-01-27 13:48:06,681 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2018-01-27 13:48:06,682 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns gpio 2018-01-27 13:48:06,683 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['13', '27', '19', '26', '5', '6'] 2018-01-27 13:48:06,683 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['12', '20', '21', '17', '16', '3', '2', '4', '7'] 2018-01-27 13:48:06,684 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 1000.0 2018-01-27 13:48:06,685 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2018-01-27 13:48:06,685 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2018-01-27 13:48:06,687 [DEBUG] [doorpi.keyboard.from_gpio] doorpi.keyboard.from_gpio loaded 2018-01-27 13:48:06,691 [DEBUG] [doorpi.keyboard.from_gpio] __init__(input_pins = ['13', '27', '19', '26', '5', '6'], output_pins = ['12', '20', '21', '17', '16', '3', '2', '4', '7'], bouncetime = 1000.0, polarity = 0) 2018-01-27 13:48:06,692 [TRACE] [doorpi.conf.config_object] get_string for key mode in section onboardpins_keyboard (default: BOARD) returns BCM 2018-01-27 13:48:06,693 [TRACE] [doorpi.conf.config_object] get_string for key pull_up_down in section onboardpins_keyboard (default: PUD_OFF) returns PUD_OFF 2018-01-27 13:48:06,695 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,696 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was added 2018-01-27 13:48:06,697 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,697 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_13 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,698 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_13 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,699 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.13 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,699 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.13 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,700 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,701 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,702 [TRACE] [doorpi.action.handler] register Event OnKeyUp_13 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,702 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_13 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,703 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.13 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,704 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.13 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,704 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,705 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,706 [TRACE] [doorpi.action.handler] register Event OnKeyDown_13 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,706 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_13 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,707 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.13 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,708 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.13 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,709 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,711 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,712 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_27 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,712 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_27 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,713 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.27 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,714 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.27 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,715 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,715 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,716 [TRACE] [doorpi.action.handler] register Event OnKeyUp_27 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,717 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_27 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,718 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.27 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,719 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.27 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,720 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,721 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,722 [TRACE] [doorpi.action.handler] register Event OnKeyDown_27 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,723 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_27 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,724 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.27 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,725 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.27 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,726 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,727 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,728 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_19 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,729 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_19 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,731 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.19 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,732 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.19 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,732 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,733 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,734 [TRACE] [doorpi.action.handler] register Event OnKeyUp_19 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,735 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_19 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,736 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.19 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,737 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.19 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,738 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,739 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,740 [TRACE] [doorpi.action.handler] register Event OnKeyDown_19 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,741 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_19 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,741 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.19 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,742 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.19 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,746 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,748 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,749 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_26 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,750 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_26 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,751 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.26 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,752 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.26 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,753 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,754 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,754 [TRACE] [doorpi.action.handler] register Event OnKeyUp_26 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,755 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_26 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,756 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.26 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,757 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.26 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,758 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,759 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,760 [TRACE] [doorpi.action.handler] register Event OnKeyDown_26 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,761 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_26 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,762 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.26 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,763 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.26 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,765 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,766 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,767 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_5 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,768 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_5 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,769 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.5 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,770 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.5 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,771 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,772 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,773 [TRACE] [doorpi.action.handler] register Event OnKeyUp_5 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,774 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_5 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,775 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.5 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,776 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.5 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,777 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,778 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,779 [TRACE] [doorpi.action.handler] register Event OnKeyDown_5 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,779 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_5 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,780 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.5 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,781 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.5 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,783 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,784 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,785 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_6 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,786 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_6 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,787 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.6 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,788 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.6 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,789 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,790 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,791 [TRACE] [doorpi.action.handler] register Event OnKeyUp_6 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,792 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_6 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,793 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.6 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,794 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.6 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,795 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,796 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_gpio is already known 2018-01-27 13:48:06,797 [TRACE] [doorpi.action.handler] register Event OnKeyDown_6 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,798 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_6 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,799 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.6 from doorpi.keyboard.from_gpio 2018-01-27 13:48:06,800 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.6 and registered source doorpi.keyboard.from_gpio 2018-01-27 13:48:06,817 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2018-01-27 13:48:06,819 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['12', '20', '21', '17', '16', '3', '2', '4', '7'] 2018-01-27 13:48:06,820 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section onboardpins_OutputPins (default: ) returns KlingelLED2 2018-01-27 13:48:06,821 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section onboardpins_OutputPins (default: ) returns KlingelLED4 2018-01-27 13:48:06,822 [TRACE] [doorpi.conf.config_object] get_string for key 21 in section onboardpins_OutputPins (default: ) returns KlingelLED5 2018-01-27 13:48:06,823 [TRACE] [doorpi.conf.config_object] get_string for key 17 in section onboardpins_OutputPins (default: ) returns Verstarker 2018-01-27 13:48:06,824 [TRACE] [doorpi.conf.config_object] get_string for key 16 in section onboardpins_OutputPins (default: ) returns KlingelLED3 2018-01-27 13:48:06,825 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_OutputPins (default: ) returns Relais1 2018-01-27 13:48:06,826 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_OutputPins (default: ) returns HGBel 2018-01-27 13:48:06,827 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section onboardpins_OutputPins (default: ) returns Relais2 2018-01-27 13:48:06,827 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_OutputPins (default: ) returns KlingelLED1 2018-01-27 13:48:06,830 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2018-01-27 13:48:06,833 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2018-01-27 13:48:06,836 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2018-01-27 13:48:06,838 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2018-01-27 13:48:06,894 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2018-01-27 13:48:06,898 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2018-01-27 13:48:06,901 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2018-01-27 13:48:06,972 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2018-01-27 13:48:06,981 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2018-01-27 13:48:06,982 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2018-01-27 13:48:06,984 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2018-01-27 13:48:06,987 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2018-01-27 13:48:06,989 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2018-01-27 13:48:06,992 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2018-01-27 13:48:06,994 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2018-01-27 13:48:06,994 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2018-01-27 13:48:06,995 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2018-01-27 13:48:06,996 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2018-01-27 13:48:06,996 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:06,997 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2018-01-27 13:48:06,997 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:06,998 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2018-01-27 13:48:06,998 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:06,999 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,000 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,000 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,001 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,001 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,002 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,002 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,003 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,003 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,004 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,005 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,005 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,006 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,006 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,007 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,007 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,008 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,008 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2018-01-27 13:48:07,009 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2018-01-27 13:48:07,010 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2018-01-27 13:48:07,010 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,011 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2018-01-27 13:48:07,011 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,012 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,012 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,013 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,014 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,014 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,015 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,015 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,016 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,016 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,017 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,017 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,018 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,018 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,019 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,020 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,020 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,021 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,021 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,022 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,022 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,023 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,023 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,024 [WARNING] [root] section DTMF not found in configfile 2018-01-27 13:48:07,025 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2018-01-27 13:48:07,025 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,026 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2018-01-27 13:48:07,026 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,027 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,027 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,028 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,028 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,029 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,030 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,030 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,031 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,031 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,032 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,032 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,033 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,033 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,034 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,035 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,035 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,036 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,036 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2018-01-27 13:48:07,038 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2018-01-27 13:48:07,039 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x743b7f50 2018-01-27 13:48:07,040 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:48:07,040 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2018-01-27 13:48:07,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x743b7f50 2018-01-27 13:48:07,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x74322a50, "(null)", "(null)") 2018-01-27 13:48:07,043 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.1 (git: 3.9.1) initialized. 2018-01-27 13:48:07,044 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2018-01-27 13:48:07,044 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2018-01-27 13:48:07,045 [INFO] [doorpi.sipphone.from_linphone] srtp init 2018-01-27 13:48:07,058 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2018-01-27 13:48:07,151 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2018-01-27 13:48:07,168 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1602 - open '/dev/snd/pcmC0D0c' failed (-2) 2018-01-27 13:48:07,171 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2018-01-27 13:48:07,188 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: USB Audio Device' added 2018-01-27 13:48:07,189 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2018-01-27 13:48:07,190 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2018-01-27 13:48:07,191 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2018-01-27 13:48:07,192 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2018-01-27 13:48:07,192 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2018-01-27 13:48:07,193 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.1 2018-01-27 13:48:07,194 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x21c6370] registered on core [0x21c5130] 2018-01-27 13:48:07,194 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x21c6370, 1, "Starting up") 2018-01-27 13:48:07,195 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2018-01-27 13:48:07,195 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [global_state_changed] 2018-01-27 13:48:07,196 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2018-01-27 13:48:07,197 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2018-01-27 13:48:07,197 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2018-01-27 13:48:07,198 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2018-01-27 13:48:07,199 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2018-01-27 13:48:07,199 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-01-27 13:48:07,200 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-01-27 13:48:07,201 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2018-01-27 13:48:07,202 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2018-01-27 13:48:07,202 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2018-01-27 13:48:07,203 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2018-01-27 13:48:07,203 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2018-01-27 13:48:07,204 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2018-01-27 13:48:07,205 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2018-01-27 13:48:07,205 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2018-01-27 13:48:07,206 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2018-01-27 13:48:07,207 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2018-01-27 13:48:07,207 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2018-01-27 13:48:07,208 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2018-01-27 13:48:07,208 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2018-01-27 13:48:07,209 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2018-01-27 13:48:07,210 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2018-01-27 13:48:07,210 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2018-01-27 13:48:07,211 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-01-27 13:48:07,212 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-01-27 13:48:07,212 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-01-27 13:48:07,213 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-01-27 13:48:07,213 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-01-27 13:48:07,214 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-01-27 13:48:07,215 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-01-27 13:48:07,215 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-01-27 13:48:07,216 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2018-01-27 13:48:07,216 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2018-01-27 13:48:07,217 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2018-01-27 13:48:07,218 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2018-01-27 13:48:07,218 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2018-01-27 13:48:07,219 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-01-27 13:48:07,219 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-01-27 13:48:07,220 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-01-27 13:48:07,221 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-01-27 13:48:07,221 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2018-01-27 13:48:07,222 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2018-01-27 13:48:07,223 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2018-01-27 13:48:07,223 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2018-01-27 13:48:07,224 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2018-01-27 13:48:07,224 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2018-01-27 13:48:07,225 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2018-01-27 13:48:07,226 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2018-01-27 13:48:07,226 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2018-01-27 13:48:07,227 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2018-01-27 13:48:07,227 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2018-01-27 13:48:07,228 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2018-01-27 13:48:07,229 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2018-01-27 13:48:07,229 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2018-01-27 13:48:07,230 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2018-01-27 13:48:07,231 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2018-01-27 13:48:07,231 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2018-01-27 13:48:07,232 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2018-01-27 13:48:07,233 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2018-01-27 13:48:07,233 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2018-01-27 13:48:07,234 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2018-01-27 13:48:07,234 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2018-01-27 13:48:07,235 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2018-01-27 13:48:07,236 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2018-01-27 13:48:07,236 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2018-01-27 13:48:07,237 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2018-01-27 13:48:07,237 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2018-01-27 13:48:07,238 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2018-01-27 13:48:07,239 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2018-01-27 13:48:07,239 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2018-01-27 13:48:07,240 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2018-01-27 13:48:07,241 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2018-01-27 13:48:07,242 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2018-01-27 13:48:07,242 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2018-01-27 13:48:07,243 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2018-01-27 13:48:07,243 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2018-01-27 13:48:07,244 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2018-01-27 13:48:07,245 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2018-01-27 13:48:07,245 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2018-01-27 13:48:07,246 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2018-01-27 13:48:07,247 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2018-01-27 13:48:07,247 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2018-01-27 13:48:07,248 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2018-01-27 13:48:07,249 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2018-01-27 13:48:07,249 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,250 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,251 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,251 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x21c6370, 2, "(null)") 2018-01-27 13:48:07,252 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2018-01-27 13:48:07,252 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [configuring_status] 2018-01-27 13:48:07,253 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2018-01-27 13:48:07,254 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2018-01-27 13:48:07,254 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2018-01-27 13:48:07,255 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2018-01-27 13:48:07,256 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2018-01-27 13:48:07,256 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2018-01-27 13:48:07,257 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2018-01-27 13:48:07,257 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2018-01-27 13:48:07,258 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2018-01-27 13:48:07,259 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2018-01-27 13:48:07,259 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,260 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,261 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,261 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,262 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2018-01-27 13:48:07,263 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2018-01-27 13:48:07,263 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2018-01-27 13:48:07,264 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,264 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,265 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2018-01-27 13:48:07,266 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2018-01-27 13:48:07,266 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,267 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2018-01-27 13:48:07,268 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21c1168] on [sip:0.0.0.0:5060;transport=UDP] 2018-01-27 13:48:07,269 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21c1948] on [sip:0.0.0.0:5060;transport=TCP] 2018-01-27 13:48:07,269 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21c1b10] on [sip:0.0.0.0:-1;transport=TLS] 2018-01-27 13:48:07,270 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 34793 2018-01-27 13:48:07,271 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,272 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,276 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x21c6370, 2, "Ready") 2018-01-27 13:48:07,277 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2018-01-27 13:48:07,277 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [global_state_changed] 2018-01-27 13:48:07,278 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x743b7f50 2018-01-27 13:48:07,278 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2018-01-27 13:48:07,279 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x743b7f50 [0x21c6370], "DoorPi ") 2018-01-27 13:48:07,281 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2018-01-27 13:48:07,281 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2018-01-27 13:48:07,283 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 1 2018-01-27 13:48:07,284 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x743b7f50 [0x21c6370], 1) 2018-01-27 13:48:07,285 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2018-01-27 13:48:07,285 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2018-01-27 13:48:07,286 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2018-01-27 13:48:07,287 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x743b7f50 [0x21c6370], 0x2d6690 [0]) 2018-01-27 13:48:07,288 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2018-01-27 13:48:07,288 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2018-01-27 13:48:07,289 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2018-01-27 13:48:07,289 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2018-01-27 13:48:07,290 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x743b7f68 2018-01-27 13:48:07,291 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x743b7f50 [0x21c6370], 0x743b7f68 [0x743b7f70]) 2018-01-27 13:48:07,292 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x21c1168] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2018-01-27 13:48:07,292 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x21c1948] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2018-01-27 13:48:07,293 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x21c1b10] on [sip:0.0.0.0:34793;transport=TLS] destroyed 2018-01-27 13:48:07,294 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2018-01-27 13:48:07,294 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21fce10] on [sip:0.0.0.0:5060;transport=UDP] 2018-01-27 13:48:07,295 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21fd010] on [sip:0.0.0.0:5060;transport=TCP] 2018-01-27 13:48:07,296 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x21fcd10] on [sip:0.0.0.0:-1;transport=TLS] 2018-01-27 13:48:07,297 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 38793 2018-01-27 13:48:07,297 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,298 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2018-01-27 13:48:07,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2018-01-27 13:48:07,299 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x743b7f68) 2018-01-27 13:48:07,300 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2018-01-27 13:48:07,301 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2018-01-27 13:48:07,301 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x743b7f50 [0x21c6370], 0x2d6690 [0]) 2018-01-27 13:48:07,302 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2018-01-27 13:48:07,303 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2018-01-27 13:48:07,303 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x743b7f50 [0x21c6370], "") 2018-01-27 13:48:07,304 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2018-01-27 13:48:07,305 [INFO] [doorpi.sipphone.from_linphone] 192.168.130.254 2018-01-27 13:48:07,306 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2018-01-27 13:48:07,307 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2018-01-27 13:48:07,308 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2018-01-27 13:48:07,308 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2018-01-27 13:48:07,309 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2018-01-27 13:48:07,309 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x743b7f50 [0x21c6370], 0) 2018-01-27 13:48:07,310 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2018-01-27 13:48:07,311 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2018-01-27 13:48:07,312 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2018-01-27 13:48:07,313 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2018-01-27 13:48:07,314 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x743b7f50 [0x21c6370], 120) 2018-01-27 13:48:07,314 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2018-01-27 13:48:07,315 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2018-01-27 13:48:07,316 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x743b7f50 [0x21c6370], 15) 2018-01-27 13:48:07,316 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2018-01-27 13:48:07,317 [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-01-27 13:48:07,319 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2018-01-27 13:48:07,320 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2018-01-27 13:48:07,323 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2018-01-27 13:48:07,323 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:48:07,324 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2018-01-27 13:48:07,325 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2018-01-27 13:48:07,325 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:48:07,326 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2018-01-27 13:48:07,326 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:48:07,327 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2018-01-27 13:48:07,327 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2018-01-27 13:48:07,328 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2018-01-27 13:48:07,329 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2018-01-27 13:48:07,329 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2018-01-27 13:48:07,331 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x743b7f50 [0x21c6370], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2018-01-27 13:48:07,332 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2018-01-27 13:48:07,332 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:48:07,333 [DEBUG] [doorpi.conf.config_object] create new key records in section SIP-Phone with value '!BASEPATH!/records/%Y-%m-%d_%H-%M-%S.wav' 2018-01-27 13:48:07,334 [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-01-27 13:48:07,335 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2018-01-27 13:48:07,336 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,336 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2018-01-27 13:48:07,337 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,337 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,338 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,338 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,339 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,339 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2018-01-27 13:48:07,340 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2018-01-27 13:48:07,341 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2018-01-27 13:48:07,342 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2018-01-27 13:48:07,343 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,344 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x74328260 2018-01-27 13:48:07,345 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:48:07,345 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,346 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x743267d0 2018-01-27 13:48:07,347 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: USB Audio Device 2018-01-27 13:48:07,347 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x743b7f50 [0x21c6370], "ALSA: USB Audio Device") 2018-01-27 13:48:07,348 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2018-01-27 13:48:07,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,349 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x743267a0 2018-01-27 13:48:07,350 [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-01-27 13:48:07,351 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x743b7f50 [0x21c6370], "ALSA: USB Audio Device") 2018-01-27 13:48:07,351 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2018-01-27 13:48:07,352 [TRACE] [doorpi.conf.config_object] get_integer for key mic_gain_db in section SIP-Phone (default: 0) returns 0.0 2018-01-27 13:48:07,353 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_mic_gain_db(0x743b7f50 [0x21c6370], 0.000000) 2018-01-27 13:48:07,353 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2018-01-27 13:48:07,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_mic_gain_db -> 0 2018-01-27 13:48:07,354 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,355 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x743280d0 2018-01-27 13:48:07,356 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2018-01-27 13:48:07,357 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2018-01-27 13:48:07,357 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-01-27 13:48:07,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,358 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x743280d0 2018-01-27 13:48:07,359 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x743b7f50 [0x21c6370], "ALSA: default device") 2018-01-27 13:48:07,359 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d669c 2018-01-27 13:48:07,360 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x743b7f50 [0x21c6370], "ALSA: default device") 2018-01-27 13:48:07,361 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d669c 2018-01-27 13:48:07,361 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2018-01-27 13:48:07,362 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x743b7f50 [0x21c6370], "ALSA: bcm2835 ALSA") 2018-01-27 13:48:07,363 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d6690 2018-01-27 13:48:07,363 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x743b7f50 [0x21c6370], "ALSA: bcm2835 ALSA") 2018-01-27 13:48:07,364 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d669c 2018-01-27 13:48:07,364 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2018-01-27 13:48:07,365 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x743b7f50 [0x21c6370], "ALSA: USB Audio Device") 2018-01-27 13:48:07,366 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2d669c 2018-01-27 13:48:07,366 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x743b7f50 [0x21c6370], "ALSA: USB Audio Device") 2018-01-27 13:48:07,367 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2d669c 2018-01-27 13:48:07,367 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB Audio Device 2018-01-27 13:48:07,368 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-01-27 13:48:07,368 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,369 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x743268c0 2018-01-27 13:48:07,370 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: USB Audio Device 2018-01-27 13:48:07,370 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,371 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x743268f0 2018-01-27 13:48:07,371 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: USB Audio Device 2018-01-27 13:48:07,372 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_mic_gain_db(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,373 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_mic_gain_db -> 0x1fb4f20 2018-01-27 13:48:07,373 [DEBUG] [doorpi.sipphone.from_linphone] mic_gain_db: 0.0 2018-01-27 13:48:07,374 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2018-01-27 13:48:07,375 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1600) 2018-01-27 13:48:07,376 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a94b0 2018-01-27 13:48:07,377 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,378 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a94b0 2018-01-27 13:48:07,379 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a94b0 2018-01-27 13:48:07,379 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1658) 2018-01-27 13:48:07,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,380 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9700 2018-01-27 13:48:07,381 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9700 2018-01-27 13:48:07,382 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9700 2018-01-27 13:48:07,383 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c16c0) 2018-01-27 13:48:07,383 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,384 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9930 2018-01-27 13:48:07,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,385 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9930 2018-01-27 13:48:07,386 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9930 2018-01-27 13:48:07,386 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1728) 2018-01-27 13:48:07,387 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,387 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9550 2018-01-27 13:48:07,388 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,389 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9550 2018-01-27 13:48:07,389 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9550 2018-01-27 13:48:07,390 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1410) 2018-01-27 13:48:07,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,391 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9cc0 2018-01-27 13:48:07,392 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,392 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9cc0 2018-01-27 13:48:07,393 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9cc0 2018-01-27 13:48:07,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1468) 2018-01-27 13:48:07,394 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,395 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9900 2018-01-27 13:48:07,395 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,396 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9900 2018-01-27 13:48:07,396 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9900 2018-01-27 13:48:07,397 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c14c0) 2018-01-27 13:48:07,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,398 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9910 2018-01-27 13:48:07,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,399 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9910 2018-01-27 13:48:07,400 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9910 2018-01-27 13:48:07,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1518) 2018-01-27 13:48:07,401 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,402 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9b50 2018-01-27 13:48:07,402 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,403 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9b50 2018-01-27 13:48:07,404 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9b50 2018-01-27 13:48:07,404 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c1580) 2018-01-27 13:48:07,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,405 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9730 2018-01-27 13:48:07,406 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,407 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9730 2018-01-27 13:48:07,407 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9730 2018-01-27 13:48:07,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x21c0f30) 2018-01-27 13:48:07,408 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,409 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9b80 2018-01-27 13:48:07,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,410 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9b80 2018-01-27 13:48:07,411 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9b80 2018-01-27 13:48:07,411 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x743282d8 2018-01-27 13:48:07,412 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a94b0 [0x21c1600]) 2018-01-27 13:48:07,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,413 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a94b0 [0x21c1600]) 2018-01-27 13:48:07,414 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,414 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2018-01-27 13:48:07,415 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a94b0 [0x21c1600], 0x2d6690 [0]) 2018-01-27 13:48:07,416 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,416 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9700 [0x21c1658]) 2018-01-27 13:48:07,417 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743274e0 2018-01-27 13:48:07,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9700 [0x21c1658]) 2018-01-27 13:48:07,418 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743274e0 2018-01-27 13:48:07,419 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2018-01-27 13:48:07,419 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9700 [0x21c1658], 0x2d6690 [0]) 2018-01-27 13:48:07,420 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,421 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9930 [0x21c16c0]) 2018-01-27 13:48:07,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,422 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9930 [0x21c16c0]) 2018-01-27 13:48:07,423 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,423 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2018-01-27 13:48:07,424 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9930 [0x21c16c0], 0x2d6690 [0]) 2018-01-27 13:48:07,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,425 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9550 [0x21c1728]) 2018-01-27 13:48:07,426 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743274e0 2018-01-27 13:48:07,427 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9550 [0x21c1728]) 2018-01-27 13:48:07,427 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743274e0 2018-01-27 13:48:07,428 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2018-01-27 13:48:07,428 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9550 [0x21c1728], 0x2d669c [1]) 2018-01-27 13:48:07,429 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,430 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9cc0 [0x21c1410]) 2018-01-27 13:48:07,430 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,431 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9cc0 [0x21c1410]) 2018-01-27 13:48:07,432 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,432 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2018-01-27 13:48:07,433 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9cc0 [0x21c1410], 0x2d669c [1]) 2018-01-27 13:48:07,434 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,434 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9900 [0x21c1468]) 2018-01-27 13:48:07,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,435 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9900 [0x21c1468]) 2018-01-27 13:48:07,436 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,436 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2018-01-27 13:48:07,437 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9900 [0x21c1468], 0x2d6690 [0]) 2018-01-27 13:48:07,438 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,439 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9910 [0x21c14c0]) 2018-01-27 13:48:07,439 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,440 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9910 [0x21c14c0]) 2018-01-27 13:48:07,440 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743272e0 2018-01-27 13:48:07,441 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2018-01-27 13:48:07,442 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9910 [0x21c14c0], 0x2d6690 [0]) 2018-01-27 13:48:07,442 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,443 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9b50 [0x21c1518]) 2018-01-27 13:48:07,444 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743274e0 2018-01-27 13:48:07,444 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9b50 [0x21c1518]) 2018-01-27 13:48:07,445 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743274e0 2018-01-27 13:48:07,445 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2018-01-27 13:48:07,446 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9b50 [0x21c1518], 0x2d6690 [0]) 2018-01-27 13:48:07,447 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,448 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9730 [0x21c1580]) 2018-01-27 13:48:07,448 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,449 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9730 [0x21c1580]) 2018-01-27 13:48:07,449 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,450 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2018-01-27 13:48:07,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9730 [0x21c1580], 0x2d6690 [0]) 2018-01-27 13:48:07,451 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,452 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9b80 [0x21c0f30]) 2018-01-27 13:48:07,453 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,453 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9b80 [0x21c0f30]) 2018-01-27 13:48:07,454 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,454 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2018-01-27 13:48:07,455 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9b80 [0x21c0f30], 0x2d6690 [0]) 2018-01-27 13:48:07,456 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,456 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,457 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74328260 2018-01-27 13:48:07,458 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,458 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74328260 2018-01-27 13:48:07,459 [INFO] [doorpi.sipphone.from_linphone] found 1 possible videodevices: 2018-01-27 13:48:07,459 [DEBUG] [doorpi.sipphone.from_linphone] | name 2018-01-27 13:48:07,460 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-01-27 13:48:07,461 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,461 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74328260 2018-01-27 13:48:07,462 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2018-01-27 13:48:07,462 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2018-01-27 13:48:07,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,463 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74328260 2018-01-27 13:48:07,464 [DEBUG] [doorpi.conf.config_object] create new key video_device in section SIP-Phone with value 'StaticImage: Static picture' 2018-01-27 13:48:07,465 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: StaticImage: Static picture) returns StaticImage: Static picture 2018-01-27 13:48:07,465 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,466 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x74328260 2018-01-27 13:48:07,467 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x743b7f50 [0x21c6370], 0x2d669c [1]) 2018-01-27 13:48:07,467 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2018-01-27 13:48:07,468 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x743b7f50 [0x21c6370], "StaticImage: Static picture") 2018-01-27 13:48:07,468 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2018-01-27 13:48:07,469 [DEBUG] [doorpi.conf.config_object] create new key video_size in section SIP-Phone with value 'vga' 2018-01-27 13:48:07,470 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2018-01-27 13:48:07,470 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x743b7f50 [0x21c6370], "vga") 2018-01-27 13:48:07,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2018-01-27 13:48:07,471 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,472 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x74326a10 2018-01-27 13:48:07,473 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2018-01-27 13:48:07,473 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2018-01-27 13:48:07,474 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x1f71390) 2018-01-27 13:48:07,475 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2018-01-27 13:48:07,476 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x764a9510 2018-01-27 13:48:07,476 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2018-01-27 13:48:07,477 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x764a9510 2018-01-27 13:48:07,478 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x764a9510 2018-01-27 13:48:07,478 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x74328288 2018-01-27 13:48:07,479 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9510 [0x1f71390]) 2018-01-27 13:48:07,479 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,480 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,481 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2d669c 2018-01-27 13:48:07,481 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x764a9510 [0x1f71390]) 2018-01-27 13:48:07,482 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x743b7f68 2018-01-27 13:48:07,482 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2018-01-27 13:48:07,483 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x743b7f50 [0x21c6370], 0x764a9510 [0x1f71390], 0x2d669c [1]) 2018-01-27 13:48:07,484 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x1ef68cc 2018-01-27 13:48:07,484 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.130.254 2018-01-27 13:48:07,485 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns doorpi621 2018-01-27 13:48:07,486 [DEBUG] [doorpi.conf.config_object] create new key sipserver_password in section SIP-Phone with value 'doorpi621' 2018-01-27 13:48:07,486 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: doorpi621) returns ******* 2018-01-27 13:48:07,487 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.130.254) returns 192.168.130.254 2018-01-27 13:48:07,487 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2018-01-27 13:48:07,488 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x743b7f50 [0x21c6370]) 2018-01-27 13:48:07,489 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x22018f8) 2018-01-27 13:48:07,489 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2018-01-27 13:48:07,490 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x764a96d0 2018-01-27 13:48:07,490 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2018-01-27 13:48:07,491 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x764a96d0 2018-01-27 13:48:07,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x764a96d0 2018-01-27 13:48:07,492 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x764a96d0 2018-01-27 13:48:07,493 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2018-01-27 13:48:07,493 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2018-01-27 13:48:07,495 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x21fbdd0) 2018-01-27 13:48:07,496 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2018-01-27 13:48:07,496 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x764a9c10 2018-01-27 13:48:07,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2018-01-27 13:48:07,497 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x764a9c10 2018-01-27 13:48:07,498 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x764a9c10 2018-01-27 13:48:07,499 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x764a9c10 2018-01-27 13:48:07,499 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x764a96d0 [0x22018f8], 0x764a9c10 [0x21fbdd0]) 2018-01-27 13:48:07,500 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2018-01-27 13:48:07,501 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x764a96d0 [0x22018f8], "sip:192.168.130.254") 2018-01-27 13:48:07,502 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2018-01-27 13:48:07,503 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x764a96d0 [0x22018f8], 0x2d669c [1]) 2018-01-27 13:48:07,503 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2018-01-27 13:48:07,504 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x743b7f50 [0x21c6370], 0x764a96d0 [0x22018f8]) 2018-01-27 13:48:07,505 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x1ef68cc 2018-01-27 13:48:07,506 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x743b7f50 [0x21c6370], 0x764a96d0 [0x22018f8]) 2018-01-27 13:48:07,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2018-01-27 13:48:07,507 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x743b7f50 [0x21c6370], "doorpi621", "(null)", "doorpi621", "(null)", "(null)", "192.168.130.254") 2018-01-27 13:48:07,508 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x21fb038) 2018-01-27 13:48:07,509 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2018-01-27 13:48:07,509 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x764a9c40 2018-01-27 13:48:07,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2018-01-27 13:48:07,510 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x764a9c40 2018-01-27 13:48:07,511 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x764a9c40 2018-01-27 13:48:07,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x764a9c40 2018-01-27 13:48:07,512 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x743b7f50 [0x21c6370], 0x764a9c40 [0x21fb038]) 2018-01-27 13:48:07,513 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2018-01-27 13:48:07,514 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2018-01-27 13:48:07,514 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnKeyUp_onboardpins.13', 'EVENT_OnKeyUp_onboardpins.6', 'EVENT_OnKeyUp_onboardpins.5', 'EVENT_OnStartup', 'EVENT_OnKeyUp_onboardpins.19', 'EVENT_OnKeyUp_onboardpins.26'] 2018-01-27 13:48:07,515 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.13' in configfile 2018-01-27 13:48:07,515 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.13 returns ['11', '33', '12', '22', '23'] 2018-01-27 13:48:07,516 [INFO] [doorpi.doorpi] registering action '11' for event 'OnKeyUp_onboardpins.13' 2018-01-27 13:48:07,517 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnKeyUp_onboardpins.13 (default: ) returns out:KlingelLED3,1,0,1 2018-01-27 13:48:07,518 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2018-01-27 13:48:07,519 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2018-01-27 13:48:07,520 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED3', 'start_value': '1'} was added to new evententry OnKeyUp_onboardpins.13 2018-01-27 13:48:07,521 [INFO] [doorpi.doorpi] registering action '12' for event 'OnKeyUp_onboardpins.13' 2018-01-27 13:48:07,522 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section EVENT_OnKeyUp_onboardpins.13 (default: ) returns sleep:1 2018-01-27 13:48:07,523 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2018-01-27 13:48:07,524 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.13 2018-01-27 13:48:07,524 [INFO] [doorpi.doorpi] registering action '22' for event 'OnKeyUp_onboardpins.13' 2018-01-27 13:48:07,525 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section EVENT_OnKeyUp_onboardpins.13 (default: ) returns out:KlingelLED3,1,0,1 2018-01-27 13:48:07,526 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED3', 'start_value': '1'} was added to event OnKeyUp_onboardpins.13 2018-01-27 13:48:07,526 [INFO] [doorpi.doorpi] registering action '23' for event 'OnKeyUp_onboardpins.13' 2018-01-27 13:48:07,527 [TRACE] [doorpi.conf.config_object] get_string for key 23 in section EVENT_OnKeyUp_onboardpins.13 (default: ) returns sleep:1 2018-01-27 13:48:07,528 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.13 2018-01-27 13:48:07,528 [INFO] [doorpi.doorpi] registering action '33' for event 'OnKeyUp_onboardpins.13' 2018-01-27 13:48:07,529 [TRACE] [doorpi.conf.config_object] get_string for key 33 in section EVENT_OnKeyUp_onboardpins.13 (default: ) returns out:KlingelLED3,1,0,1 2018-01-27 13:48:07,530 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED3', 'start_value': '1'} was added to event OnKeyUp_onboardpins.13 2018-01-27 13:48:07,530 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.6' in configfile 2018-01-27 13:48:07,531 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.6 returns ['11', '33', '12', '22', '23'] 2018-01-27 13:48:07,532 [INFO] [doorpi.doorpi] registering action '11' for event 'OnKeyUp_onboardpins.6' 2018-01-27 13:48:07,532 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnKeyUp_onboardpins.6 (default: ) returns out:KlingelLED2,1,0,1 2018-01-27 13:48:07,533 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED2', 'start_value': '1'} was added to new evententry OnKeyUp_onboardpins.6 2018-01-27 13:48:07,534 [INFO] [doorpi.doorpi] registering action '12' for event 'OnKeyUp_onboardpins.6' 2018-01-27 13:48:07,534 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section EVENT_OnKeyUp_onboardpins.6 (default: ) returns sleep:1 2018-01-27 13:48:07,535 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.6 2018-01-27 13:48:07,536 [INFO] [doorpi.doorpi] registering action '22' for event 'OnKeyUp_onboardpins.6' 2018-01-27 13:48:07,536 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section EVENT_OnKeyUp_onboardpins.6 (default: ) returns out:KlingelLED2,1,0,1 2018-01-27 13:48:07,537 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED2', 'start_value': '1'} was added to event OnKeyUp_onboardpins.6 2018-01-27 13:48:07,538 [INFO] [doorpi.doorpi] registering action '23' for event 'OnKeyUp_onboardpins.6' 2018-01-27 13:48:07,538 [TRACE] [doorpi.conf.config_object] get_string for key 23 in section EVENT_OnKeyUp_onboardpins.6 (default: ) returns sleep:1 2018-01-27 13:48:07,539 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.6 2018-01-27 13:48:07,540 [INFO] [doorpi.doorpi] registering action '33' for event 'OnKeyUp_onboardpins.6' 2018-01-27 13:48:07,540 [TRACE] [doorpi.conf.config_object] get_string for key 33 in section EVENT_OnKeyUp_onboardpins.6 (default: ) returns out:KlingelLED2,1,0,1 2018-01-27 13:48:07,541 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED2', 'start_value': '1'} was added to event OnKeyUp_onboardpins.6 2018-01-27 13:48:07,542 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.5' in configfile 2018-01-27 13:48:07,542 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.5 returns ['11', '12', '20', '21', '22', '23', '19', '33'] 2018-01-27 13:48:07,543 [INFO] [doorpi.doorpi] registering action '11' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,543 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns out:KlingelLED1,1 2018-01-27 13:48:07,544 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'KlingelLED1'} was added to new evententry OnKeyUp_onboardpins.5 2018-01-27 13:48:07,545 [INFO] [doorpi.doorpi] registering action '12' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,545 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns call:**613 2018-01-27 13:48:07,547 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2018-01-27 13:48:07,549 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**613'} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,549 [INFO] [doorpi.doorpi] registering action '19' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,550 [TRACE] [doorpi.conf.config_object] get_string for key 19 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns sleep:1.5 2018-01-27 13:48:07,551 [TRACE] [doorpi.action.handler] action SleepAction with args (1.5,) and kwargs {} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,551 [INFO] [doorpi.doorpi] registering action '20' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,552 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns out:KlingelLED1,0 2018-01-27 13:48:07,553 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'KlingelLED1'} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,553 [INFO] [doorpi.doorpi] registering action '21' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,554 [TRACE] [doorpi.conf.config_object] get_string for key 21 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns sleep:0.3 2018-01-27 13:48:07,555 [TRACE] [doorpi.action.handler] action SleepAction with args (0.3,) and kwargs {} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,555 [INFO] [doorpi.doorpi] registering action '22' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,556 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns out:KlingelLED1,1,0,0.5 2018-01-27 13:48:07,557 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'KlingelLED1', 'start_value': '1'} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,557 [INFO] [doorpi.doorpi] registering action '23' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,558 [TRACE] [doorpi.conf.config_object] get_string for key 23 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns sleep:0.5 2018-01-27 13:48:07,559 [TRACE] [doorpi.action.handler] action SleepAction with args (0.5,) and kwargs {} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,559 [INFO] [doorpi.doorpi] registering action '33' for event 'OnKeyUp_onboardpins.5' 2018-01-27 13:48:07,560 [TRACE] [doorpi.conf.config_object] get_string for key 33 in section EVENT_OnKeyUp_onboardpins.5 (default: ) returns out:KlingelLED1,1,0,0.5 2018-01-27 13:48:07,561 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 0.5, 'pin': 'KlingelLED1', 'start_value': '1'} was added to event OnKeyUp_onboardpins.5 2018-01-27 13:48:07,561 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2018-01-27 13:48:07,562 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['11', '10', '13', '12'] 2018-01-27 13:48:07,563 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2018-01-27 13:48:07,563 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2018-01-27 13:48:07,564 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2018-01-27 13:48:07,565 [INFO] [doorpi.doorpi] registering action '11' for event 'OnStartup' 2018-01-27 13:48:07,565 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnStartup (default: ) returns out:HGBel,1 2018-01-27 13:48:07,566 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'HGBel'} was added to event OnStartup 2018-01-27 13:48:07,566 [INFO] [doorpi.doorpi] registering action '12' for event 'OnStartup' 2018-01-27 13:48:07,567 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section EVENT_OnStartup (default: ) returns out:Relais1,0 2018-01-27 13:48:07,568 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Relais1'} was added to event OnStartup 2018-01-27 13:48:07,568 [INFO] [doorpi.doorpi] registering action '13' for event 'OnStartup' 2018-01-27 13:48:07,569 [TRACE] [doorpi.conf.config_object] get_string for key 13 in section EVENT_OnStartup (default: ) returns out:Relais2,0 2018-01-27 13:48:07,570 [TRACE] [doorpi.action.handler] action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Relais2'} was added to event OnStartup 2018-01-27 13:48:07,570 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.19' in configfile 2018-01-27 13:48:07,571 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.19 returns ['11', '33', '12', '22', '23'] 2018-01-27 13:48:07,572 [INFO] [doorpi.doorpi] registering action '11' for event 'OnKeyUp_onboardpins.19' 2018-01-27 13:48:07,572 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnKeyUp_onboardpins.19 (default: ) returns out:KlingelLED4,1,0,1 2018-01-27 13:48:07,573 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED4', 'start_value': '1'} was added to new evententry OnKeyUp_onboardpins.19 2018-01-27 13:48:07,574 [INFO] [doorpi.doorpi] registering action '12' for event 'OnKeyUp_onboardpins.19' 2018-01-27 13:48:07,574 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section EVENT_OnKeyUp_onboardpins.19 (default: ) returns sleep:1 2018-01-27 13:48:07,575 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.19 2018-01-27 13:48:07,576 [INFO] [doorpi.doorpi] registering action '22' for event 'OnKeyUp_onboardpins.19' 2018-01-27 13:48:07,576 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section EVENT_OnKeyUp_onboardpins.19 (default: ) returns out:KlingelLED4,1,0,1 2018-01-27 13:48:07,577 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED4', 'start_value': '1'} was added to event OnKeyUp_onboardpins.19 2018-01-27 13:48:07,578 [INFO] [doorpi.doorpi] registering action '23' for event 'OnKeyUp_onboardpins.19' 2018-01-27 13:48:07,578 [TRACE] [doorpi.conf.config_object] get_string for key 23 in section EVENT_OnKeyUp_onboardpins.19 (default: ) returns sleep:1 2018-01-27 13:48:07,579 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.19 2018-01-27 13:48:07,579 [INFO] [doorpi.doorpi] registering action '33' for event 'OnKeyUp_onboardpins.19' 2018-01-27 13:48:07,580 [TRACE] [doorpi.conf.config_object] get_string for key 33 in section EVENT_OnKeyUp_onboardpins.19 (default: ) returns out:KlingelLED4,1,0,1 2018-01-27 13:48:07,581 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED4', 'start_value': '1'} was added to event OnKeyUp_onboardpins.19 2018-01-27 13:48:07,582 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_onboardpins.26' in configfile 2018-01-27 13:48:07,583 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_onboardpins.26 returns ['11', '33', '12', '22', '23'] 2018-01-27 13:48:07,583 [INFO] [doorpi.doorpi] registering action '11' for event 'OnKeyUp_onboardpins.26' 2018-01-27 13:48:07,584 [TRACE] [doorpi.conf.config_object] get_string for key 11 in section EVENT_OnKeyUp_onboardpins.26 (default: ) returns out:KlingelLED5,1,0,1 2018-01-27 13:48:07,584 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED5', 'start_value': '1'} was added to new evententry OnKeyUp_onboardpins.26 2018-01-27 13:48:07,585 [INFO] [doorpi.doorpi] registering action '12' for event 'OnKeyUp_onboardpins.26' 2018-01-27 13:48:07,586 [TRACE] [doorpi.conf.config_object] get_string for key 12 in section EVENT_OnKeyUp_onboardpins.26 (default: ) returns sleep:1 2018-01-27 13:48:07,586 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.26 2018-01-27 13:48:07,587 [INFO] [doorpi.doorpi] registering action '22' for event 'OnKeyUp_onboardpins.26' 2018-01-27 13:48:07,588 [TRACE] [doorpi.conf.config_object] get_string for key 22 in section EVENT_OnKeyUp_onboardpins.26 (default: ) returns out:KlingelLED5,1,0,1 2018-01-27 13:48:07,588 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED5', 'start_value': '1'} was added to event OnKeyUp_onboardpins.26 2018-01-27 13:48:07,589 [INFO] [doorpi.doorpi] registering action '23' for event 'OnKeyUp_onboardpins.26' 2018-01-27 13:48:07,590 [TRACE] [doorpi.conf.config_object] get_string for key 23 in section EVENT_OnKeyUp_onboardpins.26 (default: ) returns sleep:1 2018-01-27 13:48:07,590 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to event OnKeyUp_onboardpins.26 2018-01-27 13:48:07,591 [INFO] [doorpi.doorpi] registering action '33' for event 'OnKeyUp_onboardpins.26' 2018-01-27 13:48:07,592 [TRACE] [doorpi.conf.config_object] get_string for key 33 in section EVENT_OnKeyUp_onboardpins.26 (default: ) returns out:KlingelLED5,1,0,1 2018-01-27 13:48:07,592 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 1.0, 'pin': 'KlingelLED5', 'start_value': '1'} was added to event OnKeyUp_onboardpins.26 2018-01-27 13:48:07,593 [TRACE] [doorpi.conf.config_object] get_string for key 13 in section onboardpins_InputPins (default: ) returns sleep:0 2018-01-27 13:48:07,594 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.13 2018-01-27 13:48:07,595 [TRACE] [doorpi.conf.config_object] get_string for key 27 in section onboardpins_InputPins (default: ) returns sleep:0 2018-01-27 13:48:07,595 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.27 2018-01-27 13:48:07,596 [TRACE] [doorpi.conf.config_object] get_string for key 19 in section onboardpins_InputPins (default: ) returns sleep:0 2018-01-27 13:48:07,597 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.19 2018-01-27 13:48:07,597 [TRACE] [doorpi.conf.config_object] get_string for key 26 in section onboardpins_InputPins (default: ) returns sleep:0 2018-01-27 13:48:07,598 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.26 2018-01-27 13:48:07,599 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section onboardpins_InputPins (default: ) returns sleep:0 2018-01-27 13:48:07,599 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.5 2018-01-27 13:48:07,600 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section onboardpins_InputPins (default: ) returns sleep:0 2018-01-27 13:48:07,601 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.6 2018-01-27 13:48:07,601 [WARNING] [root] section DTMF not found in configfile 2018-01-27 13:48:07,602 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2018-01-27 13:48:07,603 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2018-01-27 13:48:07,603 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2018-01-27 13:48:07,605 [DEBUG] [doorpi.action.handler] [F1APEV] fire for event OnStartup this actions [, , , ] 2018-01-27 13:48:07,606 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2018-01-27 13:48:07,607 [TRACE] [doorpi.action.handler] [F1APEV] try to fire action SleepAction with args (1.0,) and kwargs {} 2018-01-27 13:48:07,608 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2018-01-27 13:48:08,610 [TRACE] [doorpi.action.handler] [F1APEV] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'HGBel'} 2018-01-27 13:48:08,611 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '1', 'pin': 'HGBel'} 2018-01-27 13:48:08,614 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 2, value = True, log_output = True) 2018-01-27 13:48:08,615 [TRACE] [doorpi.action.handler] [F1APEV] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Relais1'} 2018-01-27 13:48:08,615 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Relais1'} 2018-01-27 13:48:08,618 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 3, value = False, log_output = True) 2018-01-27 13:48:08,619 [TRACE] [doorpi.action.handler] [F1APEV] try to fire action OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Relais2'} 2018-01-27 13:48:08,620 [TRACE] [doorpi.action.base] run OutAction with args () and kwargs {'log_output': True, 'value': '0', 'pin': 'Relais2'} 2018-01-27 13:48:08,623 [DEBUG] [doorpi.keyboard.from_gpio] out(pin = 4, value = False, log_output = True) 2018-01-27 13:48:08,624 [TRACE] [doorpi.action.handler] [F1APEV] finished fire_event for event_name OnStartup 2018-01-27 13:48:08,624 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2018-01-27 13:48:08,626 [INFO] [doorpi.doorpi] DoorPi started successfully 2018-01-27 13:48:08,627 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2018-01-27 13:48:08,627 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2018-01-27 13:48:08,628 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi/ 2018-01-27 13:48:08,629 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.130.254 2018-01-27 13:48:08,630 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2018-01-27 13:48:08,631 [INFO] [doorpi.sipphone.from_linphone] New local ip address is 192.168.130.89 2018-01-27 13:48:08,631 [INFO] [doorpi.sipphone.from_linphone] Network state is now [UP] 2018-01-27 13:48:08,632 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2018-01-27 13:48:08,633 [INFO] [doorpi.sipphone.from_linphone] 192.168.130.254 2018-01-27 13:48:08,634 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2018-01-27 13:48:08,634 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2018-01-27 13:48:08,636 [INFO] [doorpi.sipphone.from_linphone] LinphoneProxyConfig [0x22018f8] about to register (LinphoneCore version: 3.9.1) 2018-01-27 13:48:08,643 [INFO] [doorpi.sipphone.from_linphone] belle_sip_client_transaction_send_request(): waiting channel to be ready 2018-01-27 13:48:08,644 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: starting resolution of 192.168.130.254 2018-01-27 13:48:08,645 [INFO] [doorpi.sipphone.from_linphone] channel 0x21d69d0: state RES_IN_PROGRESS 2018-01-27 13:48:08,645 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2202c28] channel state changed to [RES_IN_PROGRESS] 2018-01-27 13:48:08,646 [INFO] [doorpi.sipphone.from_linphone] channel 0x21d69d0: state RES_DONE 2018-01-27 13:48:08,646 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2202c28] channel state changed to [RES_DONE] 2018-01-27 13:48:08,647 [INFO] [doorpi.sipphone.from_linphone] channel 0x21d69d0: state CONNECTING 2018-01-27 13:48:08,648 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2202c28] channel state changed to [CONNECTING] 2018-01-27 13:48:08,648 [INFO] [doorpi.sipphone.from_linphone] Trying to connect to [UDP://192.168.130.254:5060] 2018-01-27 13:48:08,649 [INFO] [doorpi.sipphone.from_linphone] belle_sip_get_src_addr_for(): af_inet6=0 2018-01-27 13:48:08,649 [INFO] [doorpi.sipphone.from_linphone] Channel has local address 192.168.130.89:5060 2018-01-27 13:48:08,650 [INFO] [doorpi.sipphone.from_linphone] channel 0x21d69d0: state READY 2018-01-27 13:48:08,651 [INFO] [doorpi.sipphone.from_linphone] transaction [0x2202c28] channel state changed to [READY] 2018-01-27 13:48:08,651 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2202c28], from state [INIT] to [TRYING] 2018-01-27 13:48:08,652 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: message sent to [UDP://192.168.130.254:5060], size: [538] bytes REGISTER sip:192.168.130.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.8N6jZHmti;rport From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" CSeq: 20 REGISTER Call-ID: 9gFTvpCmyf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) 2018-01-27 13:48:08,653 [INFO] [doorpi.sipphone.from_linphone] Neither Expires header nor corresponding Contact header found, checking from original request 2018-01-27 13:48:08,654 [INFO] [doorpi.sipphone.from_linphone] Refresher [0x2205d10] takes ownership of transaction [0x2202c28] 2018-01-27 13:48:08,654 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x22018f8] for identity ["DoorPi" ] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] 2018-01-27 13:48:08,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x21c6370, 0x22018f8, 1, "Registration in progress") 2018-01-27 13:48:08,655 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2018-01-27 13:48:08,656 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [registration_state_changed] 2018-01-27 13:48:08,707 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_network_reachable(0x21c6370, 0x1) 2018-01-27 13:48:08,708 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_network_reachable 2018-01-27 13:48:08,708 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [network_reachable] 2018-01-27 13:48:08,709 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: received [382] new bytes from [UDP://192.168.130.254:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.8N6jZHmti;rport=5060 From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" ;tag=A9C281846EDA41B3 Call-ID: 9gFTvpCmyf CSeq: 20 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="A2B8A20C14593AC2" User-Agent: FRITZ!OS Content-Length: 0 2018-01-27 13:48:08,718 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0] [382] bytes parsed 2018-01-27 13:48:08,719 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: discovered public ip and port are [192.168.130.89:5060] 2018-01-27 13:48:08,719 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2018-01-27 13:48:08,720 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2202c28], from state [TRYING] to [COMPLETED] 2018-01-27 13:48:08,721 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpi621, realm= 2018-01-27 13:48:08,722 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [doorpi621] realm [fritz.box] 2018-01-27 13:48:08,723 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x21f3e30], from state [INIT] to [TRYING] 2018-01-27 13:48:08,724 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: message sent to [UDP://192.168.130.254:5060], size: [701] bytes REGISTER sip:192.168.130.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.FSXkjQmPo;rport From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" CSeq: 21 REGISTER Call-ID: 9gFTvpCmyf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="A2B8A20C14593AC2", username="doorpi621", uri="sip:192.168.130.254", response="ead86108468b2db40f9fd6cc2fac1777" 2018-01-27 13:48:08,725 [INFO] [doorpi.sipphone.from_linphone] resolved to 192.168.130.254 2018-01-27 13:48:08,725 [INFO] [doorpi.sipphone.from_linphone] Stun server resolution successful. 2018-01-27 13:48:08,777 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: received [382] new bytes from [UDP://192.168.130.254:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.FSXkjQmPo;rport=5060 From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" ;tag=00F03EDE979E25D7 Call-ID: 9gFTvpCmyf CSeq: 21 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="EBC689F1B9C8C63C" User-Agent: FRITZ!OS Content-Length: 0 2018-01-27 13:48:08,785 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0] [382] bytes parsed 2018-01-27 13:48:08,786 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2018-01-27 13:48:08,787 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x21f3e30], from state [TRYING] to [COMPLETED] 2018-01-27 13:48:08,787 [WARNING] [doorpi.sipphone.from_linphone] Authentication is failing constantly, will retry later 2018-01-27 13:48:08,788 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2018-01-27 13:48:08,789 [INFO] [doorpi.sipphone.from_linphone] Register refresher [401] reason [Unauthorized] for proxy [sip:192.168.130.254] 2018-01-27 13:48:08,789 [INFO] [doorpi.sipphone.from_linphone] Proxy config [0x22018f8] for identity ["DoorPi" ] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationFailed] 2018-01-27 13:48:08,790 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_registration_state_changed(0x21c6370, 0x22018f8, 4, "Unauthorized") 2018-01-27 13:48:08,791 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_registration_state_changed 2018-01-27 13:48:08,791 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [registration_state_changed] 2018-01-27 13:48:08,792 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpi621, realm=fritz.box 2018-01-27 13:48:08,792 [INFO] [doorpi.sipphone.from_linphone] fritz.box/doorpi621/192.168.130.254 authentication fails. 2018-01-27 13:48:08,793 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_auth_info_requested(0x21c6370, "fritz.box", "doorpi621", "192.168.130.254") 2018-01-27 13:48:08,794 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_auth_info_requested 2018-01-27 13:48:08,794 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [auth_info_requested] 2018-01-27 13:48:13,752 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2202c28], from state [COMPLETED] to [TERMINATED] 2018-01-27 13:48:13,753 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x2202c28] terminated 2018-01-27 13:48:13,804 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x21f3e30], from state [COMPLETED] to [TERMINATED] 2018-01-27 13:48:13,805 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x21f3e30] terminated 2018-01-27 13:49:08,814 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpi621, realm=fritz.box 2018-01-27 13:49:08,815 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [doorpi621] realm [fritz.box] 2018-01-27 13:49:08,816 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2202530], from state [INIT] to [TRYING] 2018-01-27 13:49:08,817 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: message sent to [UDP://192.168.130.254:5060], size: [701] bytes REGISTER sip:192.168.130.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.5QA0KJ7ao;rport From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" CSeq: 22 REGISTER Call-ID: 9gFTvpCmyf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="EBC689F1B9C8C63C", username="doorpi621", uri="sip:192.168.130.254", response="62c7ed3bbf154ce2217b3df170e7aa59" 2018-01-27 13:49:08,869 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: received [382] new bytes from [UDP://192.168.130.254:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.5QA0KJ7ao;rport=5060 From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" ;tag=419AA124D7A9D6AD Call-ID: 9gFTvpCmyf CSeq: 22 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="E021871D89F555BA" User-Agent: FRITZ!OS Content-Length: 0 2018-01-27 13:49:08,877 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0] [382] bytes parsed 2018-01-27 13:49:08,878 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2018-01-27 13:49:08,879 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2202530], from state [TRYING] to [COMPLETED] 2018-01-27 13:49:08,880 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpi621, realm=fritz.box 2018-01-27 13:49:08,880 [INFO] [doorpi.sipphone.from_linphone] Auth info found for [doorpi621] realm [fritz.box] 2018-01-27 13:49:08,881 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2206ae8], from state [INIT] to [TRYING] 2018-01-27 13:49:08,882 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: message sent to [UDP://192.168.130.254:5060], size: [701] bytes REGISTER sip:192.168.130.254 SIP/2.0 Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.08SYk7F7S;rport From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" CSeq: 23 REGISTER Call-ID: 9gFTvpCmyf Max-Forwards: 70 Supported: outbound Accept: application/sdp Accept: text/plain Accept: application/vnd.gsma.rcs-ft-http+xml Contact: ;+sip.instance="" Expires: 3600 User-Agent: (belle-sip/1.4.2) Authorization: Digest realm="fritz.box", nonce="E021871D89F555BA", username="doorpi621", uri="sip:192.168.130.254", response="945400ec28413ce7f5b309829124db52" 2018-01-27 13:49:08,934 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0]: received [382] new bytes from [UDP://192.168.130.254:5060]: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.130.89:5060;branch=z9hG4bK.08SYk7F7S;rport=5060 From: "DoorPi" ;tag=fFnT0YvZB To: "DoorPi" ;tag=E139618E66885C79 Call-ID: 9gFTvpCmyf CSeq: 23 REGISTER WWW-Authenticate: Digest realm="fritz.box", nonce="545D282C1F741A21" User-Agent: FRITZ!OS Content-Length: 0 2018-01-27 13:49:08,943 [INFO] [doorpi.sipphone.from_linphone] channel [0x21d69d0] [382] bytes parsed 2018-01-27 13:49:08,944 [INFO] [doorpi.sipphone.from_linphone] Found transaction matching response. 2018-01-27 13:49:08,944 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2206ae8], from state [TRYING] to [COMPLETED] 2018-01-27 13:49:08,945 [WARNING] [doorpi.sipphone.from_linphone] Authentication is failing constantly, will retry later 2018-01-27 13:49:08,945 [INFO] [doorpi.sipphone.from_linphone] Refresher: scheduling next timer in 60000 ms 2018-01-27 13:49:08,946 [INFO] [doorpi.sipphone.from_linphone] Register refresher [401] reason [Unauthorized] for proxy [sip:192.168.130.254] 2018-01-27 13:49:08,947 [INFO] [doorpi.sipphone.from_linphone] linphone_core_find_auth_info(): returning auth info username=doorpi621, realm=fritz.box 2018-01-27 13:49:08,948 [INFO] [doorpi.sipphone.from_linphone] fritz.box/doorpi621/192.168.130.254 authentication fails. 2018-01-27 13:49:08,948 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_auth_info_requested(0x21c6370, "fritz.box", "doorpi621", "192.168.130.254") 2018-01-27 13:49:08,949 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_auth_info_requested 2018-01-27 13:49:08,949 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x21c6370] notifying [auth_info_requested] 2018-01-27 13:49:13,905 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2202530], from state [COMPLETED] to [TERMINATED] 2018-01-27 13:49:13,906 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x2202530] terminated 2018-01-27 13:49:13,957 [INFO] [doorpi.sipphone.from_linphone] Changing [client] [REGISTER] transaction [0x2206ae8], from state [COMPLETED] to [TERMINATED] 2018-01-27 13:49:13,958 [INFO] [doorpi.sipphone.from_linphone] Client internal REGISTER transaction [0x2206ae8] terminated ^C2018-01-27 13:49:15,216 [INFO] [doorpi.main] KeyboardInterrupt -> DoorPi will shutdown 2018-01-27 13:49:15,217 [DEBUG] [doorpi.doorpi] destroy doorpi 2018-01-27 13:49:15,217 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1995794624)>, ] 2018-01-27 13:49:15,218 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron 2018-01-27 13:49:15,220 [DEBUG] [doorpi.action.handler] [7S2ME7] fire for event OnShutdown this actions [, , , ] 2018-01-27 13:49:15,221 [DEBUG] [doorpi.action.handler] no actions for event BeforeShutdown - skip fire_event BeforeShutdown from doorpi.doorpi 2018-01-27 13:49:15,221 [TRACE] [doorpi.action.handler] [7S2ME7] try to fire action TimeTickDestroyAction with args () and kwargs {} 2018-01-27 13:49:15,223 [TRACE] [doorpi.action.base] run TimeTickDestroyAction with args () and kwargs {} 2018-01-27 13:49:15,223 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.action.SingleActions.time_tick and force_unregister is True 2018-01-27 13:49:15,224 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute59 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,224 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute59 - remove event too 2018-01-27 13:49:15,225 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute59 2018-01-27 13:49:15,226 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute58 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,226 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute58 - remove event too 2018-01-27 13:49:15,227 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute58 2018-01-27 13:49:15,227 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,228 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekEvenNumber - remove event too 2018-01-27 13:49:15,228 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekEvenNumber 2018-01-27 13:49:15,229 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute51 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,229 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute51 - remove event too 2018-01-27 13:49:15,230 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute51 2018-01-27 13:49:15,231 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute50 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,231 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute50 - remove event too 2018-01-27 13:49:15,232 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute50 2018-01-27 13:49:15,232 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute53 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,233 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute53 - remove event too 2018-01-27 13:49:15,233 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute53 2018-01-27 13:49:15,234 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute52 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,234 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute52 - remove event too 2018-01-27 13:49:15,235 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute52 2018-01-27 13:49:15,236 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute55 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,236 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute55 - remove event too 2018-01-27 13:49:15,237 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute55 2018-01-27 13:49:15,237 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute54 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,238 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute54 - remove event too 2018-01-27 13:49:15,238 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute54 2018-01-27 13:49:15,239 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute57 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,239 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute57 - remove event too 2018-01-27 13:49:15,240 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute57 2018-01-27 13:49:15,241 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute56 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,241 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute56 - remove event too 2018-01-27 13:49:15,242 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute56 2018-01-27 13:49:15,242 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,243 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvenNumber - remove event too 2018-01-27 13:49:15,243 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvenNumber 2018-01-27 13:49:15,244 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,245 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour - remove event too 2018-01-27 13:49:15,245 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour 2018-01-27 13:49:15,246 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonth from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,246 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonth - remove event too 2018-01-27 13:49:15,247 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonth 2018-01-27 13:49:15,247 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,248 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearUnevenNumber - remove event too 2018-01-27 13:49:15,248 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearUnevenNumber 2018-01-27 13:49:15,249 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour19 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,250 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour19 - remove event too 2018-01-27 13:49:15,250 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour19 2018-01-27 13:49:15,251 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour18 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,251 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour18 - remove event too 2018-01-27 13:49:15,252 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour18 2018-01-27 13:49:15,252 [TRACE] [doorpi.action.handler] unregister Event OnTimeYearEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,253 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYearEvenNumber - remove event too 2018-01-27 13:49:15,254 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYearEvenNumber 2018-01-27 13:49:15,254 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour11 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,255 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour11 - remove event too 2018-01-27 13:49:15,255 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour11 2018-01-27 13:49:15,256 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour13 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,256 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour13 - remove event too 2018-01-27 13:49:15,257 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour13 2018-01-27 13:49:15,257 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour12 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,258 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour12 - remove event too 2018-01-27 13:49:15,258 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour12 2018-01-27 13:49:15,259 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour15 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,260 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour15 - remove event too 2018-01-27 13:49:15,260 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour15 2018-01-27 13:49:15,261 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour14 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,261 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour14 - remove event too 2018-01-27 13:49:15,262 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour14 2018-01-27 13:49:15,262 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour17 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,263 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour17 - remove event too 2018-01-27 13:49:15,264 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour17 2018-01-27 13:49:15,264 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour16 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,265 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour16 - remove event too 2018-01-27 13:49:15,265 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour16 2018-01-27 13:49:15,266 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,266 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondUnevenNumber - remove event too 2018-01-27 13:49:15,267 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondUnevenNumber 2018-01-27 13:49:15,267 [TRACE] [doorpi.action.handler] unregister Event OnTimeDay from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,268 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDay - remove event too 2018-01-27 13:49:15,269 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDay 2018-01-27 13:49:15,269 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,270 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthEvenNumber - remove event too 2018-01-27 13:49:15,270 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthEvenNumber 2018-01-27 13:49:15,271 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour9 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,271 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour9 - remove event too 2018-01-27 13:49:15,272 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour9 2018-01-27 13:49:15,273 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour8 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,273 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour8 - remove event too 2018-01-27 13:49:15,274 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour8 2018-01-27 13:49:15,274 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour10 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,275 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour10 - remove event too 2018-01-27 13:49:15,275 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour10 2018-01-27 13:49:15,276 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecond from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,276 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecond - remove event too 2018-01-27 13:49:15,277 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecond 2018-01-27 13:49:15,278 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour4 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,278 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour4 - remove event too 2018-01-27 13:49:15,279 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour4 2018-01-27 13:49:15,279 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour7 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,280 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour7 - remove event too 2018-01-27 13:49:15,280 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour7 2018-01-27 13:49:15,281 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour6 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,281 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour6 - remove event too 2018-01-27 13:49:15,282 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour6 2018-01-27 13:49:15,283 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour1 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,283 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour1 - remove event too 2018-01-27 13:49:15,284 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour1 2018-01-27 13:49:15,284 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour0 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,285 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour0 - remove event too 2018-01-27 13:49:15,285 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour0 2018-01-27 13:49:15,286 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour3 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,286 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour3 - remove event too 2018-01-27 13:49:15,287 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour3 2018-01-27 13:49:15,287 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour2 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,288 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour2 - remove event too 2018-01-27 13:49:15,289 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour2 2018-01-27 13:49:15,289 [TRACE] [doorpi.action.handler] unregister Event OnTimeMonthUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,290 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMonthUnevenNumber - remove event too 2018-01-27 13:49:15,290 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMonthUnevenNumber 2018-01-27 13:49:15,291 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute5 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,291 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute5 - remove event too 2018-01-27 13:49:15,292 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute5 2018-01-27 13:49:15,292 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute4 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,293 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute4 - remove event too 2018-01-27 13:49:15,294 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute4 2018-01-27 13:49:15,294 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute7 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,295 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute7 - remove event too 2018-01-27 13:49:15,295 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute7 2018-01-27 13:49:15,296 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute6 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,296 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute6 - remove event too 2018-01-27 13:49:15,297 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute6 2018-01-27 13:49:15,297 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute1 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,298 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute1 - remove event too 2018-01-27 13:49:15,298 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute1 2018-01-27 13:49:15,299 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute0 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,299 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute0 - remove event too 2018-01-27 13:49:15,300 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute0 2018-01-27 13:49:15,301 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute3 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,301 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute3 - remove event too 2018-01-27 13:49:15,302 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute3 2018-01-27 13:49:15,302 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute2 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,303 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute2 - remove event too 2018-01-27 13:49:15,303 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute2 2018-01-27 13:49:15,304 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute9 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,304 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute9 - remove event too 2018-01-27 13:49:15,305 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute9 2018-01-27 13:49:15,305 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute8 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,306 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute8 - remove event too 2018-01-27 13:49:15,307 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute8 2018-01-27 13:49:15,307 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeek from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,308 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeek - remove event too 2018-01-27 13:49:15,308 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeek 2018-01-27 13:49:15,309 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute15 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,309 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute15 - remove event too 2018-01-27 13:49:15,310 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute15 2018-01-27 13:49:15,310 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute14 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,311 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute14 - remove event too 2018-01-27 13:49:15,312 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute14 2018-01-27 13:49:15,312 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute17 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,313 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute17 - remove event too 2018-01-27 13:49:15,313 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute17 2018-01-27 13:49:15,314 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute16 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,314 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute16 - remove event too 2018-01-27 13:49:15,315 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute16 2018-01-27 13:49:15,315 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute11 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,316 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute11 - remove event too 2018-01-27 13:49:15,316 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute11 2018-01-27 13:49:15,317 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute10 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,318 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute10 - remove event too 2018-01-27 13:49:15,318 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute10 2018-01-27 13:49:15,319 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute13 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,319 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute13 - remove event too 2018-01-27 13:49:15,320 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute13 2018-01-27 13:49:15,320 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute12 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,321 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute12 - remove event too 2018-01-27 13:49:15,321 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute12 2018-01-27 13:49:15,322 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute19 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,322 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute19 - remove event too 2018-01-27 13:49:15,323 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute19 2018-01-27 13:49:15,324 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute18 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,324 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute18 - remove event too 2018-01-27 13:49:15,325 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute18 2018-01-27 13:49:15,325 [TRACE] [doorpi.action.handler] unregister Event OnTimeWeekUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,326 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeWeekUnevenNumber - remove event too 2018-01-27 13:49:15,326 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeWeekUnevenNumber 2018-01-27 13:49:15,327 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteEvery5 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,327 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteEvery5 - remove event too 2018-01-27 13:49:15,328 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteEvery5 2018-01-27 13:49:15,328 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour20 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,329 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour20 - remove event too 2018-01-27 13:49:15,330 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour20 2018-01-27 13:49:15,330 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour21 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,331 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour21 - remove event too 2018-01-27 13:49:15,331 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour21 2018-01-27 13:49:15,332 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour22 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,332 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour22 - remove event too 2018-01-27 13:49:15,333 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour22 2018-01-27 13:49:15,333 [TRACE] [doorpi.action.handler] unregister Event OnTimeHour5 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,334 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHour5 - remove event too 2018-01-27 13:49:15,335 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHour5 2018-01-27 13:49:15,335 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute20 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,336 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute20 - remove event too 2018-01-27 13:49:15,336 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute20 2018-01-27 13:49:15,337 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute21 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,337 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute21 - remove event too 2018-01-27 13:49:15,338 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute21 2018-01-27 13:49:15,338 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute22 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,339 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute22 - remove event too 2018-01-27 13:49:15,339 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute22 2018-01-27 13:49:15,340 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute23 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,341 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute23 - remove event too 2018-01-27 13:49:15,341 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute23 2018-01-27 13:49:15,342 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute24 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,342 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute24 - remove event too 2018-01-27 13:49:15,343 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute24 2018-01-27 13:49:15,343 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute25 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,344 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute25 - remove event too 2018-01-27 13:49:15,344 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute25 2018-01-27 13:49:15,345 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute26 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,345 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute26 - remove event too 2018-01-27 13:49:15,346 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute26 2018-01-27 13:49:15,346 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute27 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,347 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute27 - remove event too 2018-01-27 13:49:15,348 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute27 2018-01-27 13:49:15,348 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute28 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,349 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute28 - remove event too 2018-01-27 13:49:15,349 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute28 2018-01-27 13:49:15,350 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute29 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,350 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute29 - remove event too 2018-01-27 13:49:15,351 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute29 2018-01-27 13:49:15,352 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,352 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayEvenNumber - remove event too 2018-01-27 13:49:15,353 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayEvenNumber 2018-01-27 13:49:15,353 [TRACE] [doorpi.action.handler] unregister Event OnTimeDayUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,354 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeDayUnevenNumber - remove event too 2018-01-27 13:49:15,354 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeDayUnevenNumber 2018-01-27 13:49:15,355 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute39 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,355 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute39 - remove event too 2018-01-27 13:49:15,356 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute39 2018-01-27 13:49:15,356 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute38 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,357 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute38 - remove event too 2018-01-27 13:49:15,357 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute38 2018-01-27 13:49:15,358 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute37 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,359 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute37 - remove event too 2018-01-27 13:49:15,359 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute37 2018-01-27 13:49:15,360 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute36 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,360 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute36 - remove event too 2018-01-27 13:49:15,361 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute36 2018-01-27 13:49:15,361 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute35 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,362 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute35 - remove event too 2018-01-27 13:49:15,362 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute35 2018-01-27 13:49:15,363 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute34 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,364 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute34 - remove event too 2018-01-27 13:49:15,364 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute34 2018-01-27 13:49:15,365 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute33 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,365 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute33 - remove event too 2018-01-27 13:49:15,366 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute33 2018-01-27 13:49:15,366 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute32 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,367 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute32 - remove event too 2018-01-27 13:49:15,367 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute32 2018-01-27 13:49:15,368 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute31 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,368 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute31 - remove event too 2018-01-27 13:49:15,369 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute31 2018-01-27 13:49:15,369 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute30 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,370 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute30 - remove event too 2018-01-27 13:49:15,371 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute30 2018-01-27 13:49:15,371 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,372 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourUnevenNumber - remove event too 2018-01-27 13:49:15,372 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourUnevenNumber 2018-01-27 13:49:15,373 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,373 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute - remove event too 2018-01-27 13:49:15,374 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute 2018-01-27 13:49:15,375 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute48 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,375 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute48 - remove event too 2018-01-27 13:49:15,376 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute48 2018-01-27 13:49:15,376 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute49 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,377 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute49 - remove event too 2018-01-27 13:49:15,377 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute49 2018-01-27 13:49:15,378 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute42 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,378 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute42 - remove event too 2018-01-27 13:49:15,379 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute42 2018-01-27 13:49:15,379 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute43 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,380 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute43 - remove event too 2018-01-27 13:49:15,381 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute43 2018-01-27 13:49:15,381 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute40 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,382 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute40 - remove event too 2018-01-27 13:49:15,382 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute40 2018-01-27 13:49:15,383 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute41 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,383 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute41 - remove event too 2018-01-27 13:49:15,384 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute41 2018-01-27 13:49:15,385 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute46 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,385 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute46 - remove event too 2018-01-27 13:49:15,386 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute46 2018-01-27 13:49:15,386 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute47 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,387 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute47 - remove event too 2018-01-27 13:49:15,387 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute47 2018-01-27 13:49:15,388 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute44 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,388 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute44 - remove event too 2018-01-27 13:49:15,389 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute44 2018-01-27 13:49:15,389 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinute45 from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,390 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinute45 - remove event too 2018-01-27 13:49:15,391 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinute45 2018-01-27 13:49:15,391 [TRACE] [doorpi.action.handler] unregister Event OnTimeHourEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,392 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeHourEvenNumber - remove event too 2018-01-27 13:49:15,392 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeHourEvenNumber 2018-01-27 13:49:15,393 [TRACE] [doorpi.action.handler] unregister Event OnTimeSecondEvenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,393 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeSecondEvenNumber - remove event too 2018-01-27 13:49:15,394 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeSecondEvenNumber 2018-01-27 13:49:15,394 [TRACE] [doorpi.action.handler] unregister Event OnTimeYear from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,395 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeYear - remove event too 2018-01-27 13:49:15,396 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeYear 2018-01-27 13:49:15,396 [TRACE] [doorpi.action.handler] unregister Event OnTimeMinuteUnevenNumber from doorpi.action.SingleActions.time_tick 2018-01-27 13:49:15,397 [DEBUG] [doorpi.action.handler] no more sources for event OnTimeMinuteUnevenNumber - remove event too 2018-01-27 13:49:15,397 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed for event OnTimeMinuteUnevenNumber 2018-01-27 13:49:15,398 [TRACE] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was removed 2018-01-27 13:49:15,399 [TRACE] [doorpi.action.handler] [7S2ME7] try to fire action WebServerShutdownAction with args () and kwargs {} 2018-01-27 13:49:15,399 [TRACE] [doorpi.action.base] run WebServerShutdownAction with args () and kwargs {} 2018-01-27 13:49:15,400 [TRACE] [doorpi.action.handler] fire Event OnWebServerStop from doorpi.status.webserver asyncron 2018-01-27 13:49:15,402 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver_lib.request_handler and force_unregister is True 2018-01-27 13:49:15,403 [TRACE] [doorpi.action.handler] unregister Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,403 [DEBUG] [doorpi.action.handler] no actions for event OnWebServerStop - skip fire_event OnWebServerStop from doorpi.status.webserver 2018-01-27 13:49:15,404 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerVirtualResource - remove event too 2018-01-27 13:49:15,405 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerVirtualResource 2018-01-27 13:49:15,405 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,406 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRealResource - remove event too 2018-01-27 13:49:15,407 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRealResource 2018-01-27 13:49:15,407 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,408 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeySet - remove event too 2018-01-27 13:49:15,408 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeySet 2018-01-27 13:49:15,409 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,409 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestGet - remove event too 2018-01-27 13:49:15,410 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestGet 2018-01-27 13:49:15,411 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,411 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequestPost - remove event too 2018-01-27 13:49:15,412 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequestPost 2018-01-27 13:49:15,412 [TRACE] [doorpi.action.handler] unregister Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,413 [DEBUG] [doorpi.action.handler] no more sources for event OnConfigKeyDelete - remove event too 2018-01-27 13:49:15,413 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnConfigKeyDelete 2018-01-27 13:49:15,414 [TRACE] [doorpi.action.handler] unregister Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,414 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerRequest - remove event too 2018-01-27 13:49:15,415 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnWebServerRequest 2018-01-27 13:49:15,416 [TRACE] [doorpi.action.handler] unregister Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2018-01-27 13:49:15,416 [DEBUG] [doorpi.action.handler] no more sources for event OnFireEvent - remove event too 2018-01-27 13:49:15,417 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed for event OnFireEvent 2018-01-27 13:49:15,417 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was removed 2018-01-27 13:49:15,428 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.status.webserver and force_unregister is True 2018-01-27 13:49:15,430 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStop from doorpi.status.webserver 2018-01-27 13:49:15,431 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStop - remove event too 2018-01-27 13:49:15,432 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStop 2018-01-27 13:49:15,432 [TRACE] [doorpi.action.handler] [78Z12F] finished fire_event for event_name OnWebServerStart 2018-01-27 13:49:15,433 [TRACE] [doorpi.action.handler] unregister Event OnWebServerStart from doorpi.status.webserver 2018-01-27 13:49:15,434 [DEBUG] [doorpi.action.handler] no more sources for event OnWebServerStart - remove event too 2018-01-27 13:49:15,435 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed for event OnWebServerStart 2018-01-27 13:49:15,436 [TRACE] [doorpi.action.handler] event_source doorpi.status.webserver was removed 2018-01-27 13:49:15,437 [TRACE] [doorpi.action.handler] [7S2ME7] try to fire action KeyboardDestroyAction with args () and kwargs {} 2018-01-27 13:49:15,437 [TRACE] [doorpi.action.base] run KeyboardDestroyAction with args () and kwargs {} 2018-01-27 13:49:15,438 [DEBUG] [doorpi.keyboard.from_gpio] destroy 2018-01-27 13:49:15,460 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.keyboard.from_gpio and force_unregister is True 2018-01-27 13:49:15,462 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.27 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,462 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.27 - remove event too 2018-01-27 13:49:15,464 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.27 2018-01-27 13:49:15,465 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_19 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,465 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_19 - remove event too 2018-01-27 13:49:15,467 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_19 2018-01-27 13:49:15,467 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.13 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,468 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.13 - remove event too 2018-01-27 13:49:15,470 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.13 2018-01-27 13:49:15,471 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.19 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,472 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.19 - remove event too 2018-01-27 13:49:15,473 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.19 2018-01-27 13:49:15,474 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_13 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,475 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_13 - remove event too 2018-01-27 13:49:15,476 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_13 2018-01-27 13:49:15,477 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_19 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,478 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_19 - remove event too 2018-01-27 13:49:15,479 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_19 2018-01-27 13:49:15,480 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,481 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed - remove event too 2018-01-27 13:49:15,482 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed 2018-01-27 13:49:15,483 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,484 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp - remove event too 2018-01-27 13:49:15,485 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp 2018-01-27 13:49:15,486 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.27 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,487 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.27 - remove event too 2018-01-27 13:49:15,488 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.27 2018-01-27 13:49:15,489 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.26 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,489 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.26 - remove event too 2018-01-27 13:49:15,490 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.26 2018-01-27 13:49:15,491 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_26 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,492 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_26 - remove event too 2018-01-27 13:49:15,493 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_26 2018-01-27 13:49:15,494 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.6 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,495 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.6 - remove event too 2018-01-27 13:49:15,496 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.6 2018-01-27 13:49:15,497 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_27 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,498 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_27 - remove event too 2018-01-27 13:49:15,499 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_27 2018-01-27 13:49:15,500 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.6 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,502 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.6 - remove event too 2018-01-27 13:49:15,503 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.6 2018-01-27 13:49:15,504 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_6 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,505 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_6 - remove event too 2018-01-27 13:49:15,506 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_6 2018-01-27 13:49:15,507 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_5 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,508 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_5 - remove event too 2018-01-27 13:49:15,509 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_5 2018-01-27 13:49:15,510 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.6 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,511 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.6 - remove event too 2018-01-27 13:49:15,512 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.6 2018-01-27 13:49:15,513 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.19 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,514 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.19 - remove event too 2018-01-27 13:49:15,515 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.19 2018-01-27 13:49:15,516 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.26 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,517 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.26 - remove event too 2018-01-27 13:49:15,518 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.26 2018-01-27 13:49:15,519 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_26 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,520 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_26 - remove event too 2018-01-27 13:49:15,521 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_26 2018-01-27 13:49:15,522 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_27 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,523 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_27 - remove event too 2018-01-27 13:49:15,524 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_27 2018-01-27 13:49:15,525 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.5 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,525 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.5 - remove event too 2018-01-27 13:49:15,526 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.5 2018-01-27 13:49:15,527 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,528 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown - remove event too 2018-01-27 13:49:15,529 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown 2018-01-27 13:49:15,530 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.5 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,531 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.5 - remove event too 2018-01-27 13:49:15,532 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.5 2018-01-27 13:49:15,532 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_27 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,533 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_27 - remove event too 2018-01-27 13:49:15,534 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_27 2018-01-27 13:49:15,535 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_26 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,536 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_26 - remove event too 2018-01-27 13:49:15,537 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_26 2018-01-27 13:49:15,538 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.13 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,539 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.13 - remove event too 2018-01-27 13:49:15,540 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.13 2018-01-27 13:49:15,541 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_onboardpins.13 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,542 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_onboardpins.13 - remove event too 2018-01-27 13:49:15,543 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_onboardpins.13 2018-01-27 13:49:15,544 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.26 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,545 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.26 - remove event too 2018-01-27 13:49:15,546 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.26 2018-01-27 13:49:15,547 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.27 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,548 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.27 - remove event too 2018-01-27 13:49:15,549 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.27 2018-01-27 13:49:15,550 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_6 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,551 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_6 - remove event too 2018-01-27 13:49:15,552 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_6 2018-01-27 13:49:15,553 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_5 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,554 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_5 - remove event too 2018-01-27 13:49:15,555 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_5 2018-01-27 13:49:15,556 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_5 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,557 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_5 - remove event too 2018-01-27 13:49:15,558 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_5 2018-01-27 13:49:15,559 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_6 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,559 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_6 - remove event too 2018-01-27 13:49:15,561 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_6 2018-01-27 13:49:15,561 [TRACE] [doorpi.action.handler] unregister Event OnKeyDown_onboardpins.5 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,562 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyDown_onboardpins.5 - remove event too 2018-01-27 13:49:15,563 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyDown_onboardpins.5 2018-01-27 13:49:15,564 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_19 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,565 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_19 - remove event too 2018-01-27 13:49:15,566 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_19 2018-01-27 13:49:15,567 [TRACE] [doorpi.action.handler] unregister Event OnKeyUp_13 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,568 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyUp_13 - remove event too 2018-01-27 13:49:15,569 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyUp_13 2018-01-27 13:49:15,570 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_13 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,570 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_13 - remove event too 2018-01-27 13:49:15,571 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_13 2018-01-27 13:49:15,572 [TRACE] [doorpi.action.handler] unregister Event OnKeyPressed_onboardpins.19 from doorpi.keyboard.from_gpio 2018-01-27 13:49:15,573 [DEBUG] [doorpi.action.handler] no more sources for event OnKeyPressed_onboardpins.19 - remove event too 2018-01-27 13:49:15,574 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed for event OnKeyPressed_onboardpins.19 2018-01-27 13:49:15,575 [TRACE] [doorpi.action.handler] event_source doorpi.keyboard.from_gpio was removed 2018-01-27 13:49:15,577 [TRACE] [doorpi.action.handler] [7S2ME7] try to fire action > with args () and kwargs {} 2018-01-27 13:49:15,578 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-01-27 13:49:15,578 [DEBUG] [doorpi.sipphone.from_linphone] destroy 2018-01-27 13:49:15,579 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_terminate_all_calls(0x743b7f50 [0x21c6370]) 2018-01-27 13:49:15,581 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_terminate_all_calls -> 0x1ef68cc 2018-01-27 13:49:15,582 [DEBUG] [doorpi.action.handler] [F1GQY2] fire for event OnSipPhoneDestroy this actions [, , ] 2018-01-27 13:49:15,583 [TRACE] [doorpi.action.handler] [F1GQY2] try to fire action > with args () and kwargs {} 2018-01-27 13:49:15,584 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-01-27 13:49:15,585 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] destroy 2018-01-27 13:49:15,585 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.CallBacks and force_unregister is True 2018-01-27 13:49:15,586 [TRACE] [doorpi.action.handler] unregister Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,587 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReject - remove event too 2018-01-27 13:49:15,588 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReject 2018-01-27 13:49:15,588 [TRACE] [doorpi.action.handler] unregister Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,589 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaNotRequired - remove event too 2018-01-27 13:49:15,590 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaNotRequired 2018-01-27 13:49:15,591 [TRACE] [doorpi.action.handler] unregister Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,591 [DEBUG] [doorpi.action.handler] no more sources for event OnDTMF - remove event too 2018-01-27 13:49:15,592 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnDTMF 2018-01-27 13:49:15,593 [TRACE] [doorpi.action.handler] unregister Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,594 [DEBUG] [doorpi.action.handler] no more sources for event OnMediaRequired - remove event too 2018-01-27 13:49:15,595 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnMediaRequired 2018-01-27 13:49:15,595 [TRACE] [doorpi.action.handler] unregister Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,596 [DEBUG] [doorpi.action.handler] no more sources for event OnCallMediaStateChange - remove event too 2018-01-27 13:49:15,597 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallMediaStateChange 2018-01-27 13:49:15,598 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,598 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateConnect - remove event too 2018-01-27 13:49:15,599 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateConnect 2018-01-27 13:49:15,600 [TRACE] [doorpi.action.handler] unregister Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,600 [DEBUG] [doorpi.action.handler] no more sources for event OnCallBusy - remove event too 2018-01-27 13:49:15,601 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallBusy 2018-01-27 13:49:15,601 [TRACE] [doorpi.action.handler] unregister Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,602 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallBusy - remove event too 2018-01-27 13:49:15,603 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallBusy 2018-01-27 13:49:15,603 [TRACE] [doorpi.action.handler] unregister Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,604 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallStateDisconnect - remove event too 2018-01-27 13:49:15,604 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallStateDisconnect 2018-01-27 13:49:15,605 [TRACE] [doorpi.action.handler] unregister Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,605 [DEBUG] [doorpi.action.handler] no more sources for event BeforeCallIncoming - remove event too 2018-01-27 13:49:15,606 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event BeforeCallIncoming 2018-01-27 13:49:15,606 [TRACE] [doorpi.action.handler] unregister Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,607 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReject - remove event too 2018-01-27 13:49:15,607 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReject 2018-01-27 13:49:15,608 [TRACE] [doorpi.action.handler] unregister Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,609 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateChange - remove event too 2018-01-27 13:49:15,609 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateChange 2018-01-27 13:49:15,610 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,610 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDismissed - remove event too 2018-01-27 13:49:15,611 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDismissed 2018-01-27 13:49:15,611 [TRACE] [doorpi.action.handler] unregister Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,612 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateDisconnect - remove event too 2018-01-27 13:49:15,612 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateDisconnect 2018-01-27 13:49:15,613 [TRACE] [doorpi.action.handler] unregister Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,614 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStart - remove event too 2018-01-27 13:49:15,614 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStart 2018-01-27 13:49:15,615 [TRACE] [doorpi.action.handler] unregister Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,615 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallReconnect - remove event too 2018-01-27 13:49:15,616 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallReconnect 2018-01-27 13:49:15,616 [TRACE] [doorpi.action.handler] unregister Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,617 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateConnect - remove event too 2018-01-27 13:49:15,617 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateConnect 2018-01-27 13:49:15,618 [TRACE] [doorpi.action.handler] unregister Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,618 [DEBUG] [doorpi.action.handler] no more sources for event OnCallStateReject - remove event too 2018-01-27 13:49:15,619 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallStateReject 2018-01-27 13:49:15,620 [TRACE] [doorpi.action.handler] unregister Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,620 [DEBUG] [doorpi.action.handler] no more sources for event OnCallIncoming - remove event too 2018-01-27 13:49:15,621 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallIncoming 2018-01-27 13:49:15,621 [TRACE] [doorpi.action.handler] unregister Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,622 [DEBUG] [doorpi.action.handler] no more sources for event AfterCallIncoming - remove event too 2018-01-27 13:49:15,622 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event AfterCallIncoming 2018-01-27 13:49:15,623 [TRACE] [doorpi.action.handler] unregister Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2018-01-27 13:49:15,623 [DEBUG] [doorpi.action.handler] no more sources for event OnCallReconnect - remove event too 2018-01-27 13:49:15,624 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed for event OnCallReconnect 2018-01-27 13:49:15,625 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was removed 2018-01-27 13:49:15,626 [TRACE] [doorpi.action.handler] [F1GQY2] try to fire action > with args () and kwargs {} 2018-01-27 13:49:15,626 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-01-27 13:49:15,627 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2018-01-27 13:49:15,628 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Player and force_unregister is True 2018-01-27 13:49:15,629 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:49:15,630 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerCreated 2018-01-27 13:49:15,630 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:49:15,631 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:49:15,632 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStarted - remove event too 2018-01-27 13:49:15,633 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStarted 2018-01-27 13:49:15,633 [TRACE] [doorpi.action.handler] unregister Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2018-01-27 13:49:15,634 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerStopped - remove event too 2018-01-27 13:49:15,634 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed for event OnPlayerStopped 2018-01-27 13:49:15,635 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was removed 2018-01-27 13:49:15,636 [TRACE] [doorpi.action.handler] [F1GQY2] try to fire action > with args () and kwargs {} 2018-01-27 13:49:15,637 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2018-01-27 13:49:15,637 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.linphone_lib.Recorder and force_unregister is True 2018-01-27 13:49:15,638 [TRACE] [doorpi.action.handler] unregister Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:49:15,639 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderCreated - remove event too 2018-01-27 13:49:15,639 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderCreated 2018-01-27 13:49:15,640 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:49:15,640 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStarted - remove event too 2018-01-27 13:49:15,641 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStarted 2018-01-27 13:49:15,641 [TRACE] [doorpi.action.handler] unregister Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2018-01-27 13:49:15,642 [DEBUG] [doorpi.action.handler] no more sources for event OnRecorderStopped - remove event too 2018-01-27 13:49:15,643 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed for event OnRecorderStopped 2018-01-27 13:49:15,643 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was removed 2018-01-27 13:49:15,644 [TRACE] [doorpi.action.handler] [F1GQY2] finished fire_event for event_name OnSipPhoneDestroy 2018-01-27 13:49:15,645 [TRACE] [doorpi.action.handler] unregister Eventsource doorpi.sipphone.from_linphone and force_unregister is True 2018-01-27 13:49:15,645 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,646 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderCreate - remove event too 2018-01-27 13:49:15,647 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderCreate 2018-01-27 13:49:15,647 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,648 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCall - remove event too 2018-01-27 13:49:15,648 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCall 2018-01-27 13:49:15,649 [TRACE] [doorpi.action.handler] unregister Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,649 [DEBUG] [doorpi.action.handler] no more sources for event BeforeSipPhoneMakeCall - remove event too 2018-01-27 13:49:15,650 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event BeforeSipPhoneMakeCall 2018-01-27 13:49:15,650 [TRACE] [doorpi.action.handler] unregister Event OnPlayerCreated from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,651 [DEBUG] [doorpi.action.handler] no more sources for event OnPlayerCreated - remove event too 2018-01-27 13:49:15,652 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnPlayerCreated 2018-01-27 13:49:15,652 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,653 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneRecorderDestroy - remove event too 2018-01-27 13:49:15,653 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneRecorderDestroy 2018-01-27 13:49:15,654 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,654 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneStart - remove event too 2018-01-27 13:49:15,655 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneStart 2018-01-27 13:49:15,655 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,656 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneDestroy - remove event too 2018-01-27 13:49:15,656 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneDestroy 2018-01-27 13:49:15,657 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,657 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutMaxCalltime - remove event too 2018-01-27 13:49:15,658 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutMaxCalltime 2018-01-27 13:49:15,658 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,659 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCallTimeoutNoResponse - remove event too 2018-01-27 13:49:15,660 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCallTimeoutNoResponse 2018-01-27 13:49:15,660 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,661 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneMakeCallFailed - remove event too 2018-01-27 13:49:15,661 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneMakeCallFailed 2018-01-27 13:49:15,662 [TRACE] [doorpi.action.handler] unregister Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,662 [DEBUG] [doorpi.action.handler] no more sources for event AfterSipPhoneMakeCall - remove event too 2018-01-27 13:49:15,663 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event AfterSipPhoneMakeCall 2018-01-27 13:49:15,664 [TRACE] [doorpi.action.handler] unregister Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2018-01-27 13:49:15,664 [DEBUG] [doorpi.action.handler] no more sources for event OnSipPhoneCreate - remove event too 2018-01-27 13:49:15,665 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed for event OnSipPhoneCreate 2018-01-27 13:49:15,665 [TRACE] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was removed 2018-01-27 13:49:15,666 [TRACE] [doorpi.action.handler] [7S2ME7] finished fire_event for event_name OnShutdown 2018-01-27 13:49:15,667 [TRACE] [doorpi.action.handler] fire Event AfterShutdown from doorpi.doorpi asyncron 2018-01-27 13:49:15,669 [DEBUG] [doorpi.action.handler] no actions for event AfterShutdown - skip fire_event AfterShutdown from doorpi.doorpi 2018-01-27 13:49:16,169 [INFO] [doorpi.doorpi] ======== DoorPi successfully shutdown ========