pi@raspi:~ $ sudo doorpi_cli --trace 2017-12-03 12:08:22,378 [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 2017-12-03 12:08:22,379 [DEBUG] [doorpi.main] loaded with arguments: ['/usr/local/bin/doorpi_cli', '--trace'] 2017-12-03 12:08:22,379 [DEBUG] [doorpi.doorpi] run 2017-12-03 12:08:22,379 [DEBUG] [doorpi.doorpi] prepare 2017-12-03 12:08:22,380 [DEBUG] [doorpi.doorpi] given arguments argv: Namespace(configfile=None, debug=False, test=False, trace=True) 2017-12-03 12:08:22,382 [INFO] [doorpi.conf.config_object] use configfile: /usr/local/etc/DoorPi/conf/doorpi.ini 2017-12-03 12:08:22,386 [DEBUG] [doorpi.conf.config_object] __init__ 2017-12-03 12:08:22,386 [TRACE] [doorpi.conf.config_object] get_from_config 2017-12-03 12:08:22,391 [TRACE] [doorpi.conf.config_object] get_string for key base_path in section DoorPi (default: /usr/local/etc/DoorPi) returns /usr/local/etc/DoorPi 2017-12-03 12:08:22,391 [TRACE] [doorpi.conf.config_object] get_string for key eventlog in section DoorPi (default: !BASEPATH!/conf/eventlog.db) returns !BASEPATH!/conf/eventlog.db 2017-12-03 12:08:22,392 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/conf/eventlog.db" to "/usr/local/etc/DoorPi/conf/eventlog.db" 2017-12-03 12:08:22,395 [TRACE] [doorpi.action.handler] register Event BeforeStartup from doorpi.doorpi 2017-12-03 12:08:22,396 [DEBUG] [doorpi.action.handler] event_source doorpi.doorpi was added 2017-12-03 12:08:22,397 [TRACE] [doorpi.action.handler] added event_name BeforeStartup and registered source doorpi.doorpi 2017-12-03 12:08:22,397 [TRACE] [doorpi.action.handler] register Event OnStartup from doorpi.doorpi 2017-12-03 12:08:22,397 [TRACE] [doorpi.action.handler] added event_name OnStartup and registered source doorpi.doorpi 2017-12-03 12:08:22,398 [TRACE] [doorpi.action.handler] register Event AfterStartup from doorpi.doorpi 2017-12-03 12:08:22,398 [TRACE] [doorpi.action.handler] added event_name AfterStartup and registered source doorpi.doorpi 2017-12-03 12:08:22,399 [TRACE] [doorpi.action.handler] register Event BeforeShutdown from doorpi.doorpi 2017-12-03 12:08:22,399 [TRACE] [doorpi.action.handler] added event_name BeforeShutdown and registered source doorpi.doorpi 2017-12-03 12:08:22,399 [TRACE] [doorpi.action.handler] register Event OnShutdown from doorpi.doorpi 2017-12-03 12:08:22,400 [TRACE] [doorpi.action.handler] added event_name OnShutdown and registered source doorpi.doorpi 2017-12-03 12:08:22,400 [TRACE] [doorpi.action.handler] register Event AfterShutdown from doorpi.doorpi 2017-12-03 12:08:22,401 [TRACE] [doorpi.action.handler] added event_name AfterShutdown and registered source doorpi.doorpi 2017-12-03 12:08:22,403 [DEBUG] [doorpi.action.SingleActions.time_tick] doorpi.action.SingleActions.time_tick loaded 2017-12-03 12:08:22,404 [DEBUG] [doorpi.action.handler] event_source doorpi.action.SingleActions.time_tick was added 2017-12-03 12:08:22,408 [TRACE] [doorpi.action.handler] action TimeTickDestroyAction with args () and kwargs {} was added to new evententry OnShutdown 2017-12-03 12:08:22,408 [TRACE] [doorpi.action.handler] action TimeTickAction with args ('!last_tick!',) and kwargs {} was added to new evententry OnTimeTick 2017-12-03 12:08:22,409 [TRACE] [doorpi.conf.config_object] get_string for key ip in section DoorPiWeb (default: ) returns 2017-12-03 12:08:22,409 [TRACE] [doorpi.conf.config_object] get_integer for key port in section DoorPiWeb (default: 80) returns 80 2017-12-03 12:08:22,411 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2017-12-03 12:08:22,412 [WARNING] [doorpi.status.webserver] failed to initiating WebService at ip and port 80 ([Errno 98] Address already in use) 2017-12-03 12:08:22,415 [INFO] [doorpi.status.webserver] Initiating WebService at ip and port 8080 2017-12-03 12:08:22,415 [TRACE] [doorpi.action.handler] register Event OnWebServerStart from doorpi.status.webserver 2017-12-03 12:08:22,416 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver was added 2017-12-03 12:08:22,416 [TRACE] [doorpi.action.handler] added event_name OnWebServerStart and registered source doorpi.status.webserver 2017-12-03 12:08:22,417 [TRACE] [doorpi.action.handler] register Event OnWebServerStop from doorpi.status.webserver 2017-12-03 12:08:22,417 [TRACE] [doorpi.action.handler] added event_name OnWebServerStop and registered source doorpi.status.webserver 2017-12-03 12:08:22,418 [TRACE] [doorpi.conf.config_object] get_string for key www in section DoorPiWeb (default: !BASEPATH!/../DoorPiWeb) returns !BASEPATH!/../DoorPiWeb 2017-12-03 12:08:22,419 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/../DoorPiWeb" to "/usr/local/etc/DoorPi/../DoorPiWeb" 2017-12-03 12:08:22,419 [TRACE] [doorpi.conf.config_object] get_string for key indexfile in section DoorPiWeb (default: index.html) returns index.html 2017-12-03 12:08:22,421 [DEBUG] [doorpi.conf.config_object] parse string "index.html" to "index.html" 2017-12-03 12:08:22,421 [TRACE] [doorpi.conf.config_object] get_string for key loginfile in section DoorPiWeb (default: login.html) returns login.html 2017-12-03 12:08:22,422 [DEBUG] [doorpi.conf.config_object] parse string "login.html" to "login.html" 2017-12-03 12:08:22,423 [TRACE] [doorpi.conf.config_object] get_string for key public in section DoorPiWeb (default: AREA_public) returns AREA_public 2017-12-03 12:08:22,424 [DEBUG] [doorpi.conf.config_object] parse string "AREA_public" to "AREA_public" 2017-12-03 12:08:22,425 [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 2017-12-03 12:08:22,426 [DEBUG] [doorpi.conf.config_object] parse string "http://motom001.github.io/DoorPiWeb" to "http://motom001.github.io/DoorPiWeb" 2017-12-03 12:08:22,426 [WARNING] [root] section WritePermission not found in configfile 2017-12-03 12:08:22,427 [TRACE] [doorpi.conf.config_object] get_keys for section WritePermission returns [] 2017-12-03 12:08:22,427 [WARNING] [root] section ReadPermission not found in configfile 2017-12-03 12:08:22,428 [TRACE] [doorpi.conf.config_object] get_keys for section ReadPermission returns [] 2017-12-03 12:08:22,428 [WARNING] [root] section Group not found in configfile 2017-12-03 12:08:22,429 [TRACE] [doorpi.conf.config_object] get_keys for section Group returns [] 2017-12-03 12:08:22,429 [TRACE] [doorpi.conf.config_object] get_keys for section User returns ['door'] 2017-12-03 12:08:22,430 [TRACE] [doorpi.conf.config_object] get_sections returns ['onboardpins_OutputPins', 'onboardpins_keyboard', 'keyboards', 'AdminNumbers', 'SIP-Phone', 'EVENT_OnStartup', 'User', 'DoorPiWeb', 'onboardpins_InputPins', 'DoorPi'] 2017-12-03 12:08:22,431 [ERROR] [doorpi.status.webserver] no ReadPermission found 2017-12-03 12:08:22,431 [ERROR] [doorpi.status.webserver] no groups in configfile! 2017-12-03 12:08:22,431 [ERROR] [doorpi.status.webserver] no WritePermission found 2017-12-03 12:08:22,432 [TRACE] [doorpi.action.handler] action WebServerStartupAction with args () and kwargs {} was added to new evententry OnWebServerStart 2017-12-03 12:08:22,432 [TRACE] [doorpi.action.handler] action WebServerShutdownAction with args () and kwargs {} was added to event OnShutdown 2017-12-03 12:08:22,433 [TRACE] [doorpi.action.handler] fire Event OnWebServerStart from doorpi.status.webserver asyncron 2017-12-03 12:08:22,435 [TRACE] [doorpi.action.handler] register Event OnWebServerRequest from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,436 [DEBUG] [doorpi.action.handler] event_source doorpi.status.webserver_lib.request_handler was added 2017-12-03 12:08:22,436 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequest and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,437 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestGet from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,437 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestGet and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,438 [TRACE] [doorpi.action.handler] register Event OnWebServerRequestPost from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,438 [TRACE] [doorpi.action.handler] added event_name OnWebServerRequestPost and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,438 [TRACE] [doorpi.action.handler] register Event OnWebServerVirtualResource from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,439 [TRACE] [doorpi.action.handler] added event_name OnWebServerVirtualResource and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,439 [TRACE] [doorpi.action.handler] register Event OnWebServerRealResource from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,440 [TRACE] [doorpi.action.handler] added event_name OnWebServerRealResource and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,440 [TRACE] [doorpi.action.handler] register Event OnFireEvent from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,441 [TRACE] [doorpi.action.handler] added event_name OnFireEvent and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,441 [TRACE] [doorpi.action.handler] register Event OnConfigKeySet from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,441 [TRACE] [doorpi.action.handler] added event_name OnConfigKeySet and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,442 [TRACE] [doorpi.action.handler] register Event OnConfigKeyDelete from doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,442 [TRACE] [doorpi.action.handler] added event_name OnConfigKeyDelete and registered source doorpi.status.webserver_lib.request_handler 2017-12-03 12:08:22,443 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnTimeSecondEvenNumber 2017-12-03 12:08:22,443 [TRACE] [doorpi.conf.config_object] get_keys for section keyboards returns ['onboardpins'] 2017-12-03 12:08:22,444 [INFO] [doorpi.keyboard.KeyboardInterface] using multi-keyboard mode (keyboards: onboardpins) 2017-12-03 12:08:22,444 [INFO] [doorpi.keyboard.KeyboardInterface] trying to add keyboard 'onboardpins' to handler 2017-12-03 12:08:22,445 [TRACE] [doorpi.conf.config_object] get_string for key onboardpins in section keyboards (default: dummy) returns piface 2017-12-03 12:08:22,445 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_InputPins returns ['1', '0', '3', '2'] 2017-12-03 12:08:22,446 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['1', '0'] 2017-12-03 12:08:22,446 [TRACE] [doorpi.conf.config_object] get_integer for key bouncetime in section onboardpins_keyboard (default: 2000) returns 200.0 2017-12-03 12:08:22,447 [TRACE] [doorpi.conf.config_object] get_integer for key polarity in section onboardpins_keyboard (default: 0) returns 0 2017-12-03 12:08:22,447 [TRACE] [doorpi.conf.config_object] get_boolean for key pressed_on_keydown in section onboardpins_keyboard (default: True) returns True 2017-12-03 12:08:22,449 [DEBUG] [doorpi.keyboard.from_piface] doorpi.keyboard.from_piface loaded 2017-12-03 12:08:22,496 [DEBUG] [doorpi.keyboard.from_piface] __init__(input_pins = ['1', '0', '3', '2'], output_pins = ['1', '0'], polarity = 0) 2017-12-03 12:08:22,569 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2017-12-03 12:08:22,569 [DEBUG] [doorpi.action.handler] event_source doorpi.keyboard.from_piface was added 2017-12-03 12:08:22,570 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,570 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_1 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,571 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_1 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,571 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.1 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,572 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.1 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,572 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2017-12-03 12:08:22,573 [TRACE] [doorpi.action.handler] added event_name OnKeyUp and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,573 [TRACE] [doorpi.action.handler] register Event OnKeyUp_1 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,573 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_1 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,574 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.1 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,574 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.1 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,575 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2017-12-03 12:08:22,575 [TRACE] [doorpi.action.handler] added event_name OnKeyDown and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,576 [TRACE] [doorpi.action.handler] register Event OnKeyDown_1 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,576 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_1 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,577 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.1 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,577 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.1 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,577 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2017-12-03 12:08:22,578 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,578 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_0 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,579 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_0 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,579 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.0 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,580 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.0 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,580 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2017-12-03 12:08:22,581 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,581 [TRACE] [doorpi.action.handler] register Event OnKeyUp_0 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,581 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_0 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,582 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.0 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,582 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.0 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,583 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2017-12-03 12:08:22,583 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,583 [TRACE] [doorpi.action.handler] register Event OnKeyDown_0 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,584 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_0 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,584 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.0 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,585 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.0 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,585 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2017-12-03 12:08:22,586 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,586 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_3 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,587 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_3 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,587 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.3 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,587 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.3 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,588 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2017-12-03 12:08:22,588 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,589 [TRACE] [doorpi.action.handler] register Event OnKeyUp_3 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,589 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_3 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,590 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.3 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,590 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.3 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,590 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2017-12-03 12:08:22,591 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,591 [TRACE] [doorpi.action.handler] register Event OnKeyDown_3 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,592 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_3 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,592 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.3 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,593 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.3 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,593 [TRACE] [doorpi.action.handler] register Event OnKeyPressed from doorpi.keyboard.from_piface 2017-12-03 12:08:22,594 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyPressed from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,594 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_2 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,594 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_2 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,595 [TRACE] [doorpi.action.handler] register Event OnKeyPressed_onboardpins.2 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,595 [TRACE] [doorpi.action.handler] added event_name OnKeyPressed_onboardpins.2 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,596 [TRACE] [doorpi.action.handler] register Event OnKeyUp from doorpi.keyboard.from_piface 2017-12-03 12:08:22,596 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyUp from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,596 [TRACE] [doorpi.action.handler] register Event OnKeyUp_2 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,597 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_2 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,597 [TRACE] [doorpi.action.handler] register Event OnKeyUp_onboardpins.2 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,598 [TRACE] [doorpi.action.handler] added event_name OnKeyUp_onboardpins.2 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,598 [TRACE] [doorpi.action.handler] register Event OnKeyDown from doorpi.keyboard.from_piface 2017-12-03 12:08:22,599 [TRACE] [doorpi.action.handler] nothing to do - event OnKeyDown from source doorpi.keyboard.from_piface is already known 2017-12-03 12:08:22,599 [TRACE] [doorpi.action.handler] register Event OnKeyDown_2 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,599 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_2 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,600 [TRACE] [doorpi.action.handler] register Event OnKeyDown_onboardpins.2 from doorpi.keyboard.from_piface 2017-12-03 12:08:22,600 [TRACE] [doorpi.action.handler] added event_name OnKeyDown_onboardpins.2 and registered source doorpi.keyboard.from_piface 2017-12-03 12:08:22,611 [TRACE] [doorpi.action.handler] action KeyboardDestroyAction with args () and kwargs {} was added to event OnShutdown 2017-12-03 12:08:22,612 [TRACE] [doorpi.conf.config_object] get_keys for section onboardpins_OutputPins returns ['1', '0'] 2017-12-03 12:08:22,613 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_OutputPins (default: ) returns Klingel 2017-12-03 12:08:22,614 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_OutputPins (default: ) returns Tueroeffner 2017-12-03 12:08:22,617 [DEBUG] [doorpi.status.status_lib.environment] doorpi.status.status_lib.environment loaded 2017-12-03 12:08:22,620 [DEBUG] [doorpi.status.requirements_lib.req_config] doorpi.status.requirements_lib.req_config loaded 2017-12-03 12:08:22,622 [DEBUG] [doorpi.status.requirements_lib.req_sipphone] doorpi.status.requirements_lib.req_sipphone loaded 2017-12-03 12:08:22,624 [DEBUG] [doorpi.sipphone.AbstractBaseClass] doorpi.sipphone.AbstractBaseClass loaded 2017-12-03 12:08:22,669 [DEBUG] [doorpi.status.requirements_lib.req_event_handler] doorpi.status.requirements_lib.req_event_handler loaded 2017-12-03 12:08:22,672 [DEBUG] [doorpi.status.requirements_lib.req_webserver] doorpi.status.requirements_lib.req_webserver loaded 2017-12-03 12:08:22,675 [DEBUG] [doorpi.status.requirements_lib.req_keyboard] doorpi.status.requirements_lib.req_keyboard loaded 2017-12-03 12:08:22,694 [DEBUG] [doorpi.status.requirements_lib.req_system] doorpi.status.requirements_lib.req_system loaded 2017-12-03 12:08:22,700 [INFO] [doorpi.sipphone.SipphoneInterface] found installed sipphone "linphone" and use this as default 2017-12-03 12:08:22,701 [TRACE] [doorpi.conf.config_object] get_string for key sipphonetyp in section SIP-Phone (default: linphone) returns linphone 2017-12-03 12:08:22,703 [DEBUG] [doorpi.sipphone.from_linphone] doorpi.sipphone.from_linphone loaded 2017-12-03 12:08:22,706 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] doorpi.sipphone.linphone_lib.CallBacks loaded 2017-12-03 12:08:22,708 [DEBUG] [doorpi.sipphone.linphone_lib.Player] doorpi.sipphone.linphone_lib.Player loaded 2017-12-03 12:08:22,712 [DEBUG] [doorpi.sipphone.linphone_lib.Recorder] doorpi.sipphone.linphone_lib.Recorder loaded 2017-12-03 12:08:22,713 [DEBUG] [doorpi.sipphone.from_linphone] __init__ 2017-12-03 12:08:22,714 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnShutdown 2017-12-03 12:08:22,714 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCreate from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,715 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.from_linphone was added 2017-12-03 12:08:22,715 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCreate and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,716 [TRACE] [doorpi.action.handler] register Event OnSipPhoneStart from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,716 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneStart and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,717 [TRACE] [doorpi.action.handler] register Event OnSipPhoneDestroy from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,717 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneDestroy and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,718 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderCreate from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,718 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderCreate and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,718 [TRACE] [doorpi.action.handler] register Event OnSipPhoneRecorderDestroy from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,719 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneRecorderDestroy and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,719 [TRACE] [doorpi.action.handler] register Event BeforeSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,720 [TRACE] [doorpi.action.handler] added event_name BeforeSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,720 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,721 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,721 [TRACE] [doorpi.action.handler] register Event OnSipPhoneMakeCallFailed from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,721 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneMakeCallFailed and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,722 [TRACE] [doorpi.action.handler] register Event AfterSipPhoneMakeCall from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,722 [TRACE] [doorpi.action.handler] added event_name AfterSipPhoneMakeCall and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,723 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutNoResponse from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,723 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutNoResponse and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,724 [TRACE] [doorpi.action.handler] register Event OnSipPhoneCallTimeoutMaxCalltime from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,724 [TRACE] [doorpi.action.handler] added event_name OnSipPhoneCallTimeoutMaxCalltime and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,724 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.from_linphone 2017-12-03 12:08:22,725 [TRACE] [doorpi.action.handler] added event_name OnPlayerCreated and registered source doorpi.sipphone.from_linphone 2017-12-03 12:08:22,725 [DEBUG] [doorpi.sipphone.linphone_lib.CallBacks] __init__ 2017-12-03 12:08:22,726 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneDestroy 2017-12-03 12:08:22,726 [TRACE] [doorpi.action.handler] register Event OnCallMediaStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,727 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.CallBacks was added 2017-12-03 12:08:22,727 [TRACE] [doorpi.action.handler] added event_name OnCallMediaStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,727 [TRACE] [doorpi.action.handler] register Event OnMediaRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,728 [TRACE] [doorpi.action.handler] added event_name OnMediaRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,728 [TRACE] [doorpi.action.handler] register Event OnMediaNotRequired from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,729 [TRACE] [doorpi.action.handler] added event_name OnMediaNotRequired and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,729 [TRACE] [doorpi.action.handler] register Event OnCallStateChange from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,729 [TRACE] [doorpi.action.handler] added event_name OnCallStateChange and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,730 [TRACE] [doorpi.action.handler] register Event OnCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,730 [TRACE] [doorpi.action.handler] added event_name OnCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,731 [TRACE] [doorpi.action.handler] register Event AfterCallStateConnect from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,731 [TRACE] [doorpi.action.handler] added event_name AfterCallStateConnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,732 [TRACE] [doorpi.action.handler] register Event OnCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,732 [TRACE] [doorpi.action.handler] added event_name OnCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,733 [TRACE] [doorpi.action.handler] register Event AfterCallStateDisconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,733 [TRACE] [doorpi.action.handler] added event_name AfterCallStateDisconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,733 [TRACE] [doorpi.action.handler] register Event OnCallStateDismissed from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,734 [TRACE] [doorpi.action.handler] added event_name OnCallStateDismissed and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,734 [TRACE] [doorpi.action.handler] register Event OnCallStateReject from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,735 [TRACE] [doorpi.action.handler] added event_name OnCallStateReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,735 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,736 [TRACE] [doorpi.action.handler] added event_name OnCallStart and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,736 [TRACE] [doorpi.action.handler] register Event OnDTMF from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,736 [TRACE] [doorpi.action.handler] added event_name OnDTMF and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,737 [WARNING] [root] section DTMF not found in configfile 2017-12-03 12:08:22,737 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2017-12-03 12:08:22,738 [TRACE] [doorpi.action.handler] register Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,738 [TRACE] [doorpi.action.handler] nothing to do - event OnCallStart from source doorpi.sipphone.linphone_lib.CallBacks is already known 2017-12-03 12:08:22,739 [TRACE] [doorpi.action.handler] register Event BeforeCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,739 [TRACE] [doorpi.action.handler] added event_name BeforeCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,740 [TRACE] [doorpi.action.handler] register Event OnCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,740 [TRACE] [doorpi.action.handler] added event_name OnCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,740 [TRACE] [doorpi.action.handler] register Event AfterCallReconnect from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,741 [TRACE] [doorpi.action.handler] added event_name AfterCallReconnect and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,741 [TRACE] [doorpi.action.handler] register Event OnCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,742 [TRACE] [doorpi.action.handler] added event_name OnCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,742 [TRACE] [doorpi.action.handler] register Event AfterCallBusy from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,743 [TRACE] [doorpi.action.handler] added event_name AfterCallBusy and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,743 [TRACE] [doorpi.action.handler] register Event OnCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,744 [TRACE] [doorpi.action.handler] added event_name OnCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,744 [TRACE] [doorpi.action.handler] register Event AfterCallIncoming from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,744 [TRACE] [doorpi.action.handler] added event_name AfterCallIncoming and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,745 [TRACE] [doorpi.action.handler] register Event OnCallReject from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,745 [TRACE] [doorpi.action.handler] added event_name OnCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,746 [TRACE] [doorpi.action.handler] register Event AfterCallReject from doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,746 [TRACE] [doorpi.action.handler] added event_name AfterCallReject and registered source doorpi.sipphone.linphone_lib.CallBacks 2017-12-03 12:08:22,747 [TRACE] [doorpi.action.handler] fire Event OnCallStart from doorpi.sipphone.linphone_lib.CallBacks asyncron 2017-12-03 12:08:22,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_new() 2017-12-03 12:08:22,749 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_new -> 0x75b20c98 2017-12-03 12:08:22,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_init() 2017-12-03 12:08:22,750 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_init -> 0x75b20c98 2017-12-03 12:08:22,751 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_class_method_new(0x75b0c6f0, "(null)", "(null)") 2017-12-03 12:08:22,751 [INFO] [doorpi.sipphone.from_linphone] Mediastreamer2 factory 2.12.0 (git: 3.9.0) initialized. 2017-12-03 12:08:22,752 [INFO] [doorpi.sipphone.from_linphone] CPU count set to 4 2017-12-03 12:08:22,753 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init() done: platform_tags=linux,arm,embedded 2017-12-03 12:08:22,753 [INFO] [doorpi.sipphone.from_linphone] srtp init 2017-12-03 12:08:22,767 [INFO] [doorpi.sipphone.from_linphone] Registering all soundcard handlers 2017-12-03 12:08:22,886 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: default device' added 2017-12-03 12:08:22,903 [INFO] [doorpi.sipphone.from_linphone] also error in pcm_hw.c:1557 - open '/dev/snd/pcmC0D0c' failed (-2) 2017-12-03 12:08:22,906 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: bcm2835 ALSA' added 2017-12-03 12:08:22,923 [INFO] [doorpi.sipphone.from_linphone] Card 'ALSA: C-Media USB Headphone Set' added 2017-12-03 12:08:22,924 [INFO] [doorpi.sipphone.from_linphone] Registering all webcam handlers 2017-12-03 12:08:22,925 [INFO] [doorpi.sipphone.from_linphone] Webcam V4L2: /dev/video0 added 2017-12-03 12:08:22,925 [INFO] [doorpi.sipphone.from_linphone] Webcam StaticImage: Static picture added 2017-12-03 12:08:22,926 [INFO] [doorpi.sipphone.from_linphone] ms_factory_init_voip() done 2017-12-03 12:08:22,926 [INFO] [doorpi.sipphone.from_linphone] Loading ms plugins from [./lib/mediastreamer/plugins] 2017-12-03 12:08:22,927 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/mediastreamer/plugins: No such file or directory 2017-12-03 12:08:22,928 [INFO] [doorpi.sipphone.from_linphone] Initializing LinphoneCore 3.9.0 2017-12-03 12:08:22,928 [INFO] [doorpi.sipphone.from_linphone] Vtable [0x11b5180] registered on core [0x11b8718] 2017-12-03 12:08:22,929 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x11b5180, 1, "Starting up") 2017-12-03 12:08:22,929 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2017-12-03 12:08:22,930 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x11b5180] notifying [global_state_changed] 2017-12-03 12:08:22,930 [INFO] [doorpi.sipphone.from_linphone] oRTP-0.25.0 initialized. 2017-12-03 12:08:22,931 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_base_init, because [2] ref 2017-12-03 12:08:22,931 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_voip_init, because [2] ref 2017-12-03 12:08:22,932 [INFO] [doorpi.sipphone.from_linphone] Skiping ms_plugins_init, because [2] ref 2017-12-03 12:08:22,932 [INFO] [doorpi.sipphone.from_linphone] Codec opus/48000 fmtp=[useinbandfec=1] number=-1, enabled=1) added to default capabilities. 2017-12-03 12:08:22,933 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-12-03 12:08:22,933 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-12-03 12:08:22,934 [INFO] [doorpi.sipphone.from_linphone] Codec speex/16000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2017-12-03 12:08:22,934 [INFO] [doorpi.sipphone.from_linphone] Codec speex/8000 fmtp=[vbr=on] number=-1, enabled=1) added to default capabilities. 2017-12-03 12:08:22,935 [INFO] [doorpi.sipphone.from_linphone] Codec PCMU/8000 fmtp=[] number=0, enabled=1) added to default capabilities. 2017-12-03 12:08:22,935 [INFO] [doorpi.sipphone.from_linphone] Codec PCMA/8000 fmtp=[] number=8, enabled=1) added to default capabilities. 2017-12-03 12:08:22,936 [INFO] [doorpi.sipphone.from_linphone] Codec t140/1000 fmtp=[] number=96, enabled=1) added to default capabilities. 2017-12-03 12:08:22,936 [INFO] [doorpi.sipphone.from_linphone] Codec red/1000 fmtp=[] number=97, enabled=1) added to default capabilities. 2017-12-03 12:08:22,937 [INFO] [doorpi.sipphone.from_linphone] Codec GSM/8000 fmtp=[] number=3, enabled=0) added to default capabilities. 2017-12-03 12:08:22,937 [INFO] [doorpi.sipphone.from_linphone] Codec G722/8000 fmtp=[] number=9, enabled=0) added to default capabilities. 2017-12-03 12:08:22,938 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iLBC 2017-12-03 12:08:22,938 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iLBC 2017-12-03 12:08:22,939 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR 2017-12-03 12:08:22,939 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR 2017-12-03 12:08:22,940 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AMR-WB 2017-12-03 12:08:22,940 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AMR-WB 2017-12-03 12:08:22,941 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2017-12-03 12:08:22,941 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2017-12-03 12:08:22,942 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-12-03 12:08:22,942 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-12-03 12:08:22,943 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-12-03 12:08:22,943 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-12-03 12:08:22,944 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-12-03 12:08:22,944 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-12-03 12:08:22,945 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-12-03 12:08:22,945 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-12-03 12:08:22,946 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for mpeg4-generic 2017-12-03 12:08:22,946 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for mpeg4-generic 2017-12-03 12:08:22,947 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for iSAC 2017-12-03 12:08:22,947 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for iSAC 2017-12-03 12:08:22,948 [INFO] [doorpi.sipphone.from_linphone] Codec speex/32000 fmtp=[vbr=on] number=-1, enabled=0) added to default capabilities. 2017-12-03 12:08:22,948 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-12-03 12:08:22,949 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-12-03 12:08:22,949 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-12-03 12:08:22,949 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-12-03 12:08:22,950 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for SILK 2017-12-03 12:08:22,950 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for SILK 2017-12-03 12:08:22,951 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-16 2017-12-03 12:08:22,951 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-16 2017-12-03 12:08:22,952 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-24 2017-12-03 12:08:22,952 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-24 2017-12-03 12:08:22,953 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-32 2017-12-03 12:08:22,953 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-32 2017-12-03 12:08:22,954 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G726-40 2017-12-03 12:08:22,954 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G726-40 2017-12-03 12:08:22,955 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-16 2017-12-03 12:08:22,955 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-16 2017-12-03 12:08:22,956 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-24 2017-12-03 12:08:22,956 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-24 2017-12-03 12:08:22,957 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-32 2017-12-03 12:08:22,957 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-32 2017-12-03 12:08:22,958 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for AAL2-G726-40 2017-12-03 12:08:22,958 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for AAL2-G726-40 2017-12-03 12:08:22,959 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CODEC2 2017-12-03 12:08:22,959 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CODEC2 2017-12-03 12:08:22,960 [INFO] [doorpi.sipphone.from_linphone] Codec VP8/90000 fmtp=[] number=-1, enabled=1) added to default capabilities. 2017-12-03 12:08:22,960 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H264 2017-12-03 12:08:22,961 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H264 2017-12-03 12:08:22,961 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MP4V-ES 2017-12-03 12:08:22,962 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263-1998 2017-12-03 12:08:22,962 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2017-12-03 12:08:22,963 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for 1016 2017-12-03 12:08:22,963 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for 1016 2017-12-03 12:08:22,964 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G723 2017-12-03 12:08:22,964 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G723 2017-12-03 12:08:22,965 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for LPC 2017-12-03 12:08:22,965 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for LPC 2017-12-03 12:08:22,966 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=10, enabled=0) added to default capabilities. 2017-12-03 12:08:22,966 [INFO] [doorpi.sipphone.from_linphone] Codec L16/44100 fmtp=[] number=11, enabled=0) added to default capabilities. 2017-12-03 12:08:22,967 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for CN 2017-12-03 12:08:22,967 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for CN 2017-12-03 12:08:22,968 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for G729 2017-12-03 12:08:22,968 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for G729 2017-12-03 12:08:22,969 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H261 2017-12-03 12:08:22,969 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for H261 2017-12-03 12:08:22,970 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for MPV 2017-12-03 12:08:22,970 [INFO] [doorpi.sipphone.from_linphone] Could not find decoder for MPV 2017-12-03 12:08:22,971 [INFO] [doorpi.sipphone.from_linphone] Could not find encoder for H263 2017-12-03 12:08:22,971 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-12-03 12:08:22,972 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:22,972 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:22,973 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:22,973 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_configuring_status(0x11b5180, 2, "(null)") 2017-12-03 12:08:22,974 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_configuring_status 2017-12-03 12:08:22,974 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x11b5180] notifying [configuring_status] 2017-12-03 12:08:22,975 [INFO] [doorpi.sipphone.from_linphone] Cannot open directory ./lib/liblinphone/plugins: No such file or directory 2017-12-03 12:08:22,975 [WARNING] [doorpi.sipphone.from_linphone] ./share/sounds/linphone/rings/oldphone.wav does not exist 2017-12-03 12:08:22,976 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_playback_gain_db(): no active call. 2017-12-03 12:08:22,976 [INFO] [doorpi.sipphone.from_linphone] linphone_core_set_mic_gain_db(): no active call. 2017-12-03 12:08:22,977 [INFO] [doorpi.sipphone.from_linphone] MTU is supposed to be 1300, rtp payload max size will be 1240 2017-12-03 12:08:22,977 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-12-03 12:08:22,978 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-12-03 12:08:22,978 [INFO] [doorpi.sipphone.from_linphone] Supported codec opus/48000 fmtp=useinbandfec=1 automatically added to codec list. 2017-12-03 12:08:22,979 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/16000 fmtp=vbr=on automatically added to codec list. 2017-12-03 12:08:22,979 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/8000 fmtp=vbr=on automatically added to codec list. 2017-12-03 12:08:22,980 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMU/8000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,980 [INFO] [doorpi.sipphone.from_linphone] Supported codec PCMA/8000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,980 [INFO] [doorpi.sipphone.from_linphone] Supported codec GSM/8000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,981 [INFO] [doorpi.sipphone.from_linphone] Supported codec G722/8000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,981 [INFO] [doorpi.sipphone.from_linphone] Supported codec speex/32000 fmtp=vbr=on automatically added to codec list. 2017-12-03 12:08:22,982 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2017-12-03 12:08:22,982 [INFO] [doorpi.sipphone.from_linphone] Supported codec L16/44100 fmtp= automatically added to codec list. 2017-12-03 12:08:22,983 [INFO] [doorpi.sipphone.from_linphone] Supported codec VP8/90000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,983 [INFO] [doorpi.sipphone.from_linphone] Supported codec t140/1000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,984 [INFO] [doorpi.sipphone.from_linphone] Supported codec red/1000 fmtp= automatically added to codec list. 2017-12-03 12:08:22,984 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-12-03 12:08:22,985 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:22,985 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2017-12-03 12:08:22,986 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11b6598] on [sip:0.0.0.0:5060;transport=UDP] 2017-12-03 12:08:22,986 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11b67e0] on [sip:0.0.0.0:5060;transport=TCP] 2017-12-03 12:08:22,987 [ERROR] [doorpi.sipphone.from_linphone] TCP bind() failed for 0.0.0.0 port 5060: Address already in use 2017-12-03 12:08:22,988 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x11b67e0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2017-12-03 12:08:22,988 [WARNING] [doorpi.sipphone.from_linphone] Could not start tcp transport on port 5060, maybe this port is already used. 2017-12-03 12:08:22,989 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11b5860] on [sip:0.0.0.0:-1;transport=TLS] 2017-12-03 12:08:22,990 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 32775 2017-12-03 12:08:22,990 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:22,991 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:22,994 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_callback_global_state_changed(0x11b5180, 2, "Ready") 2017-12-03 12:08:22,995 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_callback_global_state_changed 2017-12-03 12:08:22,996 [INFO] [doorpi.sipphone.from_linphone] Linphone core [0x11b5180] notifying [global_state_changed] 2017-12-03 12:08:22,996 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_class_method_new -> 0x75b20c98 2017-12-03 12:08:22,997 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2017-12-03 12:08:22,997 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_primary_contact(0x75b20c98 [0x11b5180], "DoorPi ") 2017-12-03 12:08:22,999 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_primary_contact -> 0 2017-12-03 12:08:22,999 [TRACE] [doorpi.action.handler] fire Event OnSipPhoneCreate from doorpi.sipphone.from_linphone asyncron 2017-12-03 12:08:23,001 [TRACE] [doorpi.conf.config_object] get_integer for key ua.max_calls in section SIP-Phone (default: 2) returns 2 2017-12-03 12:08:23,002 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_max_calls(0x75b20c98 [0x11b5180], 2) 2017-12-03 12:08:23,003 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_max_calls -> 0 2017-12-03 12:08:23,003 [TRACE] [doorpi.conf.config_object] get_boolean for key echo_cancellation_enabled in section SIP-Phone (default: False) returns False 2017-12-03 12:08:23,004 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_echo_cancellation(0x75b20c98 [0x11b5180], 0x2e0614 [0]) 2017-12-03 12:08:23,005 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_echo_cancellation -> 0 2017-12-03 12:08:23,005 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2017-12-03 12:08:23,006 [TRACE] [doorpi.conf.config_object] get_integer for key local_port in section SIP-Phone (default: 5060) returns 5060 2017-12-03 12:08:23,006 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_new() 2017-12-03 12:08:23,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_new -> 0x75b20cb0 2017-12-03 12:08:23,007 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_sip_transports(0x75b20c98 [0x11b5180], 0x75b20cb0 [0x75b20cb8]) 2017-12-03 12:08:23,008 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x11b6598] on [sip:0.0.0.0:5060;transport=UDP] destroyed 2017-12-03 12:08:23,008 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x11b5860] on [sip:0.0.0.0:32775;transport=TLS] destroyed 2017-12-03 12:08:23,009 [INFO] [doorpi.sipphone.from_linphone] sal_unlisten_ports done 2017-12-03 12:08:23,009 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11d22e0] on [sip:0.0.0.0:5060;transport=UDP] 2017-12-03 12:08:23,010 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11b67f0] on [sip:0.0.0.0:5060;transport=TCP] 2017-12-03 12:08:23,011 [ERROR] [doorpi.sipphone.from_linphone] TCP bind() failed for 0.0.0.0 port 5060: Address already in use 2017-12-03 12:08:23,011 [INFO] [doorpi.sipphone.from_linphone] Listening point [0x11b67f0] on [sip:0.0.0.0:5060;transport=TCP] destroyed 2017-12-03 12:08:23,012 [WARNING] [doorpi.sipphone.from_linphone] Could not start tcp transport on port 5060, maybe this port is already used. 2017-12-03 12:08:23,013 [INFO] [doorpi.sipphone.from_linphone] Creating listening point [0x11d2360] on [sip:0.0.0.0:-1;transport=TLS] 2017-12-03 12:08:23,013 [INFO] [doorpi.sipphone.from_linphone] Random TCP port is 39733 2017-12-03 12:08:23,014 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:23,014 [INFO] [doorpi.sipphone.from_linphone] Root ca path set to /etc/ssl/certs 2017-12-03 12:08:23,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_sip_transports -> 0 2017-12-03 12:08:23,015 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_SipTransports_dealloc(0x75b20cb0) 2017-12-03 12:08:23,016 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_SipTransports_dealloc 2017-12-03 12:08:23,016 [TRACE] [doorpi.conf.config_object] get_boolean for key video_display_enabled in section SIP-Phone (default: False) returns False 2017-12-03 12:08:23,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_display(0x75b20c98 [0x11b5180], 0x2e0614 [0]) 2017-12-03 12:08:23,017 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_display -> 0 2017-12-03 12:08:23,018 [TRACE] [doorpi.conf.config_object] get_string for key stun_server in section SIP-Phone (default: ) returns 2017-12-03 12:08:23,018 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_stun_server(0x75b20c98 [0x11b5180], "") 2017-12-03 12:08:23,019 [INFO] [doorpi.sipphone.from_linphone] Resolver is using DNS server(s): 2017-12-03 12:08:23,020 [INFO] [doorpi.sipphone.from_linphone] 192.168.1.1 2017-12-03 12:08:23,020 [INFO] [doorpi.sipphone.from_linphone] fdf5:a7ae:99dd::1 2017-12-03 12:08:23,022 [INFO] [doorpi.sipphone.from_linphone] resolver_process_data dns_res_check() in progress 2017-12-03 12:08:23,022 [INFO] [doorpi.sipphone.from_linphone] DNS resolution awaiting response, queued to main loop 2017-12-03 12:08:23,023 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_stun_server -> 0 2017-12-03 12:08:23,023 [DEBUG] [doorpi.conf.config_object] create new key FirewallPolicy in section SIP-Phone with value 'PolicyNoFirewall' 2017-12-03 12:08:23,024 [TRACE] [doorpi.conf.config_object] get_string for key FirewallPolicy in section SIP-Phone (default: PolicyNoFirewall) returns PolicyNoFirewall 2017-12-03 12:08:23,024 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_firewall_policy(0x75b20c98 [0x11b5180], 0) 2017-12-03 12:08:23,025 [INFO] [doorpi.sipphone.from_linphone] Sal nat helper [enabled] 2017-12-03 12:08:23,025 [INFO] [doorpi.sipphone.from_linphone] Sal use rport [enabled] 2017-12-03 12:08:23,027 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_firewall_policy -> 0 2017-12-03 12:08:23,027 [TRACE] [doorpi.conf.config_object] get_integer for key max_call_time in section SIP-Phone (default: 120) returns 120 2017-12-03 12:08:23,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_in_call_timeout(0x75b20c98 [0x11b5180], 120) 2017-12-03 12:08:23,028 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_in_call_timeout -> 0 2017-12-03 12:08:23,029 [TRACE] [doorpi.conf.config_object] get_integer for key call_timeout in section SIP-Phone (default: 15) returns 40 2017-12-03 12:08:23,029 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_inc_timeout(0x75b20c98 [0x11b5180], 40) 2017-12-03 12:08:23,030 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_inc_timeout -> 0 2017-12-03 12:08:23,030 [TRACE] [doorpi.conf.config_object] get_string for key dialtone in section SIP-Phone (default: !BASEPATH!/media/ShortDialTone.wav) returns !BASEPATH!/media/ShortDialTone.wav 2017-12-03 12:08:23,032 [DEBUG] [doorpi.conf.config_object] parse string "!BASEPATH!/media/ShortDialTone.wav" to "/usr/local/etc/DoorPi/media/ShortDialTone.wav" 2017-12-03 12:08:23,033 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2017-12-03 12:08:23,035 [TRACE] [doorpi.conf.config_object] get_boolean for key dialtone_renew_every_start in section SIP-Phone (default: False) returns False 2017-12-03 12:08:23,035 [TRACE] [doorpi.action.handler] register Event OnPlayerStarted from doorpi.sipphone.linphone_lib.Player 2017-12-03 12:08:23,036 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Player was added 2017-12-03 12:08:23,036 [TRACE] [doorpi.action.handler] added event_name OnPlayerStarted and registered source doorpi.sipphone.linphone_lib.Player 2017-12-03 12:08:23,037 [TRACE] [doorpi.action.handler] register Event OnPlayerStopped from doorpi.sipphone.linphone_lib.Player 2017-12-03 12:08:23,037 [TRACE] [doorpi.action.handler] added event_name OnPlayerStopped and registered source doorpi.sipphone.linphone_lib.Player 2017-12-03 12:08:23,038 [TRACE] [doorpi.action.handler] register Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player 2017-12-03 12:08:23,038 [TRACE] [doorpi.action.handler] added event_source doorpi.sipphone.linphone_lib.Player to existing event OnPlayerCreated 2017-12-03 12:08:23,039 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnSipPhoneMakeCall 2017-12-03 12:08:23,039 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateConnect 2017-12-03 12:08:23,040 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to new evententry OnCallStateDisconnect 2017-12-03 12:08:23,040 [TRACE] [doorpi.action.handler] fire Event OnPlayerCreated from doorpi.sipphone.linphone_lib.Player asyncron 2017-12-03 12:08:23,042 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_ringback(0x75b20c98 [0x11b5180], "/usr/local/etc/DoorPi/media/ShortDialTone.wav") 2017-12-03 12:08:23,043 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_ringback -> 0 2017-12-03 12:08:23,043 [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 2017-12-03 12:08:23,044 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnSipPhoneDestroy 2017-12-03 12:08:23,045 [TRACE] [doorpi.action.handler] register Event OnRecorderStarted from doorpi.sipphone.linphone_lib.Recorder 2017-12-03 12:08:23,045 [DEBUG] [doorpi.action.handler] event_source doorpi.sipphone.linphone_lib.Recorder was added 2017-12-03 12:08:23,045 [TRACE] [doorpi.action.handler] added event_name OnRecorderStarted and registered source doorpi.sipphone.linphone_lib.Recorder 2017-12-03 12:08:23,046 [TRACE] [doorpi.action.handler] register Event OnRecorderStopped from doorpi.sipphone.linphone_lib.Recorder 2017-12-03 12:08:23,046 [TRACE] [doorpi.action.handler] added event_name OnRecorderStopped and registered source doorpi.sipphone.linphone_lib.Recorder 2017-12-03 12:08:23,047 [TRACE] [doorpi.action.handler] register Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder 2017-12-03 12:08:23,047 [TRACE] [doorpi.action.handler] added event_name OnRecorderCreated and registered source doorpi.sipphone.linphone_lib.Recorder 2017-12-03 12:08:23,048 [TRACE] [doorpi.conf.config_object] get_boolean for key record_while_dialing in section SIP-Phone (default: False) returns False 2017-12-03 12:08:23,048 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateConnect 2017-12-03 12:08:23,049 [TRACE] [doorpi.action.handler] action > with args () and kwargs {} was added to event OnCallStateDisconnect 2017-12-03 12:08:23,049 [TRACE] [doorpi.action.handler] fire Event OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder asyncron 2017-12-03 12:08:23,051 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x745c4fd0 2017-12-03 12:08:23,052 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,053 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x745c3650 2017-12-03 12:08:23,053 [TRACE] [doorpi.conf.config_object] get_string for key capture_device in section SIP-Phone (default: ALSA: default device) returns ALSA: C-Media USB Headphone Set 2017-12-03 12:08:23,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_capture_device(0x75b20c98 [0x11b5180], "ALSA: C-Media USB Headphone Set") 2017-12-03 12:08:23,054 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_capture_device -> 0 2017-12-03 12:08:23,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,055 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x745c3740 2017-12-03 12:08:23,056 [TRACE] [doorpi.conf.config_object] get_string for key playback_device in section SIP-Phone (default: ALSA: default device) returns ALSA: C-Media USB Headphone Set 2017-12-03 12:08:23,056 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_playback_device(0x75b20c98 [0x11b5180], "ALSA: C-Media USB Headphone Set") 2017-12-03 12:08:23,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_playback_device -> 0 2017-12-03 12:08:23,057 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,058 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x745c4fd0 2017-12-03 12:08:23,058 [INFO] [doorpi.sipphone.from_linphone] found 3 possible sounddevices: 2017-12-03 12:08:23,059 [DEBUG] [doorpi.sipphone.from_linphone] |rec|play| name 2017-12-03 12:08:23,060 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-12-03 12:08:23,060 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_sound_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_sound_devices -> 0x745c4fd0 2017-12-03 12:08:23,061 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75b20c98 [0x11b5180], "ALSA: default device") 2017-12-03 12:08:23,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-12-03 12:08:23,062 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75b20c98 [0x11b5180], "ALSA: default device") 2017-12-03 12:08:23,063 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-12-03 12:08:23,063 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: default device 2017-12-03 12:08:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75b20c98 [0x11b5180], "ALSA: bcm2835 ALSA") 2017-12-03 12:08:23,064 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0614 2017-12-03 12:08:23,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75b20c98 [0x11b5180], "ALSA: bcm2835 ALSA") 2017-12-03 12:08:23,065 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-12-03 12:08:23,066 [DEBUG] [doorpi.sipphone.from_linphone] | O | X | ALSA: bcm2835 ALSA 2017-12-03 12:08:23,066 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_capture(0x75b20c98 [0x11b5180], "ALSA: C-Media USB Headphone Set") 2017-12-03 12:08:23,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_capture -> 0x2e0620 2017-12-03 12:08:23,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_sound_device_can_playback(0x75b20c98 [0x11b5180], "ALSA: C-Media USB Headphone Set") 2017-12-03 12:08:23,067 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_sound_device_can_playback -> 0x2e0620 2017-12-03 12:08:23,068 [DEBUG] [doorpi.sipphone.from_linphone] | X | X | ALSA: C-Media USB Headphone Set 2017-12-03 12:08:23,068 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-12-03 12:08:23,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_capture_device(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,069 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_capture_device -> 0x75b0bc98 2017-12-03 12:08:23,070 [DEBUG] [doorpi.sipphone.from_linphone] using capture_device: ALSA: C-Media USB Headphone Set 2017-12-03 12:08:23,070 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_playback_device(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,071 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_playback_device -> 0x75b0bc98 2017-12-03 12:08:23,071 [DEBUG] [doorpi.sipphone.from_linphone] using playback_device: ALSA: C-Media USB Headphone Set 2017-12-03 12:08:23,072 [TRACE] [doorpi.conf.config_object] get_list for key audio_codecs in section SIP-Phone (default: PCMA,PCMU) returns ['PCMA', 'PCMU', 'G.711a', ' G.711u'] 2017-12-03 12:08:23,072 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_audio_codecs(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5df0) 2017-12-03 12:08:23,073 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea4c0 2017-12-03 12:08:23,074 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea4c0 2017-12-03 12:08:23,075 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea4c0 2017-12-03 12:08:23,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5e60) 2017-12-03 12:08:23,076 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea4d0 2017-12-03 12:08:23,077 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea4d0 2017-12-03 12:08:23,078 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea4d0 2017-12-03 12:08:23,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5ec8) 2017-12-03 12:08:23,079 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea4e0 2017-12-03 12:08:23,080 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea4e0 2017-12-03 12:08:23,081 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea4e0 2017-12-03 12:08:23,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5f30) 2017-12-03 12:08:23,082 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea4f0 2017-12-03 12:08:23,083 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea4f0 2017-12-03 12:08:23,084 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea4f0 2017-12-03 12:08:23,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5f88) 2017-12-03 12:08:23,085 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea500 2017-12-03 12:08:23,086 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea500 2017-12-03 12:08:23,087 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea500 2017-12-03 12:08:23,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5fe0) 2017-12-03 12:08:23,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,088 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea510 2017-12-03 12:08:23,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,089 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea510 2017-12-03 12:08:23,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea510 2017-12-03 12:08:23,090 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b55c8) 2017-12-03 12:08:23,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,091 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea520 2017-12-03 12:08:23,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,092 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea520 2017-12-03 12:08:23,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea520 2017-12-03 12:08:23,093 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5620) 2017-12-03 12:08:23,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,094 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea530 2017-12-03 12:08:23,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea530 2017-12-03 12:08:23,095 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea530 2017-12-03 12:08:23,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5688) 2017-12-03 12:08:23,096 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea540 2017-12-03 12:08:23,097 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea540 2017-12-03 12:08:23,098 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea540 2017-12-03 12:08:23,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b56e0) 2017-12-03 12:08:23,099 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea550 2017-12-03 12:08:23,100 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea550 2017-12-03 12:08:23,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea550 2017-12-03 12:08:23,101 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_audio_codecs -> 0x745cf080 2017-12-03 12:08:23,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4c0 [0x11b5df0]) 2017-12-03 12:08:23,102 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745c1fc0 2017-12-03 12:08:23,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4c0 [0x11b5df0]) 2017-12-03 12:08:23,103 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,104 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec opus 2017-12-03 12:08:23,104 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea4c0 [0x11b5df0], 0x2e0614 [0]) 2017-12-03 12:08:23,105 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4d0 [0x11b5e60]) 2017-12-03 12:08:23,106 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d10a0 2017-12-03 12:08:23,107 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4d0 [0x11b5e60]) 2017-12-03 12:08:23,107 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,108 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-12-03 12:08:23,108 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea4d0 [0x11b5e60], 0x2e0614 [0]) 2017-12-03 12:08:23,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,109 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4e0 [0x11b5ec8]) 2017-12-03 12:08:23,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d10a0 2017-12-03 12:08:23,110 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4e0 [0x11b5ec8]) 2017-12-03 12:08:23,111 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,111 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-12-03 12:08:23,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea4e0 [0x11b5ec8], 0x2e0614 [0]) 2017-12-03 12:08:23,112 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4f0 [0x11b5f30]) 2017-12-03 12:08:23,113 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d10a0 2017-12-03 12:08:23,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea4f0 [0x11b5f30]) 2017-12-03 12:08:23,114 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,115 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMU 2017-12-03 12:08:23,115 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea4f0 [0x11b5f30], 0x2e0620 [1]) 2017-12-03 12:08:23,116 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea500 [0x11b5f88]) 2017-12-03 12:08:23,117 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d10a0 2017-12-03 12:08:23,118 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea500 [0x11b5f88]) 2017-12-03 12:08:23,118 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,118 [DEBUG] [doorpi.sipphone.from_linphone] enable audio codec PCMA 2017-12-03 12:08:23,119 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea500 [0x11b5f88], 0x2e0620 [1]) 2017-12-03 12:08:23,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,120 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea510 [0x11b5fe0]) 2017-12-03 12:08:23,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,121 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea510 [0x11b5fe0]) 2017-12-03 12:08:23,122 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,122 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec GSM 2017-12-03 12:08:23,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea510 [0x11b5fe0], 0x2e0614 [0]) 2017-12-03 12:08:23,123 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea520 [0x11b55c8]) 2017-12-03 12:08:23,124 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d10a0 2017-12-03 12:08:23,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea520 [0x11b55c8]) 2017-12-03 12:08:23,125 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,125 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec G722 2017-12-03 12:08:23,126 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea520 [0x11b55c8], 0x2e0614 [0]) 2017-12-03 12:08:23,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,127 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea530 [0x11b5620]) 2017-12-03 12:08:23,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d10a0 2017-12-03 12:08:23,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea530 [0x11b5620]) 2017-12-03 12:08:23,128 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x745d1140 2017-12-03 12:08:23,129 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec speex 2017-12-03 12:08:23,129 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea530 [0x11b5620], 0x2e0614 [0]) 2017-12-03 12:08:23,130 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea540 [0x11b5688]) 2017-12-03 12:08:23,131 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea540 [0x11b5688]) 2017-12-03 12:08:23,132 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,133 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2017-12-03 12:08:23,133 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea540 [0x11b5688], 0x2e0614 [0]) 2017-12-03 12:08:23,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,134 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea550 [0x11b56e0]) 2017-12-03 12:08:23,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,135 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea550 [0x11b56e0]) 2017-12-03 12:08:23,136 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,136 [DEBUG] [doorpi.sipphone.from_linphone] disable audio codec L16 2017-12-03 12:08:23,137 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea550 [0x11b56e0], 0x2e0614 [0]) 2017-12-03 12:08:23,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,138 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745cf148 2017-12-03 12:08:23,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,139 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745cf148 2017-12-03 12:08:23,140 [INFO] [doorpi.sipphone.from_linphone] found 2 possible videodevices: 2017-12-03 12:08:23,140 [DEBUG] [doorpi.sipphone.from_linphone] | name 2017-12-03 12:08:23,141 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-12-03 12:08:23,141 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,142 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745cf148 2017-12-03 12:08:23,142 [DEBUG] [doorpi.sipphone.from_linphone] | V4L2: /dev/video0 2017-12-03 12:08:23,143 [DEBUG] [doorpi.sipphone.from_linphone] | StaticImage: Static picture 2017-12-03 12:08:23,143 [DEBUG] [doorpi.sipphone.from_linphone] ------------------------------------ 2017-12-03 12:08:23,144 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,144 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745cf148 2017-12-03 12:08:23,144 [TRACE] [doorpi.conf.config_object] get_string for key video_device in section SIP-Phone (default: V4L2: /dev/video0) returns StaticImage: Static picture 2017-12-03 12:08:23,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_devices(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,145 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_devices -> 0x745cf148 2017-12-03 12:08:23,146 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_enable_video_capture(0x75b20c98 [0x11b5180], 0x2e0620 [1]) 2017-12-03 12:08:23,146 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_enable_video_capture -> 0 2017-12-03 12:08:23,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_video_device(0x75b20c98 [0x11b5180], "StaticImage: Static picture") 2017-12-03 12:08:23,147 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_video_device -> 0 2017-12-03 12:08:23,148 [TRACE] [doorpi.conf.config_object] get_string for key video_size in section SIP-Phone (default: vga) returns vga 2017-12-03 12:08:23,148 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_preferred_video_size_by_name(0x75b20c98 [0x11b5180], "vga") 2017-12-03 12:08:23,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_preferred_video_size_by_name -> 0 2017-12-03 12:08:23,149 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_device(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,150 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_device -> 0x745c38c0 2017-12-03 12:08:23,150 [DEBUG] [doorpi.sipphone.from_linphone] using video_device: StaticImage: Static picture 2017-12-03 12:08:23,151 [TRACE] [doorpi.conf.config_object] get_list for key video_codecs in section SIP-Phone (default: VP8) returns ['VP8'] 2017-12-03 12:08:23,151 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_get_video_codecs(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,152 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_from_native_ptr(0x11b5d50) 2017-12-03 12:08:23,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_new() 2017-12-03 12:08:23,153 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_new -> 0x76cea560 2017-12-03 12:08:23,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_init() 2017-12-03 12:08:23,154 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_init -> 0x76cea560 2017-12-03 12:08:23,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_from_native_ptr -> 0x76cea560 2017-12-03 12:08:23,155 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_get_video_codecs -> 0x745cf1e8 2017-12-03 12:08:23,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea560 [0x11b5d50]) 2017-12-03 12:08:23,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,156 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_video_capture_enabled(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_video_capture_enabled -> 0x2e0620 2017-12-03 12:08:23,157 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_PayloadType_get_mime_type(0x76cea560 [0x11b5d50]) 2017-12-03 12:08:23,158 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_PayloadType_get_mime_type -> 0x75b20cb0 2017-12-03 12:08:23,158 [DEBUG] [doorpi.sipphone.from_linphone] enable video codec VP8 2017-12-03 12:08:23,159 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_enable_payload_type(0x75b20c98 [0x11b5180], 0x76cea560 [0x11b5d50], 0x2e0620 [1]) 2017-12-03 12:08:23,160 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_enable_payload_type -> 0x101e8bc 2017-12-03 12:08:23,160 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_server in section SIP-Phone (default: ) returns 192.168.1.16 2017-12-03 12:08:23,161 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_username in section SIP-Phone (default: ) returns 1000 2017-12-03 12:08:23,161 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_password in section SIP-Phone (default: 1000) returns ******* 2017-12-03 12:08:23,162 [TRACE] [doorpi.conf.config_object] get_string for key sipserver_realm in section SIP-Phone (default: 192.168.1.16) returns asterisk 2017-12-03 12:08:23,162 [INFO] [doorpi.sipphone.from_linphone] using DoorPi with SIP-Server 2017-12-03 12:08:23,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_proxy_config(0x75b20c98 [0x11b5180]) 2017-12-03 12:08:23,163 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_from_native_ptr(0x11b7fe0) 2017-12-03 12:08:23,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_new() 2017-12-03 12:08:23,164 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_new -> 0x76cea570 2017-12-03 12:08:23,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_init() 2017-12-03 12:08:23,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_init -> 0x76cea570 2017-12-03 12:08:23,165 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_from_native_ptr -> 0x76cea570 2017-12-03 12:08:23,166 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_proxy_config -> 0x76cea570 2017-12-03 12:08:23,166 [TRACE] [doorpi.conf.config_object] get_string for key identity in section SIP-Phone (default: DoorPi) returns DoorPi 2017-12-03 12:08:23,167 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_class_method_new("DoorPi ") 2017-12-03 12:08:23,168 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_from_native_ptr(0x11d0fa0) 2017-12-03 12:08:23,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_new() 2017-12-03 12:08:23,169 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_new -> 0x76cea580 2017-12-03 12:08:23,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Address_init() 2017-12-03 12:08:23,170 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_init -> 0x76cea580 2017-12-03 12:08:23,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_from_native_ptr -> 0x76cea580 2017-12-03 12:08:23,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Address_class_method_new -> 0x76cea580 2017-12-03 12:08:23,171 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_identity_address(0x76cea570 [0x11b7fe0], 0x76cea580 [0x11d0fa0]) 2017-12-03 12:08:23,172 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_identity_address -> 0 2017-12-03 12:08:23,173 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_set_server_addr(0x76cea570 [0x11b7fe0], "sip:192.168.1.16") 2017-12-03 12:08:23,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_set_server_addr -> 0 2017-12-03 12:08:23,174 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_ProxyConfig_enable_register(0x76cea570 [0x11b7fe0], 0x2e0620 [1]) 2017-12-03 12:08:23,175 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_ProxyConfig_enable_register -> 0 2017-12-03 12:08:23,175 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_proxy_config(0x75b20c98 [0x11b5180], 0x76cea570 [0x11b7fe0]) 2017-12-03 12:08:23,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_proxy_config -> 0x101e8bc 2017-12-03 12:08:23,177 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_set_default_proxy_config(0x75b20c98 [0x11b5180], 0x76cea570 [0x11b7fe0]) 2017-12-03 12:08:23,178 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_set_default_proxy_config -> 0 2017-12-03 12:08:23,178 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_create_auth_info(0x75b20c98 [0x11b5180], "1000", "(null)", "passwort", "(null)", "(null)", "asterisk") 2017-12-03 12:08:23,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_from_native_ptr(0x11d7a40) 2017-12-03 12:08:23,179 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_new() 2017-12-03 12:08:23,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_new -> 0x76cea590 2017-12-03 12:08:23,180 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_AuthInfo_init() 2017-12-03 12:08:23,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_init -> 0x76cea590 2017-12-03 12:08:23,181 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_AuthInfo_from_native_ptr -> 0x76cea590 2017-12-03 12:08:23,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_create_auth_info -> 0x76cea590 2017-12-03 12:08:23,182 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] >>> pylinphone_Core_instance_method_add_auth_info(0x75b20c98 [0x11b5180], 0x76cea590 [0x11d7a40]) 2017-12-03 12:08:23,183 [DEBUG] [doorpi.sipphone.from_linphone] [PYLINPHONE] <<< pylinphone_Core_instance_method_add_auth_info -> None 2017-12-03 12:08:23,183 [DEBUG] [doorpi.sipphone.from_linphone] start successfully 2017-12-03 12:08:23,184 [TRACE] [doorpi.conf.config_object] get_sections returns ['EVENT_OnStartup'] 2017-12-03 12:08:23,184 [INFO] [doorpi.doorpi] found EVENT_ section 'EVENT_OnStartup' in configfile 2017-12-03 12:08:23,185 [TRACE] [doorpi.conf.config_object] get_keys for section EVENT_OnStartup returns ['10'] 2017-12-03 12:08:23,185 [INFO] [doorpi.doorpi] registering action '10' for event 'OnStartup' 2017-12-03 12:08:23,186 [TRACE] [doorpi.conf.config_object] get_string for key 10 in section EVENT_OnStartup (default: ) returns sleep:1 2017-12-03 12:08:23,187 [DEBUG] [doorpi.action.SingleActions.sleep] doorpi.action.SingleActions.sleep loaded 2017-12-03 12:08:23,188 [TRACE] [doorpi.action.handler] action SleepAction with args (1.0,) and kwargs {} was added to new evententry OnStartup 2017-12-03 12:08:23,188 [TRACE] [doorpi.conf.config_object] get_string for key 1 in section onboardpins_InputPins (default: ) returns sleep:0.1 2017-12-03 12:08:23,189 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.1 2017-12-03 12:08:23,189 [TRACE] [doorpi.conf.config_object] get_string for key 0 in section onboardpins_InputPins (default: ) returns sleep:0.1 2017-12-03 12:08:23,190 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.0 2017-12-03 12:08:23,191 [TRACE] [doorpi.conf.config_object] get_string for key 3 in section onboardpins_InputPins (default: ) returns sleep:0.1 2017-12-03 12:08:23,191 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.3 2017-12-03 12:08:23,192 [TRACE] [doorpi.conf.config_object] get_string for key 2 in section onboardpins_InputPins (default: ) returns sleep:0.1 2017-12-03 12:08:23,192 [TRACE] [doorpi.action.handler] action SleepAction with args (0.1,) and kwargs {} was added to new evententry OnKeyPressed_onboardpins.2 2017-12-03 12:08:23,193 [WARNING] [root] section DTMF not found in configfile 2017-12-03 12:08:23,193 [TRACE] [doorpi.conf.config_object] get_keys for section DTMF returns [] 2017-12-03 12:08:23,194 [TRACE] [doorpi.conf.config_object] get_string for key is_alive_led in section DoorPi (default: ) returns 2017-12-03 12:08:23,194 [TRACE] [doorpi.action.handler] fire Event BeforeStartup from doorpi.doorpi asyncron Exception in thread OnWebServerStart from doorpi.status.webserver: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Exception in thread OnPlayerCreated from doorpi.sipphone.linphone_lib.Player: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Exception in thread OnSipPhoneCreate from doorpi.sipphone.from_linphone: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Exception in thread OnCallStart from doorpi.sipphone.linphone_lib.CallBacks: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Exception in thread OnRecorderCreated from doorpi.sipphone.linphone_lib.Recorder: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked Exception in thread BeforeStartup from doorpi.doorpi: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2017-12-03 12:08:29,476 [ERROR] [doorpi.main] Exception NameError: database is locked Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 152, in main_as_application try: doorpi.DoorPi(parsed_arguments).run() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 235, in run self.event_handler.fire_event_synchron('OnStartup', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked 2017-12-03 12:08:29,479 [DEBUG] [doorpi.doorpi] destroy doorpi 2017-12-03 12:08:29,480 [DEBUG] [doorpi.doorpi] Threads before starting shutdown: [<_MainThread(MainThread, started 1995911168)>, ] 2017-12-03 12:08:29,480 [TRACE] [doorpi.action.handler] fire Event BeforeShutdown from doorpi.doorpi asyncron Traceback (most recent call last): File "/usr/local/bin/doorpi_cli", line 11, in Exception in thread BeforeShutdown from doorpi.doorpi: Traceback (most recent call last): File "/usr/lib/python2.7/threading.py", line 810, in __bootstrap_inner self.run() File "/usr/lib/python2.7/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) OperationalError: database is locked sys.exit(entry_point()) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 169, in entry_point raise SystemExit(main_as_application(sys.argv)) File "/usr/local/lib/python2.7/dist-packages/doorpi/main.py", line 155, in main_as_application finally: doorpi.DoorPi().destroy() File "/usr/local/lib/python2.7/dist-packages/doorpi/doorpi.py", line 205, in destroy self.event_handler.fire_event_synchron('OnShutdown', __name__) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 251, in fire_event_synchron if not silent: self.db.insert_event_log(event_fire_id, event_source, event_name, start_time, kwargs) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 135, in insert_event_log self.execute_sql(sql_statement) File "/usr/local/lib/python2.7/dist-packages/doorpi/action/handler.py", line 121, in execute_sql return self._db.execute(sql) sqlite3.OperationalError: database is locked