2020-08-09 16:43:12,377 [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 2020-08-09 16:43:12,378 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--debug'] 2020-08-09 16:43:12,379 [DEBUG] [doorpi.doorpi] run 2020-08-09 16:43:12,379 [DEBUG] [doorpi.doorpi] prepare 2020-08-09 16:43:12,380 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=True, test=False, trace=False) 2020-08-09 16:43:12,383 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2020-08-09 16:43:12,394 [DEBUG] [doorpi.conf.config_object] __init__ 2020-08-09 16:43:12,395 [TRACE] [doorpi.conf.config_object] get_from_config 2020-08-09 16:43:12,401 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2020-08-09 16:43:12,402 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2020-08-09 16:43:12,403 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2020-08-09 16:43:12,406 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2020-08-09 16:43:12,407 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2020-08-09 16:43:12,407 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2020-08-09 16:43:12,408 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2020-08-09 16:43:12,408 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2020-08-09 16:43:12,409 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2020-08-09 16:43:12,409 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2020-08-09 16:43:12,410 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2020-08-09 16:43:12,410 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2020-08-09 16:43:12,410 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2020-08-09 16:43:12,411 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2020-08-09 16:43:12,411 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2020-08-09 16:43:12,412 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2020-08-09 16:43:12,413 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2020-08-09 16:43:12,415 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2020-08-09 16:43:12,417 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2020-08-09 16:43:12,418 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2020-08-09 16:43:12,418 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2020-08-09 16:43:12,419 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 8081 2020-08-09 16:43:12,422 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 8081 2020-08-09 16:43:12,423 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2020-08-09 16:43:12,424 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2020-08-09 16:43:12,424 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2020-08-09 16:43:12,424 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2020-08-09 16:43:12,425 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2020-08-09 16:43:12,425 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2020-08-09 16:43:12,427 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2020-08-09 16:43:12,427 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2020-08-09 16:43:12,428 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2020-08-09 16:43:12,429 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2020-08-09 16:43:12,430 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2020-08-09 16:43:12,430 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2020-08-09 16:43:12,431 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2020-08-09 16:43:12,432 [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 2020-08-09 16:43:12,433 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2020-08-09 16:43:12,433 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns ['administrators'] 2020-08-09 16:43:12,434 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns ['administrators'] 2020-08-09 16:43:12,434 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns ['administrators'] 2020-08-09 16:43:12,435 [WARNING] [root] section User not found in configfile 2020-08-09 16:43:12,435 [TRACE] [doorpi.conf.config_object] get_keys for section User returns [] 2020-08-09 16:43:12,436 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section Group (default: []) returns ['admin'] 2020-08-09 16:43:12,436 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'Group', 'ReadPermission', 'AdminNumbers', 'keyboards', 'DTMF', 'SIP-Phone', 'WritePermission', 'EVENT_OnKeyUp_0', 'EVENT_OnKeyPressed_onboardpins.1', 'EVENT_OnStartup', 'EVENT_OnKeyPressed_onboardpins.6', 'rfidreader_InputPins', 'DoorPiWeb', 'rfidreader_keyboard', 'onboardpins_InputPins', 'DoorPi'] 2020-08-09 16:43:12,437 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section WritePermission (default: []) returns ['public'] 2020-08-09 16:43:12,437 [TRACE] [doorpi.conf.config_object] get_list for key administrators in section ReadPermission (default: []) returns ['public'] 2020-08-09 16:43:12,438 [ERROR] [doorpi.status.webserver] user admin is assigned to group administrators but doesn't exist as user 2020-08-09 16:43:12,438 [ERROR] [doorpi.status.webserver] module public doesn't exist but is assigned to group administrators in WritePermission 2020-08-09 16:43:12,439 [ERROR] [doorpi.status.webserver] module public doesn't exist but is assigned to group administrators in ReadPermission 2020-08-09 16:43:12,439 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2020-08-09 16:43:12,440 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2020-08-09 16:43:12,440 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2020-08-09 16:43:12,442 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,443 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2020-08-09 16:43:12,443 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,444 [DEBUG] [doorpi.action.handler] [FR5CKJ] fire for event OnWebServerStart this actions [] 2020-08-09 16:43:12,444 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,445 [TRACE] [doorpi.action.handler] [FR5CKJ] try to fire action WebServerStartupAction with args () and kwargs {} 2020-08-09 16:43:12,445 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,446 [TRACE] [doorpi.action.base] run WebServerStartupAction with args () and kwargs {} 2020-08-09 16:43:12,446 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,447 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,447 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,448 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,448 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,449 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,449 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,449 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,450 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,450 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,451 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,451 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2020-08-09 16:43:12,452 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['rfidreader', 'onboardpins'] 2020-08-09 16:43:12,452 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: rfidreader, onboardpins) 2020-08-09 16:43:12,453 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'rfidreader' to handler 2020-08-09 16:43:12,453 [TRACE] [doorpi.conf.config_object] get_string for key rfidreader in section keyboards (default: dummy) returns hxgreader 2020-08-09 16:43:12,454 [TRACE] [doorpi.conf.config_object] get_keys for section rfidreader_InputPins returns ['10327614'] 2020-08-09 16:43:12,454 [WARNING] [root] section rfidreader_OutputPins not found in configfile 2020-08-09 16:43:12,455 [TRACE] [doorpi.conf.config_object] get_keys for section rfidreader_OutputPins returns [] 2020-08-09 16:43:12,455 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section rfidreader_keyboard (default: 2000) returns 5000.0 2020-08-09 16:43:12,456 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section rfidreader_keyboard (default: 0) returns 0 2020-08-09 16:43:12,456 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section rfidreader_keyboard (default: True) returns True 2020-08-09 16:43:12,458 [ERROR] [doorpi.keyboard.KeyboardInterface] keyboard rfidreader not found @ keyboard.from_hxgreader (msg: No module named evdev) Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/keyboard/KeyboardInterface.py", line 42, in load_single_keyboard keyboard = importlib.import_module('doorpi.keyboard.from_'+keyboard_type).get( File "/usr/lib/python2.7/importlib/__init__.py", line 37, in import_module __import__(name) File "/usr/local/lib/python2.7/dist-packages/doorpi/keyboard/from_hxgreader.py", line 8, in from evdev import InputDevice, event_code, list_devices ImportError: No module named evdev 2020-08-09 16:43:12,460 [ERROR] [doorpi.keyboard.KeyboardInterface] couldn't load keyboard rfidreader 2020-08-09 16:43:12,461 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2020-08-09 16:43:12,462 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2020-08-09 16:43:12,462 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '0', '3', '2', '5', '4', '7', '6'] 2020-08-09 16:43:12,463 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['0'] 2020-08-09 16:43:12,463 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 2000.0 2020-08-09 16:43:12,464 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2020-08-09 16:43:12,464 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2020-08-09 16:43:12,466 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2020-08-09 16:43:12,514 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0', '3', '2', '5', '4', '7', '6'], output_pins = ['0'], polarity = 0) 2020-08-09 16:43:12,605 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,606 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2020-08-09 16:43:12,606 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,607 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,607 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,607 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,608 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,608 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,609 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,609 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,610 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,610 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,610 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,611 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,611 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,612 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,612 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,613 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,613 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,613 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,614 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,614 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,615 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,615 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,616 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.0 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,616 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,616 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,617 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,617 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,618 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,618 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.0 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,618 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,619 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,619 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,620 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,620 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,620 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.0 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,621 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,621 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,622 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,622 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_3 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,623 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.3 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,623 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.3 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,624 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,624 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,624 [TRACE] [doorpi.action.handler] register Event OnKeyUp_3 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,625 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_3 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,625 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.3 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,626 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.3 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,626 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,626 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,627 [TRACE] [doorpi.action.handler] register Event OnKeyDown_3 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,627 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_3 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,628 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.3 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,628 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.3 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,628 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,629 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,629 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,630 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,630 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.2 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,631 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.2 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,631 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,632 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,632 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,632 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,633 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.2 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,633 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.2 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,634 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,634 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,634 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,635 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,635 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.2 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,636 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.2 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,636 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,637 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,637 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_5 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,637 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_5 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,638 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.5 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,638 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.5 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,639 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,639 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,640 [TRACE] [doorpi.action.handler] register Event OnKeyUp_5 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,640 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_5 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,640 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.5 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,641 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.5 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,641 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,642 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,642 [TRACE] [doorpi.action.handler] register Event OnKeyDown_5 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,643 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_5 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,643 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.5 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,643 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.5 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,644 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,644 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,645 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_4 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,645 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_4 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,646 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.4 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,646 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.4 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,646 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,647 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,647 [TRACE] [doorpi.action.handler] register Event OnKeyUp_4 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,648 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_4 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,648 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.4 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,648 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.4 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,649 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,649 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,650 [TRACE] [doorpi.action.handler] register Event OnKeyDown_4 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,650 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_4 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,651 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.4 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,651 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.4 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,651 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,652 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,652 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_7 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,653 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_7 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,653 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.7 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,654 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.7 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,654 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,654 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,655 [TRACE] [doorpi.action.handler] register Event OnKeyUp_7 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,655 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_7 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,656 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.7 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,656 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.7 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,656 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,657 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,657 [TRACE] [doorpi.action.handler] register Event OnKeyDown_7 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,658 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_7 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,658 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.7 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,658 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.7 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,659 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:12,659 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,660 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_6 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,660 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_6 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,661 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.6 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,661 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.6 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,662 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:12,662 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,663 [TRACE] [doorpi.action.handler] register Event OnKeyUp_6 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,663 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_6 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,664 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.6 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,664 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.6 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,664 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:12,665 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2020-08-09 16:43:12,665 [TRACE] [doorpi.action.handler] register Event OnKeyDown_6 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,666 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_6 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,666 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.6 from doorpi.keyboard.from_piface 2020-08-09 16:43:12,667 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.6 and registered source doorpi.keyboard.from_piface 2020-08-09 16:43:12,678 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2020-08-09 16:43:12,679 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['0'] 2020-08-09 16:43:12,680 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_OutputPins (default: ) returns Tueroeffner 2020-08-09 16:43:12,683 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2020-08-09 16:43:12,686 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2020-08-09 16:43:12,688 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2020-08-09 16:43:12,690 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2020-08-09 16:43:12,747 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2020-08-09 16:43:12,749 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2020-08-09 16:43:12,753 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2020-08-09 16:43:12,772 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2020-08-09 16:43:12,779 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2020-08-09 16:43:12,780 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2020-08-09 16:43:12,783 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2020-08-09 16:43:12,786 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2020-08-09 16:43:12,788 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2020-08-09 16:43:12,790 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2020-08-09 16:43:12,792 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2020-08-09 16:43:12,792 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2020-08-09 16:43:12,793 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,793 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2020-08-09 16:43:12,794 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,794 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,795 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,795 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,795 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,796 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,796 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,797 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,797 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,797 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,798 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,798 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,799 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,799 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,799 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,800 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,800 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,801 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,801 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,802 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,802 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,803 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,803 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2020-08-09 16:43:12,803 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2020-08-09 16:43:12,804 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2020-08-09 16:43:12,804 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,805 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2020-08-09 16:43:12,805 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,806 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,806 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,806 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,807 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,807 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,808 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,808 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,808 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,809 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,809 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,810 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,810 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,811 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,811 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,811 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,812 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,812 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,813 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,813 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,814 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,814 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,814 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,815 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"0"'] 2020-08-09 16:43:12,815 [TRACE] [doorpi.action.handler] register Event OnDTMF_"0" from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,816 [TRACE] [doorpi.action.handler] added event_name OnDTMF_"0" and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,816 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,817 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2020-08-09 16:43:12,817 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,817 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,818 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,818 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,819 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,819 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,820 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,820 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,820 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,821 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,821 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,822 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,822 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,822 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,823 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,823 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,824 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,824 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,824 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:12,983 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-08-09 16:43:12,985 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:12,986 [DEBUG] [doorpi.action.handler] no actions for event OnCallStart - skip fire_event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:12,988 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2020-08-09 16:43:12,989 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2020-08-09 16:43:12,990 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-08-09 16:43:12,990 [DEBUG] [doorpi.action.handler] no actions for event OnSipPhoneCreate - skip fire_event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2020-08-09 16:43:12,990 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2020-08-09 16:43:12,993 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2020-08-09 16:43:12,994 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2020-08-09 16:43:12,996 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2020-08-09 16:43:12,997 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2020-08-09 16:43:12,998 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2020-08-09 16:43:12,999 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 15 2020-08-09 16:43:13,000 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2020-08-09 16:43:13,001 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2020-08-09 16:43:13,002 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2020-08-09 16:43:13,004 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2020-08-09 16:43:13,004 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:13,005 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2020-08-09 16:43:13,005 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:13,006 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:13,006 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:13,007 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:13,007 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2020-08-09 16:43:13,008 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2020-08-09 16:43:13,008 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2020-08-09 16:43:13,009 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2020-08-09 16:43:13,009 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:13,011 [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 2020-08-09 16:43:13,012 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2020-08-09 16:43:13,012 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerCreated - skip fire_event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:13,012 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,013 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2020-08-09 16:43:13,014 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,014 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,015 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,015 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,016 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,016 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2020-08-09 16:43:13,017 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2020-08-09 16:43:13,017 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2020-08-09 16:43:13,018 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2020-08-09 16:43:13,019 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2020-08-09 16:43:13,020 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: default device 2020-08-09 16:43:13,020 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderCreated - skip fire_event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:13,021 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2020-08-09 16:43:13,023 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2020-08-09 16:43:13,023 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-09 16:43:13,024 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2020-08-09 16:43:13,025 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 Headphones 2020-08-09 16:43:13,025 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: USB PnP Sound Device 2020-08-09 16:43:13,026 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-09 16:43:13,026 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: default device 2020-08-09 16:43:13,027 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: default device 2020-08-09 16:43:13,027 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU'] 2020-08-09 16:43:13,029 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2020-08-09 16:43:13,030 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-08-09 16:43:13,031 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-08-09 16:43:13,031 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2020-08-09 16:43:13,032 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2020-08-09 16:43:13,033 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2020-08-09 16:43:13,034 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2020-08-09 16:43:13,035 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2020-08-09 16:43:13,035 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-08-09 16:43:13,036 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2020-08-09 16:43:13,037 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2020-08-09 16:43:13,037 [DEBUG] [doorpi.sipphone.from_linphone] | name 2020-08-09 16:43:13,038 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-09 16:43:13,038 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2020-08-09 16:43:13,039 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2020-08-09 16:43:13,039 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2020-08-09 16:43:13,040 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns V4L2: /dev/video0 2020-08-09 16:43:13,040 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2020-08-09 16:43:13,041 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: V4L2: /dev/video0 2020-08-09 16:43:13,042 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2020-08-09 16:43:13,043 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2020-08-09 16:43:13,043 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.2.1 2020-08-09 16:43:13,044 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns klingeltest 2020-08-09 16:43:13,044 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: klingeltest) returns ******* 2020-08-09 16:43:13,045 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.2.1) returns 192.168.2.1 2020-08-09 16:43:13,045 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2020-08-09 16:43:13,046 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2020-08-09 16:43:13,050 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2020-08-09 16:43:13,050 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnKeyUp_0', 'EVENT_OnKeyPressed_onboardpins.1', 'EVENT_OnStartup', 'EVENT_OnKeyPressed_onboardpins.6'] 2020-08-09 16:43:13,051 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyUp_0' in configfile 2020-08-09 16:43:13,051 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyUp_0 returns ['10', '5'] 2020-08-09 16:43:13,052 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyUp_0' 2020-08-09 16:43:13,052 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyUp_0 (default: ) returns call:**612 2020-08-09 16:43:13,053 [DEBUG] [doorpi.action.SingleActions.call] doorpi.action.SingleActions.call loaded 2020-08-09 16:43:13,054 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**612'} was added to new evententry OnKeyUp_0 2020-08-09 16:43:13,055 [INFO] [doorpi.doorpi] registering action '5' for event 'OnKeyUp_0' 2020-08-09 16:43:13,055 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section EVENT_OnKeyUp_0 (default: ) returns os_execute:/usr/local/etc/DoorPi/scripts/Klingelbild.sh 2020-08-09 16:43:13,056 [DEBUG] [doorpi.action.SingleActions.os_execute] doorpi.action.SingleActions.os_execute loaded 2020-08-09 16:43:13,058 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} was added to event OnKeyUp_0 2020-08-09 16:43:13,059 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.1' in configfile 2020-08-09 16:43:13,059 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.1 returns ['10'] 2020-08-09 16:43:13,060 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.1' 2020-08-09 16:43:13,060 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.1 (default: ) returns os_execute:/usr/local/etc/DoorPi/scripts/Sabotagekontakt.sh 2020-08-09 16:43:13,062 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Sabotagekontakt.sh'} was added to new evententry OnKeyPressed_onboardpins.1 2020-08-09 16:43:13,063 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2020-08-09 16:43:13,063 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10', '20'] 2020-08-09 16:43:13,063 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2020-08-09 16:43:13,064 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2020-08-09 16:43:13,065 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2020-08-09 16:43:13,066 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2020-08-09 16:43:13,066 [INFO] [doorpi.doorpi] registering action '20' for event 'OnStartup' 2020-08-09 16:43:13,067 [TRACE] [doorpi.conf.config_object] get_string for key 20 in section EVENT_OnStartup (default: ) returns os_execute:/usr/local/etc/DoorPi/scripts/Startmeldung.sh 2020-08-09 16:43:13,068 [TRACE] [doorpi.action.handler] action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Startmeldung.sh'} was added to event OnStartup 2020-08-09 16:43:13,069 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnKeyPressed_onboardpins.6' in configfile 2020-08-09 16:43:13,069 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnKeyPressed_onboardpins.6 returns ['10'] 2020-08-09 16:43:13,070 [INFO] [doorpi.doorpi] registering action '10' for event 'OnKeyPressed_onboardpins.6' 2020-08-09 16:43:13,070 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnKeyPressed_onboardpins.6 (default: ) returns out:Tueroeffner,1,0,3 2020-08-09 16:43:13,071 [DEBUG] [doorpi.action.SingleActions.out] doorpi.action.SingleActions.out loaded 2020-08-09 16:43:13,072 [DEBUG] [doorpi.action.SingleActions.out_triggered] doorpi.action.SingleActions.out_triggered loaded 2020-08-09 16:43:13,073 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnKeyPressed_onboardpins.6 2020-08-09 16:43:13,074 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,074 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to event OnKeyPressed_onboardpins.1 2020-08-09 16:43:13,075 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_InputPins (default: ) returns call:**612 2020-08-09 16:43:13,075 [TRACE] [doorpi.action.handler] action CallAction with args () and kwargs {'number': '**612'} was added to new evententry OnKeyPressed_onboardpins.0 2020-08-09 16:43:13,076 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,076 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.3 2020-08-09 16:43:13,077 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,077 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.2 2020-08-09 16:43:13,078 [TRACE] [doorpi.conf.config_object] get_string for key 5 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,078 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.5 2020-08-09 16:43:13,079 [TRACE] [doorpi.conf.config_object] get_string for key 4 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,079 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.4 2020-08-09 16:43:13,080 [TRACE] [doorpi.conf.config_object] get_string for key 7 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,080 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.7 2020-08-09 16:43:13,081 [TRACE] [doorpi.conf.config_object] get_string for key 6 in section onboardpins_InputPins (default: ) returns sleep:0 2020-08-09 16:43:13,082 [TRACE] [doorpi.action.handler] action SleepAction with args (0.0,) and kwargs {} was added to event OnKeyPressed_onboardpins.6 2020-08-09 16:43:13,082 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns ['"0"'] 2020-08-09 16:43:13,083 [TRACE] [doorpi.conf.config_object] get_string for key "0" in section DTMF (default: ) returns out:Tueroeffner,1,0,3 2020-08-09 16:43:13,083 [TRACE] [doorpi.action.handler] action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} was added to new evententry OnDTMF_"0" 2020-08-09 16:43:13,084 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2020-08-09 16:43:13,084 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron 2020-08-09 16:43:13,086 [DEBUG] [doorpi.action.handler] [9HILG0] fire for event OnStartup this actions [, ] 2020-08-09 16:43:13,087 [DEBUG] [doorpi.action.handler] no actions for event BeforeStartup - skip fire_event BeforeStartup from doorpi.doorpi 2020-08-09 16:43:13,087 [TRACE] [doorpi.action.handler] [9HILG0] try to fire action SleepAction with args (1.0,) and kwargs {} 2020-08-09 16:43:13,088 [TRACE] [doorpi.action.base] run SleepAction with args (1.0,) and kwargs {} 2020-08-09 16:43:14,091 [TRACE] [doorpi.action.handler] [9HILG0] try to fire action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Startmeldung.sh'} 2020-08-09 16:43:14,092 [TRACE] [doorpi.action.base] run OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Startmeldung.sh'} /bin/sh: 1: /usr/local/etc/DoorPi/scripts/Startmeldung.sh: not found 2020-08-09 16:43:14,109 [TRACE] [doorpi.action.handler] [9HILG0] finished fire_event for event_name OnStartup 2020-08-09 16:43:14,110 [TRACE] [doorpi.action.handler] fire Event AfterStartup from doorpi.doorpi asyncron 2020-08-09 16:43:14,112 [INFO] [doorpi.doorpi] DoorPi started successfully 2020-08-09 16:43:14,113 [INFO] [doorpi.doorpi] BasePath is /usr/local/etc/DoorPi 2020-08-09 16:43:14,113 [INFO] [doorpi.doorpi] Weburl is http://raspberrypi:8081/ 2020-08-09 16:43:14,114 [DEBUG] [doorpi.action.handler] no actions for event AfterStartup - skip fire_event AfterStartup from doorpi.doorpi 2020-08-09 16:43:25,550 [TRACE] [doorpi.action.handler] fire Event OnKeyDown from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:25,552 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_0 from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:25,554 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown - skip fire_event OnKeyDown from doorpi.keyboard.from_piface 2020-08-09 16:43:25,554 [TRACE] [doorpi.action.handler] fire Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:25,556 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_0 - skip fire_event OnKeyDown_0 from doorpi.keyboard.from_piface 2020-08-09 16:43:25,558 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:25,559 [DEBUG] [doorpi.action.handler] no actions for event OnKeyDown_onboardpins.0 - skip fire_event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-09 16:43:25,560 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_0 from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:25,562 [TRACE] [doorpi.action.handler] fire Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:25,564 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed_0 - skip fire_event OnKeyPressed_0 from doorpi.keyboard.from_piface 2020-08-09 16:43:25,565 [DEBUG] [doorpi.action.handler] no actions for event OnKeyPressed - skip fire_event OnKeyPressed from doorpi.keyboard.from_piface 2020-08-09 16:43:25,565 [DEBUG] [doorpi.action.handler] [JI97QC] fire for event OnKeyPressed_onboardpins.0 this actions [] 2020-08-09 16:43:25,566 [TRACE] [doorpi.action.handler] [JI97QC] try to fire action CallAction with args () and kwargs {'number': '**612'} 2020-08-09 16:43:25,567 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '**612'} 2020-08-09 16:43:25,568 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:25,569 [DEBUG] [doorpi.sipphone.from_linphone] call (**612) 2020-08-09 16:43:25,570 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2020-08-09 16:43:25,570 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:25,571 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2020-08-09 15:43:25.571186 2020-08-09 16:43:25,581 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call) 2020-08-09 16:43:25,582 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:25,584 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:25,585 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:25,591 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress) 2020-08-09 16:43:25,593 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:25,594 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:25,597 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:25,598 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:25,599 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:25,599 [DEBUG] [doorpi.action.handler] [ALNC8W] fire for event OnSipPhoneMakeCall this actions [] 2020-08-09 16:43:25,601 [TRACE] [doorpi.action.handler] [ALNC8W] try to fire action > with args () and kwargs {} 2020-08-09 16:43:25,602 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:25,602 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:25,605 [TRACE] [doorpi.action.handler] [JI97QC] finished fire_event for event_name OnKeyPressed_onboardpins.0 2020-08-09 16:43:25,606 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:25,607 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:25,608 [TRACE] [doorpi.action.handler] [ALNC8W] finished fire_event for event_name OnSipPhoneMakeCall 2020-08-09 16:43:25,768 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (5 - Early media) 2020-08-09 16:43:25,769 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:25,771 [TRACE] [doorpi.action.handler] fire Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:25,822 [DEBUG] [doorpi.action.handler] no actions for event OnCallMediaStateChange - skip fire_event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:25,822 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:29,675 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (6 - Connected) 2020-08-09 16:43:29,676 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:29,677 [TRACE] [doorpi.action.handler] fire Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:29,678 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:29,680 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (7 - Streams running) 2020-08-09 16:43:29,681 [DEBUG] [doorpi.action.handler] [P6SYSP] fire for event OnCallStateConnect this actions [, ] 2020-08-09 16:43:29,682 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:29,683 [TRACE] [doorpi.action.handler] [P6SYSP] try to fire action > with args () and kwargs {} 2020-08-09 16:43:29,684 [TRACE] [doorpi.action.handler] fire Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:29,685 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:29,685 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:29,687 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:29,687 [TRACE] [doorpi.action.handler] fire Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:29,690 [DEBUG] [doorpi.action.handler] no actions for event AfterCallStateConnect - skip fire_event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:29,691 [TRACE] [doorpi.action.handler] [P6SYSP] try to fire action > with args () and kwargs {} 2020-08-09 16:43:29,692 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:29,693 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:29,694 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] starting recording to /usr/local/etc/DoorPi/records/2020-08-09_16-43-25.wav 2020-08-09 16:43:29,697 [TRACE] [doorpi.action.handler] fire Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder asyncron 2020-08-09 16:43:29,699 [DEBUG] [doorpi.action.handler] no actions for event OnCallMediaStateChange - skip fire_event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:29,701 [TRACE] [doorpi.action.handler] [P6SYSP] finished fire_event for event_name OnCallStateConnect 2020-08-09 16:43:29,702 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderStarted - skip fire_event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:31,478 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] on_dtmf_digit (48) 2020-08-09 16:43:31,480 [TRACE] [doorpi.action.handler] fire Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:31,482 [TRACE] [doorpi.action.handler] fire Event OnDTMF_"0" from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:31,485 [DEBUG] [doorpi.action.handler] no actions for event OnDTMF - skip fire_event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:31,486 [DEBUG] [doorpi.action.handler] [VN9ESD] fire for event OnDTMF_"0" this actions [] 2020-08-09 16:43:31,487 [TRACE] [doorpi.action.handler] [VN9ESD] try to fire action OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} 2020-08-09 16:43:31,488 [TRACE] [doorpi.action.base] run OutTriggeredAction with args () and kwargs {'stop_pin': 'NoStopPinSet', 'end_value': '0', 'timeout': 3.0, 'pin': 'Tueroeffner', 'start_value': '1'} 2020-08-09 16:43:31,490 [DEBUG] [doorpi.keyboard.from_piface] out(pin = 0, value = True, log_output = True) 2020-08-09 16:43:33,498 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (13 - Call ended) 2020-08-09 16:43:33,500 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:33,501 [TRACE] [doorpi.action.handler] fire Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:33,503 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:33,504 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:33,506 [DEBUG] [doorpi.action.handler] [ELK0RK] fire for event OnCallStateDisconnect this actions [, ] 2020-08-09 16:43:33,507 [TRACE] [doorpi.action.handler] [ELK0RK] try to fire action > with args () and kwargs {} 2020-08-09 16:43:33,508 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:33,508 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:33,509 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:33,511 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:33,511 [TRACE] [doorpi.action.handler] [ELK0RK] try to fire action > with args () and kwargs {} 2020-08-09 16:43:33,512 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:33,513 [TRACE] [doorpi.action.handler] [ELK0RK] finished fire_event for event_name OnCallStateDisconnect 2020-08-09 16:43:33,608 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2020-08-09 16:43:33,609 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:33,611 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:34,504 [DEBUG] [doorpi.keyboard.from_piface] out(pin = 0, value = False, log_output = True) 2020-08-09 16:43:34,507 [TRACE] [doorpi.action.handler] [VN9ESD] finished fire_event for event_name OnDTMF_"0" 2020-08-09 16:43:34,554 [TRACE] [doorpi.action.handler] fire Event OnKeyUp from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:34,556 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_0 from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:34,558 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp - skip fire_event OnKeyUp from doorpi.keyboard.from_piface 2020-08-09 16:43:34,559 [TRACE] [doorpi.action.handler] fire Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface asyncron 2020-08-09 16:43:34,560 [DEBUG] [doorpi.action.handler] [LXK2WO] fire for event OnKeyUp_0 this actions [, ] 2020-08-09 16:43:34,561 [TRACE] [doorpi.action.handler] [LXK2WO] try to fire action CallAction with args () and kwargs {'number': '**612'} 2020-08-09 16:43:34,562 [TRACE] [doorpi.action.base] run CallAction with args () and kwargs {'number': '**612'} 2020-08-09 16:43:34,563 [TRACE] [doorpi.action.handler] fire Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:34,563 [DEBUG] [doorpi.action.handler] no actions for event OnKeyUp_onboardpins.0 - skip fire_event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2020-08-09 16:43:34,565 [DEBUG] [doorpi.sipphone.from_linphone] call (**612) 2020-08-09 16:43:34,566 [DEBUG] [doorpi.action.handler] no actions for event BeforeSipPhoneMakeCall - skip fire_event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:34,566 [DEBUG] [doorpi.sipphone.from_linphone] no current call -> start new call 2020-08-09 16:43:34,567 [DEBUG] [doorpi.sipphone.from_linphone] reset current call start datetime to 2020-08-09 15:43:34.567688 2020-08-09 16:43:34,576 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (2 - Starting outgoing call) 2020-08-09 16:43:34,578 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:34,579 [TRACE] [doorpi.action.handler] fire Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:34,581 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:34,586 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (3 - Outgoing call in progress) 2020-08-09 16:43:34,588 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:34,588 [DEBUG] [doorpi.action.handler] no actions for event OnMediaRequired - skip fire_event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:34,590 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:34,591 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:34,593 [TRACE] [doorpi.action.handler] fire Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone asyncron 2020-08-09 16:43:34,594 [DEBUG] [doorpi.action.handler] [IUQTMP] fire for event OnSipPhoneMakeCall this actions [] 2020-08-09 16:43:34,595 [TRACE] [doorpi.action.handler] [IUQTMP] try to fire action > with args () and kwargs {} 2020-08-09 16:43:34,596 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:34,596 [TRACE] [doorpi.action.handler] fire Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:34,599 [TRACE] [doorpi.action.handler] [LXK2WO] try to fire action OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} 2020-08-09 16:43:34,599 [DEBUG] [doorpi.action.handler] no actions for event AfterSipPhoneMakeCall - skip fire_event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2020-08-09 16:43:34,600 [TRACE] [doorpi.action.base] run OsExecuteAction with args () and kwargs {'command': '/usr/local/etc/DoorPi/scripts/Klingelbild.sh'} 2020-08-09 16:43:34,602 [TRACE] [doorpi.action.handler] [IUQTMP] finished fire_event for event_name OnSipPhoneMakeCall 2020-08-09 16:43:34,608 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStarted - skip fire_event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player /bin/sh: 1: /usr/local/etc/DoorPi/scripts/Klingelbild.sh: not found 2020-08-09 16:43:34,621 [TRACE] [doorpi.action.handler] [LXK2WO] finished fire_event for event_name OnKeyUp_0 2020-08-09 16:43:34,773 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (5 - Early media) 2020-08-09 16:43:34,774 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:34,775 [TRACE] [doorpi.action.handler] fire Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:34,782 [DEBUG] [doorpi.action.handler] no actions for event OnCallMediaStateChange - skip fire_event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:34,782 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:38,686 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (6 - Connected) 2020-08-09 16:43:38,687 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:38,689 [TRACE] [doorpi.action.handler] fire Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:38,690 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:38,691 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (7 - Streams running) 2020-08-09 16:43:38,693 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:38,694 [DEBUG] [doorpi.action.handler] [ZVO0WO] fire for event OnCallStateConnect this actions [, ] 2020-08-09 16:43:38,695 [TRACE] [doorpi.action.handler] fire Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:38,696 [TRACE] [doorpi.action.handler] [ZVO0WO] try to fire action > with args () and kwargs {} 2020-08-09 16:43:38,697 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:38,698 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:38,698 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:38,699 [TRACE] [doorpi.action.handler] fire Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:38,701 [DEBUG] [doorpi.action.handler] no actions for event AfterCallStateConnect - skip fire_event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:38,705 [TRACE] [doorpi.action.handler] [ZVO0WO] try to fire action > with args () and kwargs {} 2020-08-09 16:43:38,706 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:38,707 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:38,708 [DEBUG] [doorpi.action.handler] no actions for event OnCallMediaStateChange - skip fire_event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:38,708 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] starting recording to /usr/local/etc/DoorPi/records/2020-08-09_16-43-34.wav 2020-08-09 16:43:38,710 [TRACE] [doorpi.action.handler] fire Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder asyncron 2020-08-09 16:43:38,712 [DEBUG] [doorpi.action.handler] no actions for event OnRecorderStarted - skip fire_event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2020-08-09 16:43:38,712 [TRACE] [doorpi.action.handler] [ZVO0WO] finished fire_event for event_name OnCallStateConnect 2020-08-09 16:43:40,414 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (13 - Call ended) 2020-08-09 16:43:40,415 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:40,417 [TRACE] [doorpi.action.handler] fire Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:40,419 [TRACE] [doorpi.action.handler] fire Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:40,419 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:40,421 [DEBUG] [doorpi.action.handler] [4CLBWF] fire for event OnCallStateDisconnect this actions [, ] 2020-08-09 16:43:40,422 [TRACE] [doorpi.action.handler] [4CLBWF] try to fire action > with args () and kwargs {} 2020-08-09 16:43:40,423 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:40,424 [DEBUG] [doorpi.action.handler] no actions for event OnMediaNotRequired - skip fire_event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2020-08-09 16:43:40,424 [TRACE] [doorpi.action.handler] fire Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player asyncron 2020-08-09 16:43:40,426 [TRACE] [doorpi.action.handler] [4CLBWF] try to fire action > with args () and kwargs {} 2020-08-09 16:43:40,427 [DEBUG] [doorpi.action.handler] no actions for event OnPlayerStopped - skip fire_event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2020-08-09 16:43:40,427 [TRACE] [doorpi.action.base] run SingleAction with args () and kwargs {} 2020-08-09 16:43:40,429 [TRACE] [doorpi.action.handler] [4CLBWF] finished fire_event for event_name OnCallStateDisconnect 2020-08-09 16:43:40,522 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] call_state_changed (18 - Call released) 2020-08-09 16:43:40,523 [TRACE] [doorpi.action.handler] fire Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks asyncron 2020-08-09 16:43:40,526 [DEBUG] [doorpi.action.handler] no actions for event OnCallStateChange - skip fire_event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks